builder: larch_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-053 starttime: 1464731912.91 results: success (0) buildid: 20160531123017 builduid: 42c1045af760481394f517dee40f15b0 revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.906716) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.907370) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.907722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.927913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.928192) ========= 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=920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-31 14:58:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.3M=0s 2016-05-31 14:58:33 (67.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136211 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.080494) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.080855) ========= 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=920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014518 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.136830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.137140) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 23a96762bc11fdbd26f032d3fcbcbf7417797acb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 23a96762bc11fdbd26f032d3fcbcbf7417797acb --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=920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-31 14:58:33,420 truncating revision to first 12 chars 2016-05-31 14:58:33,420 Setting DEBUG logging. 2016-05-31 14:58:33,420 attempt 1/10 2016-05-31 14:58:33,420 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/23a96762bc11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 14:58:33,780 unpacking tar archive at: larch-23a96762bc11/testing/mozharness/ program finished with exit code 0 elapsedTime=0.688401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.841739) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.842583) ========= script_repo_revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.843040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.843329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.853398) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-31 14:58:33.854086) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:58:34 INFO - MultiFileLogger online at 20160531 14:58:34 in /builds/slave/test 14:58:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 14:58:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:58:34 INFO - {'append_to_log': False, 14:58:34 INFO - 'base_work_dir': '/builds/slave/test', 14:58:34 INFO - 'blob_upload_branch': 'Larch', 14:58:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:58:34 INFO - 'branch': 'Larch', 14:58:34 INFO - 'buildbot_json_path': 'buildprops.json', 14:58:34 INFO - 'config_files': ('talos/linux_config.py',), 14:58:34 INFO - 'default_actions': ('clobber', 14:58:34 INFO - 'read-buildbot-config', 14:58:34 INFO - 'download-and-extract', 14:58:34 INFO - 'populate-webroot', 14:58:34 INFO - 'create-virtualenv', 14:58:34 INFO - 'install', 14:58:34 INFO - 'run-tests'), 14:58:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:58:34 INFO - 'download_minidump_stackwalk': True, 14:58:34 INFO - 'download_symbols': 'ondemand', 14:58:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:58:34 INFO - 'tooltool.py': '/tools/tooltool.py', 14:58:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:58:34 INFO - '/tools/misc-python/virtualenv.py')}, 14:58:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:58:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:58:34 INFO - 'installer_path': 'installer.exe', 14:58:34 INFO - 'log_level': 'info', 14:58:34 INFO - 'log_name': 'talos', 14:58:34 INFO - 'log_to_console': True, 14:58:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:58:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:58:34 INFO - 'opt_config_files': (), 14:58:34 INFO - 'pip_index': False, 14:58:34 INFO - 'sps_profile': False, 14:58:34 INFO - 'sps_profile_interval': 0, 14:58:34 INFO - 'suite': 'dromaeojs-e10s', 14:58:34 INFO - 'system_bits': '32', 14:58:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:58:34 INFO - 'title': 'talos-linux64-ix-053', 14:58:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:58:34 INFO - 'use_talos_json': True, 14:58:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:58:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:58:34 INFO - 'work_dir': 'build'} 14:58:34 INFO - ##### 14:58:34 INFO - ##### Running clobber step. 14:58:34 INFO - ##### 14:58:34 INFO - Running pre-action listener: _resource_record_pre_action 14:58:34 INFO - Running main action method: clobber 14:58:34 INFO - rmtree: /builds/slave/test/build 14:58:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:58:35 INFO - Running post-action listener: _resource_record_post_action 14:58:35 INFO - ##### 14:58:35 INFO - ##### Finished clobber step (success) 14:58:35 INFO - ##### 14:58:35 INFO - ##### 14:58:35 INFO - ##### Running read-buildbot-config step. 14:58:35 INFO - ##### 14:58:35 INFO - Running pre-action listener: _resource_record_pre_action 14:58:35 INFO - Running main action method: read_buildbot_config 14:58:35 INFO - Using buildbot properties: 14:58:35 INFO - { 14:58:35 INFO - "project": "", 14:58:35 INFO - "product": "firefox", 14:58:35 INFO - "script_repo_revision": "production", 14:58:35 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 14:58:35 INFO - "repository": "", 14:58:35 INFO - "buildername": "Ubuntu HW 12.04 x64 larch pgo talos dromaeojs-e10s", 14:58:35 INFO - "buildid": "20160531123017", 14:58:35 INFO - "pgo_build": "True", 14:58:35 INFO - "basedir": "/builds/slave/test", 14:58:35 INFO - "buildnumber": 2, 14:58:35 INFO - "slavename": "talos-linux64-ix-053", 14:58:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:58:35 INFO - "platform": "ubuntu64_hw", 14:58:35 INFO - "scheduler": "tests-larch-linux64-pgo-talos", 14:58:35 INFO - "branch": "larch", 14:58:35 INFO - "repo_path": "projects/larch", 14:58:35 INFO - "revision": "23a96762bc11fdbd26f032d3fcbcbf7417797acb", 14:58:35 INFO - "stage_platform": "linux64-pgo", 14:58:35 INFO - "builduid": "42c1045af760481394f517dee40f15b0", 14:58:35 INFO - "slavebuilddir": "test" 14:58:35 INFO - } 14:58:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:58:35 INFO - Running post-action listener: _resource_record_post_action 14:58:35 INFO - ##### 14:58:35 INFO - ##### Finished read-buildbot-config step (success) 14:58:35 INFO - ##### 14:58:35 INFO - ##### 14:58:35 INFO - ##### Running download-and-extract step. 14:58:35 INFO - ##### 14:58:35 INFO - Running pre-action listener: _resource_record_pre_action 14:58:35 INFO - Running main action method: download_and_extract 14:58:35 INFO - mkdir: /builds/slave/test/build/tests 14:58:35 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')]} 14:58:35 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:38 INFO - Downloaded 1361 bytes. 14:58:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:58:38 INFO - Using the following test package requirements: 14:58:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:58:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:58:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:58:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'jsshell-linux-x86_64.zip'], 14:58:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:58:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:58:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:58:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:58:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:58:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:58:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:58:38 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')]} 14:58:38 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:58:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:42 INFO - Downloaded 24412845 bytes. 14:58:42 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'] 14:58:42 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 14:58:43 INFO - Return code: 0 14:58:43 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 14:58:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:43 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:58:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:45 INFO - Downloaded 24412845 bytes. 14:58:45 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'] 14:58:45 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 14:58:47 INFO - Return code: 0 14:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:47 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:58:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:58:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:58:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:48 INFO - Downloaded 10834063 bytes. 14:58:48 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'] 14:58:48 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 14:58:48 INFO - Return code: 0 14:58:48 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')]} 14:58:48 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:58:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/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 14:58:50 INFO - Downloaded 59070319 bytes. 14:58:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:50 INFO - mkdir: /builds/slave/test/properties 14:58:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:58:50 INFO - Writing to file /builds/slave/test/properties/build_url 14:58:50 INFO - Contents: 14:58:50 INFO - build_url:https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:51 INFO - Running post-action listener: _resource_record_post_action 14:58:51 INFO - Running post-action listener: set_extra_try_arguments 14:58:51 INFO - ##### 14:58:51 INFO - ##### Finished download-and-extract step (success) 14:58:51 INFO - ##### 14:58:51 INFO - ##### 14:58:51 INFO - ##### Running populate-webroot step. 14:58:51 INFO - ##### 14:58:51 INFO - Running pre-action listener: _resource_record_pre_action 14:58:51 INFO - Running main action method: populate_webroot 14:58:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:58:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:58:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:58:51 INFO - '--tppagecycles', 14:58:51 INFO - '7'], 14:58:51 INFO - 'tests': ['tsvgm']}}, 14:58:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:58:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:58:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:58:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:58:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:58:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:58:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['damp', 'tps']}, 14:58:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'tests': ['damp', 'tps']}, 14:58:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['dromaeo_dom']}, 14:58:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:58:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['a11yr', 14:58:51 INFO - 'ts_paint', 14:58:51 INFO - 'tpaint', 14:58:51 INFO - 'sessionrestore', 14:58:51 INFO - 'sessionrestore_no_auto_restore', 14:58:51 INFO - 'tabpaint']}, 14:58:51 INFO - 'other-e10s': {'tests': ['a11yr', 14:58:51 INFO - 'ts_paint', 14:58:51 INFO - 'tpaint', 14:58:51 INFO - 'sessionrestore', 14:58:51 INFO - 'sessionrestore_no_auto_restore', 14:58:51 INFO - 'tabpaint']}, 14:58:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:58:51 INFO - 'ts_paint', 14:58:51 INFO - 'tpaint', 14:58:51 INFO - 'sessionrestore', 14:58:51 INFO - 'sessionrestore_no_auto_restore', 14:58:51 INFO - 'tabpaint']}, 14:58:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:58:51 INFO - 'ts_paint', 14:58:51 INFO - 'tpaint', 14:58:51 INFO - 'sessionrestore', 14:58:51 INFO - 'sessionrestore_no_auto_restore', 14:58:51 INFO - 'tabpaint']}, 14:58:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['a11yr', 14:58:51 INFO - 'ts_paint', 14:58:51 INFO - 'tpaint', 14:58:51 INFO - 'sessionrestore', 14:58:51 INFO - 'sessionrestore_no_auto_restore', 14:58:51 INFO - 'tabpaint']}, 14:58:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['a11yr', 14:58:51 INFO - 'ts_paint', 14:58:51 INFO - 'tpaint', 14:58:51 INFO - 'sessionrestore', 14:58:51 INFO - 'sessionrestore_no_auto_restore', 14:58:51 INFO - 'tabpaint']}, 14:58:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['tsvgx', 14:58:51 INFO - 'tsvgr_opacity', 14:58:51 INFO - 'tart', 14:58:51 INFO - 'tscrollx', 14:58:51 INFO - 'cart']}, 14:58:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:58:51 INFO - 'tsvgr_opacity', 14:58:51 INFO - 'tart', 14:58:51 INFO - 'tscrollx', 14:58:51 INFO - 'cart']}, 14:58:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'talos_options': ['--disable-e10s'], 14:58:51 INFO - 'tests': ['tp5o']}, 14:58:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'tests': ['tp5o']}, 14:58:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'talos_options': ['--disable-e10s', 14:58:51 INFO - '--xperf_path', 14:58:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:58:51 INFO - 'tests': ['tp5n']}, 14:58:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:51 INFO - 'talos_options': ['--xperf_path', 14:58:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:58:51 INFO - 'tests': ['tp5n']}}, 14:58:51 INFO - 'talos.zip': {'path': '', 14:58:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:58:51 INFO - Running post-action listener: _resource_record_post_action 14:58:51 INFO - ##### 14:58:51 INFO - ##### Finished populate-webroot step (success) 14:58:51 INFO - ##### 14:58:51 INFO - ##### 14:58:51 INFO - ##### Running create-virtualenv step. 14:58:51 INFO - ##### 14:58:51 INFO - Running pre-action listener: _resource_record_pre_action 14:58:51 INFO - Running main action method: create_virtualenv 14:58:51 INFO - Creating virtualenv /builds/slave/test/build/venv 14:58:51 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 14:58:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:58:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:58:51 INFO - Using real prefix '/usr' 14:58:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:58:51 INFO - Installing distribute.............................................................................................................................................................................................done. 14:58:53 INFO - Installing pip.................done. 14:58:53 INFO - Return code: 0 14:58:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:58:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:58:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:58:53 INFO - Using env: {'DISPLAY': ':0', 14:58:53 INFO - 'HOME': '/home/cltbld', 14:58:53 INFO - 'LANG': 'en_US.UTF-8', 14:58:53 INFO - 'LANGUAGE': 'en_US:en', 14:58:53 INFO - 'LOGNAME': 'cltbld', 14:58:53 INFO - 'MAIL': '/var/mail/cltbld', 14:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:53 INFO - 'MOZ_NO_REMOTE': '1', 14:58:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:53 INFO - 'NO_EM_RESTART': '1', 14:58:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:53 INFO - 'PWD': '/builds/slave/test', 14:58:53 INFO - 'SHELL': '/bin/bash', 14:58:53 INFO - 'SHLVL': '1', 14:58:53 INFO - 'TERM': 'linux', 14:58:53 INFO - 'TMOUT': '86400', 14:58:53 INFO - 'USER': 'cltbld', 14:58:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:53 INFO - '_': '/tools/buildbot/bin/python'} 14:58:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:53 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 14:58:53 INFO - Cleaning up... 14:58:53 INFO - Return code: 0 14:58:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:58:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:58:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:58:53 INFO - Using env: {'DISPLAY': ':0', 14:58:53 INFO - 'HOME': '/home/cltbld', 14:58:53 INFO - 'LANG': 'en_US.UTF-8', 14:58:53 INFO - 'LANGUAGE': 'en_US:en', 14:58:53 INFO - 'LOGNAME': 'cltbld', 14:58:53 INFO - 'MAIL': '/var/mail/cltbld', 14:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:53 INFO - 'MOZ_NO_REMOTE': '1', 14:58:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:53 INFO - 'NO_EM_RESTART': '1', 14:58:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:53 INFO - 'PWD': '/builds/slave/test', 14:58:53 INFO - 'SHELL': '/bin/bash', 14:58:53 INFO - 'SHLVL': '1', 14:58:53 INFO - 'TERM': 'linux', 14:58:53 INFO - 'TMOUT': '86400', 14:58:53 INFO - 'USER': 'cltbld', 14:58:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:53 INFO - '_': '/tools/buildbot/bin/python'} 14:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:54 INFO - Downloading/unpacking psutil>=3.1.1 14:58: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 14:58: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 14:58: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 14:58: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 14:58: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 14:58: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 14:58:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:58:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:58:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:58:56 INFO - Installing collected packages: psutil 14:58:56 INFO - Running setup.py install for psutil 14:58:56 INFO - building 'psutil._psutil_linux' extension 14:58:56 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 14:58:56 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 14:58:56 INFO - building 'psutil._psutil_posix' extension 14:58:56 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 14:58:56 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 14:58:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:58:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:58:56 INFO - Successfully installed psutil 14:58:56 INFO - Cleaning up... 14:58:56 INFO - Return code: 0 14:58:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:58: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')]} 14:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:58: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:58: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 mozsystemmonitor==0.3 14:58:56 INFO - Using env: {'DISPLAY': ':0', 14:58:56 INFO - 'HOME': '/home/cltbld', 14:58:56 INFO - 'LANG': 'en_US.UTF-8', 14:58:56 INFO - 'LANGUAGE': 'en_US:en', 14:58:56 INFO - 'LOGNAME': 'cltbld', 14:58:56 INFO - 'MAIL': '/var/mail/cltbld', 14:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:56 INFO - 'MOZ_NO_REMOTE': '1', 14:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:56 INFO - 'NO_EM_RESTART': '1', 14:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:56 INFO - 'PWD': '/builds/slave/test', 14:58:56 INFO - 'SHELL': '/bin/bash', 14:58:56 INFO - 'SHLVL': '1', 14:58:56 INFO - 'TERM': 'linux', 14:58:56 INFO - 'TMOUT': '86400', 14:58:56 INFO - 'USER': 'cltbld', 14:58:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:56 INFO - '_': '/tools/buildbot/bin/python'} 14:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:58: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 14:58: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 14:58: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 14:58: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 14:58: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 14:58: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 14:58:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:58:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:58:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:58:58 INFO - Installing collected packages: mozsystemmonitor 14:58:58 INFO - Running setup.py install for mozsystemmonitor 14:58:58 INFO - Successfully installed mozsystemmonitor 14:58:58 INFO - Cleaning up... 14:58:58 INFO - Return code: 0 14:58:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:58:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:58 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 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:58:58 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 14:58:58 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 14:58:58 INFO - Using env: {'DISPLAY': ':0', 14:58:58 INFO - 'HOME': '/home/cltbld', 14:58:58 INFO - 'LANG': 'en_US.UTF-8', 14:58:58 INFO - 'LANGUAGE': 'en_US:en', 14:58:58 INFO - 'LOGNAME': 'cltbld', 14:58:58 INFO - 'MAIL': '/var/mail/cltbld', 14:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:58 INFO - 'MOZ_NO_REMOTE': '1', 14:58:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:58 INFO - 'NO_EM_RESTART': '1', 14:58:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:58 INFO - 'PWD': '/builds/slave/test', 14:58:58 INFO - 'SHELL': '/bin/bash', 14:58:58 INFO - 'SHLVL': '1', 14:58:58 INFO - 'TERM': 'linux', 14:58:58 INFO - 'TMOUT': '86400', 14:58:58 INFO - 'USER': 'cltbld', 14:58:58 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:58 INFO - '_': '/tools/buildbot/bin/python'} 14:58:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:59 INFO - Downloading/unpacking blobuploader==1.2.4 14:58:59 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 14:58:59 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 14:58:59 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 14:58:59 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 14:58:59 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 14:58:59 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 14:59:01 INFO - Downloading blobuploader-1.2.4.tar.gz 14:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:59:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:59:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 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 14:59:01 INFO - Downloading docopt-0.6.1.tar.gz 14:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:59:01 INFO - Installing collected packages: blobuploader, requests, docopt 14:59:01 INFO - Running setup.py install for blobuploader 14:59:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:59:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:59:01 INFO - Running setup.py install for requests 14:59:02 INFO - Running setup.py install for docopt 14:59:02 INFO - Successfully installed blobuploader requests docopt 14:59:02 INFO - Cleaning up... 14:59:02 INFO - Return code: 0 14:59:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:02 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 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:59:02 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 14:59:02 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 14:59:02 INFO - Using env: {'DISPLAY': ':0', 14:59:02 INFO - 'HOME': '/home/cltbld', 14:59:02 INFO - 'LANG': 'en_US.UTF-8', 14:59:02 INFO - 'LANGUAGE': 'en_US:en', 14:59:02 INFO - 'LOGNAME': 'cltbld', 14:59:02 INFO - 'MAIL': '/var/mail/cltbld', 14:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:02 INFO - 'MOZ_NO_REMOTE': '1', 14:59:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:02 INFO - 'NO_EM_RESTART': '1', 14:59:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:02 INFO - 'PWD': '/builds/slave/test', 14:59:02 INFO - 'SHELL': '/bin/bash', 14:59:02 INFO - 'SHLVL': '1', 14:59:02 INFO - 'TERM': 'linux', 14:59:02 INFO - 'TMOUT': '86400', 14:59:02 INFO - 'USER': 'cltbld', 14:59:02 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:02 INFO - '_': '/tools/buildbot/bin/python'} 14:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:59:02 INFO - Running setup.py (path:/tmp/pip-QLk556-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:59:02 INFO - Running setup.py (path:/tmp/pip-sqfMZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:59:02 INFO - Running setup.py (path:/tmp/pip-qrrOfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:59:02 INFO - Running setup.py (path:/tmp/pip-nAFffJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:59:02 INFO - Running setup.py (path:/tmp/pip-Ai3OyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:59:03 INFO - Running setup.py (path:/tmp/pip-ENSwG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:59:03 INFO - Running setup.py (path:/tmp/pip-HHiyw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:59:03 INFO - Running setup.py (path:/tmp/pip-hUyGuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:59:03 INFO - Running setup.py (path:/tmp/pip-zBoWoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:59:03 INFO - Running setup.py (path:/tmp/pip-XT_Zun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:59:03 INFO - Running setup.py (path:/tmp/pip-bkpjQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:59:03 INFO - Running setup.py (path:/tmp/pip-r5DPAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:59:03 INFO - Running setup.py (path:/tmp/pip-IXy8ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:59:03 INFO - Running setup.py (path:/tmp/pip-yhBIGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:59:03 INFO - Running setup.py (path:/tmp/pip-0UDn8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:59:03 INFO - Running setup.py (path:/tmp/pip-geD54b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:59:03 INFO - Running setup.py (path:/tmp/pip-3HR8Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:59:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:59:03 INFO - Running setup.py install for manifestparser 14:59:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:59:03 INFO - Running setup.py install for mozcrash 14:59:04 INFO - Running setup.py install for mozdebug 14:59:04 INFO - Running setup.py install for mozdevice 14:59:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Running setup.py install for mozfile 14:59:04 INFO - Running setup.py install for mozhttpd 14:59:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Running setup.py install for mozinfo 14:59:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Running setup.py install for mozInstall 14:59:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Running setup.py install for mozleak 14:59:04 INFO - Running setup.py install for mozlog 14:59:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:59:04 INFO - Running setup.py install for moznetwork 14:59:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:59:05 INFO - Running setup.py install for mozprocess 14:59:05 INFO - Running setup.py install for mozprofile 14:59:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:59:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:59:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:59:05 INFO - Running setup.py install for mozrunner 14:59:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:59:05 INFO - Running setup.py install for mozscreenshot 14:59:05 INFO - Running setup.py install for moztest 14:59:05 INFO - Running setup.py install for mozversion 14:59:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:59:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:59:05 INFO - Cleaning up... 14:59:05 INFO - Return code: 0 14:59:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:59: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')]} 14:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59: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')]} 14:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:05 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 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:59:05 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 14:59:05 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 14:59:05 INFO - Using env: {'DISPLAY': ':0', 14:59:05 INFO - 'HOME': '/home/cltbld', 14:59:05 INFO - 'LANG': 'en_US.UTF-8', 14:59:05 INFO - 'LANGUAGE': 'en_US:en', 14:59:05 INFO - 'LOGNAME': 'cltbld', 14:59:05 INFO - 'MAIL': '/var/mail/cltbld', 14:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:05 INFO - 'MOZ_NO_REMOTE': '1', 14:59:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:05 INFO - 'NO_EM_RESTART': '1', 14:59:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:05 INFO - 'PWD': '/builds/slave/test', 14:59:05 INFO - 'SHELL': '/bin/bash', 14:59:05 INFO - 'SHLVL': '1', 14:59:05 INFO - 'TERM': 'linux', 14:59:05 INFO - 'TMOUT': '86400', 14:59:05 INFO - 'USER': 'cltbld', 14:59:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:05 INFO - '_': '/tools/buildbot/bin/python'} 14:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:59:05 INFO - Running setup.py (path:/tmp/pip-db2H1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:59:05 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)) 14:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:59:05 INFO - Running setup.py (path:/tmp/pip-e30rsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:59:06 INFO - Running setup.py (path:/tmp/pip-7psBh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:59:06 INFO - Running setup.py (path:/tmp/pip-kZ0sGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:59:06 INFO - Running setup.py (path:/tmp/pip-bEMb0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:59:06 INFO - Running setup.py (path:/tmp/pip-s1cOn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:59:06 INFO - Running setup.py (path:/tmp/pip-NJBaxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:59:06 INFO - Running setup.py (path:/tmp/pip-bIfTOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:59:06 INFO - Running setup.py (path:/tmp/pip-4Ot4mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:59:06 INFO - Running setup.py (path:/tmp/pip-hzgvaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:59:06 INFO - Running setup.py (path:/tmp/pip-Tkf98E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:59:06 INFO - Running setup.py (path:/tmp/pip-L2EK27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:59:06 INFO - Running setup.py (path:/tmp/pip-JX3uZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:59:06 INFO - Running setup.py (path:/tmp/pip-4F_7zY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:59:06 INFO - Running setup.py (path:/tmp/pip-1Gyate-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:59:06 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)) 14:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:59:06 INFO - Running setup.py (path:/tmp/pip-is2cd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:59:07 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)) 14:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:59:07 INFO - Running setup.py (path:/tmp/pip-QesIzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:59:07 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)) 14:59:07 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)) 14:59:07 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)) 14:59:07 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)) 14:59:07 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)) 14:59:07 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)) 14:59:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59:09 INFO - Downloading blessings-1.6.tar.gz 14:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:59:09 INFO - Installing collected packages: blessings 14:59:09 INFO - Running setup.py install for blessings 14:59:09 INFO - Successfully installed blessings 14:59:09 INFO - Cleaning up... 14:59:09 INFO - Return code: 0 14:59:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:59:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:59:09 INFO - Reading from file tmpfile_stdout 14:59:09 INFO - Current package versions: 14:59:09 INFO - argparse == 1.2.1 14:59:09 INFO - blessings == 1.6 14:59:09 INFO - blobuploader == 1.2.4 14:59:09 INFO - docopt == 0.6.1 14:59:09 INFO - manifestparser == 1.1 14:59:09 INFO - mozInstall == 1.12 14:59:09 INFO - mozcrash == 0.17 14:59:09 INFO - mozdebug == 0.1 14:59:09 INFO - mozdevice == 0.48 14:59:09 INFO - mozfile == 1.2 14:59:09 INFO - mozhttpd == 0.7 14:59:09 INFO - mozinfo == 0.9 14:59:09 INFO - mozleak == 0.1 14:59:09 INFO - mozlog == 3.2 14:59:09 INFO - moznetwork == 0.27 14:59:09 INFO - mozprocess == 0.22 14:59:09 INFO - mozprofile == 0.28 14:59:09 INFO - mozrunner == 6.11 14:59:09 INFO - mozscreenshot == 0.1 14:59:09 INFO - mozsystemmonitor == 0.3 14:59:09 INFO - moztest == 0.7 14:59:09 INFO - mozversion == 1.4 14:59:09 INFO - psutil == 3.1.1 14:59:09 INFO - requests == 1.2.3 14:59:09 INFO - wsgiref == 0.1.2 14:59:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:59: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')]} 14:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59: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')]} 14:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:09 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 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:59:09 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 14:59:09 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 14:59:09 INFO - Using env: {'DISPLAY': ':0', 14:59:09 INFO - 'HOME': '/home/cltbld', 14:59:09 INFO - 'LANG': 'en_US.UTF-8', 14:59:09 INFO - 'LANGUAGE': 'en_US:en', 14:59:09 INFO - 'LOGNAME': 'cltbld', 14:59:09 INFO - 'MAIL': '/var/mail/cltbld', 14:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:09 INFO - 'MOZ_NO_REMOTE': '1', 14:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:09 INFO - 'NO_EM_RESTART': '1', 14:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:09 INFO - 'PWD': '/builds/slave/test', 14:59:09 INFO - 'SHELL': '/bin/bash', 14:59:09 INFO - 'SHLVL': '1', 14:59:09 INFO - 'TERM': 'linux', 14:59:09 INFO - 'TMOUT': '86400', 14:59:09 INFO - 'USER': 'cltbld', 14:59:09 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:09 INFO - '_': '/tools/buildbot/bin/python'} 14:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 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)) 14:59:09 INFO - Cleaning up... 14:59:09 INFO - Return code: 0 14:59:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:59: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')]} 14:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59: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')]} 14:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:59:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:59:09 INFO - Using env: {'DISPLAY': ':0', 14:59:09 INFO - 'HOME': '/home/cltbld', 14:59:09 INFO - 'LANG': 'en_US.UTF-8', 14:59:09 INFO - 'LANGUAGE': 'en_US:en', 14:59:09 INFO - 'LOGNAME': 'cltbld', 14:59:09 INFO - 'MAIL': '/var/mail/cltbld', 14:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:09 INFO - 'MOZ_NO_REMOTE': '1', 14:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:09 INFO - 'NO_EM_RESTART': '1', 14:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:09 INFO - 'PWD': '/builds/slave/test', 14:59:09 INFO - 'SHELL': '/bin/bash', 14:59:09 INFO - 'SHLVL': '1', 14:59:09 INFO - 'TERM': 'linux', 14:59:09 INFO - 'TMOUT': '86400', 14:59:09 INFO - 'USER': 'cltbld', 14:59:09 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:09 INFO - '_': '/tools/buildbot/bin/python'} 14:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:09 INFO - Downloading/unpacking jsonschema 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:59:11 INFO - Downloading/unpacking functools32 (from jsonschema) 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59:11 INFO - Downloading functools32-3.2.3-2.tar.gz 14:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:59:12 INFO - warning: no files found matching '*.txt' 14:59:12 INFO - no previously-included directories found matching 'build' 14:59:12 INFO - no previously-included directories found matching 'dist' 14:59:12 INFO - no previously-included directories found matching '.git*' 14:59:12 INFO - Installing collected packages: jsonschema, functools32 14:59:12 INFO - Running setup.py install for functools32 14:59:12 INFO - warning: no files found matching '*.txt' 14:59:12 INFO - no previously-included directories found matching 'build' 14:59:12 INFO - no previously-included directories found matching 'dist' 14:59:12 INFO - no previously-included directories found matching '.git*' 14:59:12 INFO - Successfully installed jsonschema functools32 14:59:12 INFO - Cleaning up... 14:59:12 INFO - Return code: 0 14:59:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f57f311bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, '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': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', '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 14:59:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:59:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:59:12 INFO - Using env: {'DISPLAY': ':0', 14:59:12 INFO - 'HOME': '/home/cltbld', 14:59:12 INFO - 'LANG': 'en_US.UTF-8', 14:59:12 INFO - 'LANGUAGE': 'en_US:en', 14:59:12 INFO - 'LOGNAME': 'cltbld', 14:59:12 INFO - 'MAIL': '/var/mail/cltbld', 14:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:12 INFO - 'MOZ_NO_REMOTE': '1', 14:59:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:12 INFO - 'NO_EM_RESTART': '1', 14:59:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:12 INFO - 'PWD': '/builds/slave/test', 14:59:12 INFO - 'SHELL': '/bin/bash', 14:59:12 INFO - 'SHLVL': '1', 14:59:12 INFO - 'TERM': 'linux', 14:59:12 INFO - 'TMOUT': '86400', 14:59:12 INFO - 'USER': 'cltbld', 14:59:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:12 INFO - '_': '/tools/buildbot/bin/python'} 14:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:12 INFO - Downloading/unpacking flake8 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59: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 14:59:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:59:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:59:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:59:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:59:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:59:15 INFO - no previously-included directories found matching 'docs/_build' 14:59:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:59:15 INFO - Running setup.py install for pep8 14:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:59:15 INFO - no previously-included directories found matching 'docs/_build' 14:59:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:59:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:59:15 INFO - Cleaning up... 14:59:15 INFO - Return code: 0 14:59:15 INFO - Running post-action listener: _resource_record_post_action 14:59:15 INFO - Running post-action listener: _start_resource_monitoring 14:59:15 INFO - Starting resource monitoring. 14:59:15 INFO - ##### 14:59:15 INFO - ##### Finished create-virtualenv step (success) 14:59:15 INFO - ##### 14:59:15 INFO - ##### 14:59:15 INFO - ##### Running install step. 14:59:15 INFO - ##### 14:59:15 INFO - Running pre-action listener: _resource_record_pre_action 14:59:15 INFO - Running main action method: install 14:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:59:15 INFO - Reading from file tmpfile_stdout 14:59:15 INFO - Detecting whether we're running mozinstall >=1.0... 14:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:59:15 INFO - Reading from file tmpfile_stdout 14:59:15 INFO - Output received: 14:59:15 INFO - Usage: mozinstall [options] installer 14:59:15 INFO - Options: 14:59:15 INFO - -h, --help show this help message and exit 14:59:15 INFO - -d DEST, --destination=DEST 14:59:15 INFO - Directory to install application into. [default: 14:59:15 INFO - "/builds/slave/test"] 14:59:15 INFO - --app=APP Application being installed. [default: firefox] 14:59:15 INFO - mkdir: /builds/slave/test/build/application 14:59:15 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'] 14:59:15 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 14:59:32 INFO - Reading from file tmpfile_stdout 14:59:32 INFO - Output received: 14:59:32 INFO - /builds/slave/test/build/application/firefox/firefox 14:59:32 INFO - Running post-action listener: _resource_record_post_action 14:59:32 INFO - ##### 14:59:32 INFO - ##### Finished install step (success) 14:59:32 INFO - ##### 14:59:32 INFO - ##### 14:59:32 INFO - ##### Running run-tests step. 14:59:32 INFO - ##### 14:59:32 INFO - Running pre-action listener: _resource_record_pre_action 14:59:32 INFO - Running main action method: run_tests 14:59:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:59:32 INFO - Python 2.7.3 14:59:32 INFO - Return code: 0 14:59:32 INFO - grabbing minidump binary from tooltool 14:59:32 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')]} 14:59:32 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 0x295c9c0>, '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 0x295d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295dd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:59:32 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 14:59:32 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 14:59:32 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 14:59:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:59:32 INFO - Return code: 0 14:59:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:59:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:59:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:59:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:59:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:59:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:59:33 INFO - Return code: 0 14:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:59:33 INFO - Using env: {'DISPLAY': ':0', 14:59:33 INFO - 'HOME': '/home/cltbld', 14:59:33 INFO - 'LANG': 'en_US.UTF-8', 14:59:33 INFO - 'LANGUAGE': 'en_US:en', 14:59:33 INFO - 'LOGNAME': 'cltbld', 14:59:33 INFO - 'MAIL': '/var/mail/cltbld', 14:59:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:59:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:33 INFO - 'MOZ_NO_REMOTE': '1', 14:59:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:33 INFO - 'NO_EM_RESTART': '1', 14:59:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:33 INFO - 'PWD': '/builds/slave/test', 14:59:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:59:33 INFO - 'SHELL': '/bin/bash', 14:59:33 INFO - 'SHLVL': '1', 14:59:33 INFO - 'TERM': 'linux', 14:59:33 INFO - 'TMOUT': '86400', 14:59:33 INFO - 'USER': 'cltbld', 14:59:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039', 14:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:33 INFO - '_': '/tools/buildbot/bin/python'} 14:59:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:59:33 INFO - mozversion application_buildid: 20160531123017 14:59:33 INFO - mozversion application_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 14:59:33 INFO - mozversion application_display_name: Nightly 14:59:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:59:33 INFO - mozversion application_name: Firefox 14:59:33 INFO - mozversion application_remotingname: firefox 14:59:33 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:59:33 INFO - mozversion application_vendor: Mozilla 14:59:33 INFO - mozversion application_version: 49.0a1 14:59:33 INFO - mozversion platform_buildid: 20160531123017 14:59:33 INFO - mozversion platform_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 14:59:33 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:59:33 INFO - mozversion platform_version: 49.0a1 14:59:33 INFO - using testdate: 1464731973 14:59:33 INFO - actual date: 1464731973 14:59:33 INFO - starting webserver on 'localhost:50468' 14:59:33 INFO - SUITE-START | Running 2 tests 14:59:33 INFO - TEST-START | dromaeo_css 14:59:33 INFO - operating with platform_type : linux_ 14:59:33 INFO - Initialising browser for dromaeo_css test... 14:59:33 INFO - TEST-INFO | started process 15692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1yZSIq/profile http://localhost:50468/getInfo.html) 14:59:38 INFO - PROCESS | 15692 | __metrics Screen width/height:1600/1200 14:59:38 INFO - PROCESS | 15692 | colorDepth:24 14:59:38 INFO - PROCESS | 15692 | Browser inner width/height: 1024/697 14:59:38 INFO - PROCESS | 15692 | __metrics 14:59:39 ERROR - PROCESS | 15692 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 14:59:40 INFO - TEST-INFO | 15692: exit 0 14:59:40 INFO - Browser initialized. 14:59:40 INFO - Running cycle 1/1 for dromaeo_css test... 14:59:40 INFO - TEST-INFO | started process 15811 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1yZSIq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:59:52 INFO - PROCESS | 15811 | RSS: Main: 168009728 14:59:52 INFO - PROCESS | 15811 | 15:00:41 INFO - PROCESS | 15811 | 1464732041578 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:02:48 INFO - PROCESS | 15811 | Cycle 1(1): loaded http://localhost:50468/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50468/tests/dromaeo/cssquery-ext.html) 15:02:49 INFO - PROCESS | 15811 | RSS: Main: 170717184 15:02:49 INFO - PROCESS | 15811 | 15:05:51 INFO - PROCESS | 15811 | Cycle 1(1): loaded http://localhost:50468/tests/dromaeo/cssquery-ext.html (next: http://localhost:50468/tests/dromaeo/cssquery-jquery.html) 15:05:52 INFO - PROCESS | 15811 | RSS: Main: 169963520 15:05:52 INFO - PROCESS | 15811 | 15:08:54 INFO - PROCESS | 15811 | Cycle 1(1): loaded http://localhost:50468/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50468/tests/dromaeo/cssquery-mootools.html) 15:08:54 INFO - PROCESS | 15811 | RSS: Main: 171515904 15:08:54 INFO - PROCESS | 15811 | 15:11:56 INFO - PROCESS | 15811 | Cycle 1(1): loaded http://localhost:50468/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50468/tests/dromaeo/cssquery-prototype.html) 15:11:57 INFO - PROCESS | 15811 | RSS: Main: 168853504 15:11:57 INFO - PROCESS | 15811 | 15:14:59 INFO - PROCESS | 15811 | Cycle 1(1): loaded http://localhost:50468/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50468/tests/dromaeo/cssquery-yui.html) 15:15:00 INFO - PROCESS | 15811 | RSS: Main: 170598400 15:15:00 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | Cycle 1(1): loaded http://localhost:50468/tests/dromaeo/cssquery-yui.html (next: http://localhost:50468/tests/dromaeo/cssquery-dojo.html) 15:18:02 INFO - PROCESS | 15811 | RSS: Main: 172048384 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | __start_tp_report 15:18:02 INFO - PROCESS | 15811 | _x_x_mozilla_page_load 15:18:02 INFO - PROCESS | 15811 | _x_x_mozilla_page_load_details 15:18:02 INFO - PROCESS | 15811 | |i|pagename|runs| 15:18:02 INFO - PROCESS | 15811 | |0;dojo.html;3111.175538482302;3181.411438883808;3195.7123858852706;3197.3445443684045;3202.1194171602806;5483.451654834515;5537;5554.028045092108;5563.582731295149;5580.441955804417;5361.651492652974;5484.067708489556;5499.202615620733;5523.613347065707;5569.081101618228;4950.950490495094;4982.975085124587;5096.974515127419;5137.38351397831;5184.274201611758;716.1913350618117;4857.319975203474;4954.554090131887;5004.62465315101;5074.340335756365;5007.474215207387;5037.874053148682;5057.519535644108;5064.797408103672;5073.923891141636;5051.44434112251;5056.696598204119;5070.847874563769;5072.54347108762;5083.491650834924;4897.3878265216845;4992;5020.347354843883;5020.924686129711;5026.145555255615;5361.571074314045;5405.891882162374;5416.945830541684;5420.105682562373;5433.809816656398;5716.28546431696;5806.854828629276;5874.148248503986;5896.764129434818;5902.557308201902;5924.407559244084;5930.110483427477;5941.584089113764;5959.910601340984;6008.609440386361;5528.9723551381985;5544.528715059225;5553.666779993213;5557.249771280865;5561.388247292794;5867.589268751147;5891.852703682356;5951.404859514014;6075.605085669417;6092.786752463642;5715.342735585377;5738.426157384311;5759.020966435716;5763.423657634203;5765.048766953406;5823.359430462646;5829.183914252008;5891.9705401473575;5910.113482977502;5930.495907847881;5806.21616081832;5837.241158649434;5877.353491115974;5898.321693005273;5914.6746928918465;5808.419158084243;5830.300363956352;5850.736716847752;5856.267966504187;5930.258717660293;4429.490608580055;4441.178381999276;4443.466783985942;4454.710443821141;4485.551444855489;3909.257241124177;3950.9604903951167;3961.6830653547645;3997.720159588801;4020.9396859047138;5627.071533196977;5658.547316214693;5666.008448521492;5683.943160568341;5709.371969083398;5969.552283578749;5977.970110149421;5987.431194036647;5995.100734889714;6008.879822403527;5433.239346491494;5556.888862222733;5573.944260557342;5612.635178713371;5643;5075.111855425286;5101;5104.617153713486;5111.335526381621;5121.4622464641425;6138.631682099089;6144.907826382608;6147.877042459126;6158.507319414436;6161.599496032743;5804.303483581997;5954.999999999914;5982.312034116132;5987.31145918225;6017.939820601738;6181;6181.598196117238;6192.256929168529;6197.566170368031;6201.193844800147;5632.239647647515;5887.116932459994;5905.40945905406;5906.793262235801;5913.172155898264;5897.144913987534;5971.044632858721;5985.25184351956;5985.970070149621;5999.160117583455;5784.913226301524;5817.738201781015;5825.650460972355;5826.271716035496;5832.387599302012;5626.099824028136;5759.280089988752;5830.708464576839;5838.737256823369;5847.298324200953;5579.9163012555655;5641.464060914206;5682.062459694104;5699.373068962494;5782.942170578241;5786.9131963019745;5809.680467574324;5823.359430462561;5837.09525023622;5852.122181672783;5511.283533140827;5665.121906104396;5716.514096301695;5727.083666613488;5752.827415177551 15:18:02 INFO - PROCESS | 15811 | |1;ext.html;596.6986671730775;617.1390909680995;635.1647583427795;637.4295005969656;648.5557393185666;42355.78822105889;43589.782051089736;43648.56351436485;43694.56305436945;47185.52814471854;44977;45877;46039.309410358874;46208.768956155174;46241.76879115608;8690.869636955451;8863.379563430562;8893.421927574687;8909.509976951265;8909.95545022274;8811.911880881205;8825.51459669718;8867.955660221722;8868.778280542974;8905.866412003825;8886.911130888706;8909.55452227386;8967;9007.414518056337;9034.14175653312;8812.691555795549;8845.4250473719;8854.513001784899;8856.955715221447;8868.290536757044;8713.085126061736;8809.163129502687;8867.246284065797;8874.29005679544;8887.911120888837;42470.57529424697;42489.57510424887;42661;42784.14431711348;42955.35566966498;3680.5583330000572;3716.2195938852874;3734.477173195755;3893.610638936112;4084.591540845892;31719.207019824687;32109;32130.518042229385;32190.356192876246;32635.18412039694;4478.8880277992985;4509.503954564995;4532.365468834366;4540.704854184501;4618.953810461886;341604;343025;345510;346164;347477.26261368784;59727;60407.69796150991;60408.093878591855;60445;60466.39533604607;36138.27723445516;36926.261474770356;36984;38467.61532384696;38695;15906;16000.359985600446;16169.22138564757;16181.676366472604;16184.352625894833;11852.70368240801;11857.822132667845;11962.82055769164;11989.88010119905;12009.279443233434;19729.210831566863;19850.90074549618;19918.701219481718;20119.19523219055;20174.798252017586;7151.713931442684;7260.96369518149;7410.481266311414;7436;7459.769138092154;1012.9949350253202;1083.8103331916882;1087.581281206741;1095.2990086344746;1103.5034234594464;32983.46040119886;33161.50257746136;33226;33457.33085338328;33613.663863361544;1111.5442668505873;1161.6224726963771;1162.924409913353;1170.12825445044;1209.2019267283551;16227.83772162287;16837.663246734995;16891.91554042222;16949.91525042367;16989.065601391794;569653;576661;576683;576894;580730;57539.42460575424;58275;58452;58535.707321463124;58947;1337.2110454831695;1353.3301015997145;1363.6454521824396;1364.8498665146826;1364.9044566880423;30273.394532108792;31507.36985260328;31810.522842156923;31974.36051279008;32563.837180813945;50237;51070.23394649009;51122.74438627783;51238.92566446025;51427.74286128546;441196;445902;447343;448124;452563;28218.576721348785;28628.14115576536;28674.569881451378;28895;28947.421051579273;48521.27218091661;49035.264471033675;49733;50190.247146293565;50541;40244.195116096926;41141.177176455705;41612.37581436341;41803.16393672171;43013.1397372057;40622.796886016564;40719.946004050405;41426.171476569696;41584.584154158074;42555.7872210637;35832.64167358293;39813.60186398099;40503.392449112696;41266.1746765057;41545;35593.28813423665;37235.255294894494;37255.25489490249;37357.62642373541;37498;28555.857220713762;28663.283417913884;28729;29060;29131.271718206368 15:18:02 INFO - PROCESS | 15811 | |2;jquery.html;372.57154272586524;375.5080844094237;385.9266739319529;392.289955181122;395.95248570171583;17209.3116275349;18869.150888210028;19528.804711952875;19626.901865490654;19700.704489432654;18533.44399668009;18808;19043;19192.71210931837;19288.421347359603;5579.246801681782;5631.18347839564;5781.450762177607;5811.186433899257;5882.911756323659;5637.661740295575;5691.8577035574235;5704.8859022819515;5732.054211055191;5736.913946290809;5527.226188333617;5658.773649054033;5658.773649054054;5671.914921276184;5716.79991200306;5644.491995720364;5672.12082127271;5681.488666020036;5690.658560486384;5700.37295897451;4922.901541969176;4997.175466048134;5034.697918124888;5073.061483625541;5082.415522214918;22205;22244.443888902744;22449.32652020442;22939.476657567655;22983;2422.8667423291713;2468.7531246875346;2709.552923767576;2721;2734.125079974409;13979.09135906163;14512.1292722437;14551.636209094751;14604.561863144016;14763.33564989578;1913.1773337464886;1955.1885967323685;1968.3602829080462;1971.0637447212603;2015.8084981926693;78274;80049;80051.99999999942;80747;80901.59549202275;9801.509924503745;9846.901530984598;10044.29689921713;10098.798024039625;10249.33379330356;13486.190828550318;13573.253471059186;13591.388387522387;13601.455941762417;13606.795898061537;7652.885206721904;7729.757473377851;7806.960965195138;7849.529028258323;7896.644650990719;7542.321191092828;7560.735374261874;7758.418118641124;7783.104942931522;7802.492837965514;6365.554411191265;6398.200224971879;6406;6422.197225346832;6589.6375699336995;1815.6277963017549;1819.7361382599713;1836.7887692915485;1853.8887666740038;1876.2401227502883;991.8363470027365;1004.3672486333563;1090.0298734126634;1106.5241945963312;1136.93696393872;17595.912020439817;19260.422187334403;19287.8071219286;19462.610747784965;19793.802061979484;1029.9433531155858;1044.4516628769804;1050.3960222871879;1067.1196263082988;1081.4268437728017;10544;11186.272892261977;11231.38227397501;11237.71905702364;11271.492782824793;453346;457985;460102;462770;465075;80549.59725201454;83126;83664.58167709244;84477.15522844816;84618.57690711507;1031.6985645932896;1050.22808235945;1058.2327812335996;1060.1677683018793;1062.718379629399;16003.839961600235;17167.31330746756;17329.653406932044;17369.826301736823;17471.38850140239;45186;45223.547764521936;45373.773131134134;45497.545024549334;45786;59593.404065960516;59688.403115968285;59886.40113598808;60769.392306078145;61145;8257.587120644064;8267.62795674184;8318.833623327379;8339.54132522717;8509.149085091689;9232.307576931891;12406.379681016093;13199.604011879659;13362.532311369445;13798.448062077405;11817;11838.526458941546;12016.579419720269;12057.51769929193;12086.818697719717;11670.591529296435;11748.823767643322;11865.050795936477;12174.452149653463;12435.12954093241;12413.06901982373;12513;12516.624501264976;12620;12778.61664150077;7578.204288549623;7580.9620951894885;8814.338924580554;8878.822423551623;9033.412828166149;11931;12308.138430309793;12338.13633045714;12449;12450.564230251897 15:18:02 INFO - PROCESS | 15811 | |3;mootools.html;1492.7686208637658;1527.8013858198433;1720.707479728446;1827.5431142214447;1861.7393564900917;8205.712800051999;8918;8934.329925255599;9529.380590261637;9545.61817527299;8986.775330616738;9187.310951678634;9228.8615670765;9265;9299.302552308587;2266.7506574276817;2303.8617682938993;2308.1921327535397;2310.7689231076924;2310.8266879984067;2259.9887000564972;2288.8855557222073;2346.765323467648;2347.424880904181;2348.424635964191;2326.5463234669246;2342.121704360865;2346.4720437901506;2388.9761102389016;2391.0435825669697;2245.2029529517113;2245.6743772153027;2252.8085112765434;2256.751757306695;2272.0570963050313;2255.796978271947;2268.5236100418765;2269.069681430622;2271.3640180749417;2280.315905228425;9250.167484926322;9311.348205625609;9315.860262096074;9317.254619630414;9351.532423378803;5224.216367544861;5349.14413693811;5361.249425080453;5385.111456609655;5425.891482170335;3263.5104734289807;3307.090550098718;3462.671046250602;3471.878484253039;3474.7915125092322;1152.1474109159162;1163.3775929877474;1176.1214699045486;1176.1355403778216;1202.6392082375253;12544.184627999242;12662.81005784914;12687.619371418872;12702;12731.872681273162;4709.929351059737;4727.83452579165;4795.784189711471;4833.637477189225;4842.975785121052;10760.892391076146;10818.513166907507;10835.945820270848;10843.945780271048;10851.51168197433;5382.2464854920345;5418.214358917935;5481.862953426196;5502.367227768777;5512.944870551243;4438.134563760036;4737.28940658904;4821.975890120527;4835.323054772335;5070.6957582545165;4087.7956102195367;4176.352665336878;4192.81132349045;4223.767692776865;4242.766647834399;1283.5122653391652;1289.767841788487;1295.6242689619905;1316.1116246533552;1320.3332317179763;919.4069824962861;927.7077720518015;938.5448057692014;951.7335146159087;971.0047201618397;5699;5756.769729210785;5808.099744539603;5953.255843019622;6110.450059494667;439.74714539139484;452.8890421846603;455.5467310026545;465.9138059458943;470.08333749189217;3991.1020020495157;4008.0581063450068;4018.7588744675413;4032.6169013943627;4053.310937140693;10818.24272300947;11595.420228988516;11605.825912611317;11608.30350178992;11616.825747613793;10996.615118470816;11315.434228288552;11415.143864210217;11426.428678566039;11428.257163284527;8384.580770961427;8482.915170848213;8496.872546911802;8531;8571.785705357415;5819.098039803837;5827.125931110368;5849.64902105875;5890.705464726832;5984.910226346696;10094.798104038025;10271.229657775548;10333.173346132142;10348.22388320894;10428.687139385831;10523.36887782395;10615.097716694167;10634.84047739269;10708.464576771286;10708.464576771286;7086.751963681247;7838.490498117604;8046.155153708776;8153.6738530458115;8220.712275070344;8954.776130596787;9012.098790120936;9017;9039.412438191497;9118.088191180828;7997.200279971957;8170.182981701782;8189.590520474072;8278.379121565786;8305.335573154012;7696.961515192388;7891.921080789119;7905.644246009058;7939.722109726132;8029.638666259915;6942.548734332252;6956.79129626112;7012.474064445289;7035.261297563887;7126.714931402686;4981.427135879276;5065.670731402447;5169.172932330809;5202.323697919246;5223.843284701465;4952.4304704959395;4988.750562471934;4993.151164302005;5086.720230387216;5172.069027575073 15:18:02 INFO - PROCESS | 15811 | |4;prototype.html;327.4139290669701;330.7155845972464;330.90403782902956;338.80518701746473;340.68146283225184;5527.203658810888;5633.8309850704445;5661.1508273758955;5684.147377893318;5717.542596592262;5537.307836520435;5542.722863856811;5550.27846379971;5598.5086636446695;5600.803971860985;4127.071408932996;4173.394857745625;4320.935185972213;4325.805338759747;4333.090051089275;4289.091354347317;4313.978430107845;4327.7186982846015;4338.436003319564;4347.13057388522;4312;4312.28847240205;4325.134973005396;4344.565543445646;4369.890752731176;4067.3695577185435;4269.487661480611;4272.273713468718;4290.549492303295;4370.628496577779;3731.8880433587037;3748.4377343398437;3752.981235093807;3753.41822017585;3756.154865155318;5292.676830792302;5296.232046353296;5370.650907690988;5416.943695979286;5434.483724046209;4760.523947605247;4905.5094490550655;4956.851294461172;4969.130402179604;5009.173486374744;5824.388439213864;5830.970845145789;5843.415658434123;5845.5908086433965;5872.647641141545;4199.160167966419;4226.119376877291;4238.7032907696475;4247.660187185018;4262.7655478948645;5911.96037367112;5978.8206353809455;6083.543734219951;6111.419415155553;6111.694415279218;4326.437563116775;4330.631896288788;4351.586599273064;4360.345948107746;4381.211381951216;4416.690831641754;4446.599806017474;4460.241758900995;4464.2633965396;4469.687121901501;4929.359183306147;4996.850094497171;4998.725070121178;5032.47159048302;5062.443131255559;5878.678546621145;5893;5908.320543137508;5917.023691091008;5926.318473375531;1506.3372116268806;1517.1807224098864;1517.4613012380667;1525.3745964154643;1527.2745445913101;962.764122789921;990.4898977026837;991.1921783746368;993.4486360070231;995.3629677006721;5635.492205968387;5654.15187721845;5661.207430959669;5662;5711.857203569944;5965.403459654;5985.904933273319;6013.5189184865985;6046.73018666085;6060.727267272983;5497.670139791625;5662.631928924607;5698.17376480408;5700.686462244534;5700.8859822803315;3213.855376508063;3228.6609905960004;3240.09277402323;3260.7065364117343;3274.8526316315824;6194.628322300677;6224.782132625336;6258.496601359493;6279.215098112736;6328.968355158195;6192.287886893065;6203.4416902479;6229.7508099676415;6246.531510136758;6267.6866156691985;6021.8558473889825;6257.030160325156;6261.874762504815;6286.151369565142;6311.873762524816;6040.1241819934985;6041.063635136561;6054.848628784315;6109.877802443837;6113;5994.70026498682;6003;6034.21555197839;6052.364501727431;6064;5967.880642387215;6242.719077641608;6262.937370626236;6289.40250676185;6302.613425046482;4228.667969589569;4257;4259.148170365877;4278.572142785696;4285.271503844417;5907.527397808251;5960.5231581474245;5965;6029.246344206974;6083.117947897442;5780.364159942319;5783.566232532493;5856.472917437451;5878.706684529397;5921.082232254007;5832.24180856486;5932.436418540232;5972.850678733066;5974.492168165754;5981.222441082806;5170.844874653766;5719.8570035749435;5734.827955161351;5795.072788353843;5840.07996001999;5880.001199388257;5910.7340169693325;5911.083782013795;5920.851978700567;5954.46409823118;5573.191887176417;5743.4543718346695;5762.1849117527845;5763.308402991668;5801.419858014334 15:18:02 INFO - PROCESS | 15811 | |5;yui.html;789.443442373127;804.4610111225478;828.5235989306146;850.8741518498339;860.1269711243085;14839.777403338945;15425.305861236244;15548.922255388736;15636.140012299318;15716.842831571681;14208.502702405418;14348;14349.641258968531;14350.138991660508;14355.569332920002;673.6968364236077;793.1671744668075;812.7683610171102;818.8935438393;820.1019883227856;801.7113839017978;807.0839597057324;808.1877712898558;808.6078252047781;808.8180159464132;861.0528418739399;862.4006315610653;865.3682811547574;868.3183700794877;869.9652013919435;859.8280343931176;864.3733293362325;864.7924498120469;865.3034307382554;866.9956650216772;621.6208113051035;623.18985319085;624.4941597306197;625.7559551774812;626.4768917953514;14606.561803145816;14648.19434931089;14656.487022954252;14709.70580588393;14715.117092974457;14102.294885255696;14411.279436028157;14466.638334041629;14550.417983280657;14602.34289456977;11886.167968242227;11894.940525297317;12035.097367697457;12042.518299268018;12203.328816915066;3778.924421511582;3796.8101594920145;3854.0557563396865;3855.4987851579394;3944.349182384903;60864;61766.38233617696;62269.688651556455;62303.68848155776;62924.37075629231;10876.72808179786;11049.116070714324;11079.390633515073;11113.44432778374;11125;19161.32935347256;19249.13378897953;19297.807021929882;19303.80696193048;19354.612907741765;8277.62750676221;8389.790255243546;8391;8445.662173513114;8518.063013068557;4884.389451318585;4886.291487734258;4888.437829649636;4906.779194936236;4950.381202349707;3473.1664400543686;3528.4530897710897;3542.663446972585;3556.2354093869426;3591;622.8505158761865;639.3829954094228;653.1085068880943;655.7672026430644;656.6651007985866;745.6383653927841;748.6107224243362;752.0486584470649;762.6110683551428;772.1892013385923;9514.33399662031;9604.135627793532;9661.806763864683;9882.505874706236;9954.701358959242;591.6716222496556;624.3194917539813;713.4934196720386;736.0321177651372;774.2760518914832;4431.8670439886855;4493.842715504941;4577;4594.862154135381;4641.698289611232;65999.67000164969;67443.3255667437;68696;69009.65495172492;69919.3008069923;58988.7050564753;59274;60643;61152;62369.37630623727;54783.45216547863;54875.72562137164;55182.72408637931;55525.722371387885;56078;9075.954620226856;9133.132352426508;9267.490288034223;9271.490068046322;9278.257739380968;37437;37938.43092353672;37945.81027094847;38424.88663123555;38860.805695971336;46860.7656961713;49692.503074970235;50886.23670644869;51003;51368;11273.154513411362;11371.658850234506;11471;11508.309501429942;11533.480993355486;12380.133390662568;12556.121071524905;12601.306928119042;12905.967522598357;13400.32998350098;10254.794904102026;11116;11578.189526733047;11880.643580692593;12231.449584768528;11646.94176529112;11797;11878;12021.819672704742;12452.750944981231;11128.332300062022;11231.55073797039;11263.943680281545;11386.146039047271;11401.258918170292;10239.232057595858;10263.435511046964;10326.48367581633;10454.529546170288;10470.581176752845;9745.22038236925;10110.191184705352;10163.44101074448;10180.287379883337;10211.081002709792 15:18:02 INFO - PROCESS | 15811 | __end_tp_report 15:18:02 INFO - PROCESS | 15811 | __start_cc_report 15:18:02 INFO - PROCESS | 15811 | _x_x_mozilla_cycle_collect,364 15:18:02 INFO - PROCESS | 15811 | __end_cc_report 15:18:02 INFO - PROCESS | 15811 | __startTimestamp1464733082725__endTimestamp 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | ------- Summary: start ------- 15:18:02 INFO - PROCESS | 15811 | Number of tests: 6 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | [#0] dojo.html Cycles:165 Average:5460.62 Median:5715.34 stddev:735.73 (12.9%) stddev-sans-first:714.52 15:18:02 INFO - PROCESS | 15811 | Values: 3111.2 3181.4 3195.7 3197.3 3202.1 5483.5 5537.0 5554.0 5563.6 5580.4 5361.7 5484.1 5499.2 5523.6 5569.1 4951.0 4983.0 5097.0 5137.4 5184.3 716.2 4857.3 4954.6 5004.6 5074.3 5007.5 5037.9 5057.5 5064.8 5073.9 5051.4 5056.7 5070.8 5072.5 5083.5 4897.4 4992.0 5020.3 5020.9 5026.1 5361.6 5405.9 5416.9 5420.1 5433.8 5716.3 5806.9 5874.1 5896.8 5902.6 5924.4 5930.1 5941.6 5959.9 6008.6 5529.0 5544.5 5553.7 5557.2 5561.4 5867.6 5891.9 5951.4 6075.6 6092.8 5715.3 5738.4 5759.0 5763.4 5765.0 5823.4 5829.2 5892.0 5910.1 5930.5 5806.2 5837.2 5877.4 5898.3 5914.7 5808.4 5830.3 5850.7 5856.3 5930.3 4429.5 4441.2 4443.5 4454.7 4485.6 3909.3 3951.0 3961.7 3997.7 4020.9 5627.1 5658.5 5666.0 5683.9 5709.4 5969.6 5978.0 5987.4 5995.1 6008.9 5433.2 5556.9 5573.9 5612.6 5643.0 5075.1 5101.0 5104.6 5111.3 5121.5 6138.6 6144.9 6147.9 6158.5 6161.6 5804.3 5955.0 5982.3 5987.3 6017.9 6181.0 6181.6 6192.3 6197.6 6201.2 5632.2 5887.1 5905.4 5906.8 5913.2 5897.1 5971.0 5985.3 5986.0 5999.2 5784.9 5817.7 5825.7 5826.3 5832.4 5626.1 5759.3 5830.7 5838.7 5847.3 5579.9 5641.5 5682.1 5699.4 5782.9 5786.9 5809.7 5823.4 5837.1 5852.1 5511.3 5665.1 5716.5 5727.1 5752.8 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | [#1] ext.html Cycles:180 Average:61127.19 Median:41598.48 stddev:123752.44 (297.5%) stddev-sans-first:124016.15 15:18:02 INFO - PROCESS | 15811 | Values: 596.7 617.1 635.2 637.4 648.6 42355.8 43589.8 43648.6 43694.6 47185.5 44977.0 45877.0 46039.3 46208.8 46241.8 8690.9 8863.4 8893.4 8909.5 8910.0 8811.9 8825.5 8868.0 8868.8 8905.9 8886.9 8909.6 8967.0 9007.4 9034.1 8812.7 8845.4 8854.5 8857.0 8868.3 8713.1 8809.2 8867.2 8874.3 8887.9 42470.6 42489.6 42661.0 42784.1 42955.4 3680.6 3716.2 3734.5 3893.6 4084.6 31719.2 32109.0 32130.5 32190.4 32635.2 4478.9 4509.5 4532.4 4540.7 4619.0 341604.0 343025.0 345510.0 346164.0 347477.3 59727.0 60407.7 60408.1 60445.0 60466.4 36138.3 36926.3 36984.0 38467.6 38695.0 15906.0 16000.4 16169.2 16181.7 16184.4 11852.7 11857.8 11962.8 11989.9 12009.3 19729.2 19850.9 19918.7 20119.2 20174.8 7151.7 7261.0 7410.5 7436.0 7459.8 1013.0 1083.8 1087.6 1095.3 1103.5 32983.5 33161.5 33226.0 33457.3 33613.7 1111.5 1161.6 1162.9 1170.1 1209.2 16227.8 16837.7 16891.9 16949.9 16989.1 569653.0 576661.0 576683.0 576894.0 580730.0 57539.4 58275.0 58452.0 58535.7 58947.0 1337.2 1353.3 1363.6 1364.8 1364.9 30273.4 31507.4 31810.5 31974.4 32563.8 50237.0 51070.2 51122.7 51238.9 51427.7 441196.0 445902.0 447343.0 448124.0 452563.0 28218.6 28628.1 28674.6 28895.0 28947.4 48521.3 49035.3 49733.0 50190.2 50541.0 40244.2 41141.2 41612.4 41803.2 43013.1 40622.8 40719.9 41426.2 41584.6 42555.8 35832.6 39813.6 40503.4 41266.2 41545.0 35593.3 37235.3 37255.3 37357.6 37498.0 28555.9 28663.3 28729.0 29060.0 29131.3 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | [#2] jquery.html Cycles:180 Average:28116.65 Median:12702.93 stddev:75816.53 (596.8%) stddev-sans-first:76000.60 15:18:02 INFO - PROCESS | 15811 | Values: 372.6 375.5 385.9 392.3 396.0 17209.3 18869.2 19528.8 19626.9 19700.7 18533.4 18808.0 19043.0 19192.7 19288.4 5579.2 5631.2 5781.5 5811.2 5882.9 5637.7 5691.9 5704.9 5732.1 5736.9 5527.2 5658.8 5658.8 5671.9 5716.8 5644.5 5672.1 5681.5 5690.7 5700.4 4922.9 4997.2 5034.7 5073.1 5082.4 22205.0 22244.4 22449.3 22939.5 22983.0 2422.9 2468.8 2709.6 2721.0 2734.1 13979.1 14512.1 14551.6 14604.6 14763.3 1913.2 1955.2 1968.4 1971.1 2015.8 78274.0 80049.0 80052.0 80747.0 80901.6 9801.5 9846.9 10044.3 10098.8 10249.3 13486.2 13573.3 13591.4 13601.5 13606.8 7652.9 7729.8 7807.0 7849.5 7896.6 7542.3 7560.7 7758.4 7783.1 7802.5 6365.6 6398.2 6406.0 6422.2 6589.6 1815.6 1819.7 1836.8 1853.9 1876.2 991.8 1004.4 1090.0 1106.5 1136.9 17595.9 19260.4 19287.8 19462.6 19793.8 1029.9 1044.5 1050.4 1067.1 1081.4 10544.0 11186.3 11231.4 11237.7 11271.5 453346.0 457985.0 460102.0 462770.0 465075.0 80549.6 83126.0 83664.6 84477.2 84618.6 1031.7 1050.2 1058.2 1060.2 1062.7 16003.8 17167.3 17329.7 17369.8 17471.4 45186.0 45223.5 45373.8 45497.5 45786.0 59593.4 59688.4 59886.4 60769.4 61145.0 8257.6 8267.6 8318.8 8339.5 8509.1 9232.3 12406.4 13199.6 13362.5 13798.4 11817.0 11838.5 12016.6 12057.5 12086.8 11670.6 11748.8 11865.1 12174.5 12435.1 12413.1 12513.0 12516.6 12620.0 12778.6 7578.2 7581.0 8814.3 8878.8 9033.4 11931.0 12308.1 12338.1 12449.0 12450.6 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | [#3] mootools.html Cycles:180 Average:5948.41 Median:2590.01 stddev:3477.68 (134.3%) stddev-sans-first:3471.32 15:18:02 INFO - PROCESS | 15811 | Values: 1492.8 1527.8 1720.7 1827.5 1861.7 8205.7 8918.0 8934.3 9529.4 9545.6 8986.8 9187.3 9228.9 9265.0 9299.3 2266.8 2303.9 2308.2 2310.8 2310.8 2260.0 2288.9 2346.8 2347.4 2348.4 2326.5 2342.1 2346.5 2389.0 2391.0 2245.2 2245.7 2252.8 2256.8 2272.1 2255.8 2268.5 2269.1 2271.4 2280.3 9250.2 9311.3 9315.9 9317.3 9351.5 5224.2 5349.1 5361.2 5385.1 5425.9 3263.5 3307.1 3462.7 3471.9 3474.8 1152.1 1163.4 1176.1 1176.1 1202.6 12544.2 12662.8 12687.6 12702.0 12731.9 4709.9 4727.8 4795.8 4833.6 4843.0 10760.9 10818.5 10835.9 10843.9 10851.5 5382.2 5418.2 5481.9 5502.4 5512.9 4438.1 4737.3 4822.0 4835.3 5070.7 4087.8 4176.4 4192.8 4223.8 4242.8 1283.5 1289.8 1295.6 1316.1 1320.3 919.4 927.7 938.5 951.7 971.0 5699.0 5756.8 5808.1 5953.3 6110.5 439.7 452.9 455.5 465.9 470.1 3991.1 4008.1 4018.8 4032.6 4053.3 10818.2 11595.4 11605.8 11608.3 11616.8 10996.6 11315.4 11415.1 11426.4 11428.3 8384.6 8482.9 8496.9 8531.0 8571.8 5819.1 5827.1 5849.6 5890.7 5984.9 10094.8 10271.2 10333.2 10348.2 10428.7 10523.4 10615.1 10634.8 10708.5 10708.5 7086.8 7838.5 8046.2 8153.7 8220.7 8954.8 9012.1 9017.0 9039.4 9118.1 7997.2 8170.2 8189.6 8278.4 8305.3 7697.0 7891.9 7905.6 7939.7 8029.6 6942.5 6956.8 7012.5 7035.3 7126.7 4981.4 5065.7 5169.2 5202.3 5223.8 4952.4 4988.8 4993.2 5086.7 5172.1 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | [#4] prototype.html Cycles:180 Average:4944.64 Median:5673.39 stddev:1462.69 (25.8%) stddev-sans-first:1425.14 15:18:02 INFO - PROCESS | 15811 | Values: 327.4 330.7 330.9 338.8 340.7 5527.2 5633.8 5661.2 5684.1 5717.5 5537.3 5542.7 5550.3 5598.5 5600.8 4127.1 4173.4 4320.9 4325.8 4333.1 4289.1 4314.0 4327.7 4338.4 4347.1 4312.0 4312.3 4325.1 4344.6 4369.9 4067.4 4269.5 4272.3 4290.5 4370.6 3731.9 3748.4 3753.0 3753.4 3756.2 5292.7 5296.2 5370.7 5416.9 5434.5 4760.5 4905.5 4956.9 4969.1 5009.2 5824.4 5831.0 5843.4 5845.6 5872.6 4199.2 4226.1 4238.7 4247.7 4262.8 5912.0 5978.8 6083.5 6111.4 6111.7 4326.4 4330.6 4351.6 4360.3 4381.2 4416.7 4446.6 4460.2 4464.3 4469.7 4929.4 4996.9 4998.7 5032.5 5062.4 5878.7 5893.0 5908.3 5917.0 5926.3 1506.3 1517.2 1517.5 1525.4 1527.3 962.8 990.5 991.2 993.4 995.4 5635.5 5654.2 5661.2 5662.0 5711.9 5965.4 5985.9 6013.5 6046.7 6060.7 5497.7 5662.6 5698.2 5700.7 5700.9 3213.9 3228.7 3240.1 3260.7 3274.9 6194.6 6224.8 6258.5 6279.2 6329.0 6192.3 6203.4 6229.8 6246.5 6267.7 6021.9 6257.0 6261.9 6286.2 6311.9 6040.1 6041.1 6054.8 6109.9 6113.0 5994.7 6003.0 6034.2 6052.4 6064.0 5967.9 6242.7 6262.9 6289.4 6302.6 4228.7 4257.0 4259.1 4278.6 4285.3 5907.5 5960.5 5965.0 6029.2 6083.1 5780.4 5783.6 5856.5 5878.7 5921.1 5832.2 5932.4 5972.9 5974.5 5981.2 5170.8 5719.9 5734.8 5795.1 5840.1 5880.0 5910.7 5911.1 5920.9 5954.5 5573.2 5743.5 5762.2 5763.3 5801.4 15:18:02 INFO - PROCESS | 15811 | 15:18:02 INFO - PROCESS | 15811 | [#5] yui.html Cycles:180 Average:15711.05 Median:4885.34 stddev:19071.82 (390.4%) stddev-sans-first:19092.40 15:18:02 INFO - PROCESS | 15811 | Values: 789.4 804.5 828.5 850.9 860.1 14839.8 15425.3 15548.9 15636.1 15716.8 14208.5 14348.0 14349.6 14350.1 14355.6 673.7 793.2 812.8 818.9 820.1 801.7 807.1 808.2 808.6 808.8 861.1 862.4 865.4 868.3 870.0 859.8 864.4 864.8 865.3 867.0 621.6 623.2 624.5 625.8 626.5 14606.6 14648.2 14656.5 14709.7 14715.1 14102.3 14411.3 14466.6 14550.4 14602.3 11886.2 11894.9 12035.1 12042.5 12203.3 3778.9 3796.8 3854.1 3855.5 3944.3 60864.0 61766.4 62269.7 62303.7 62924.4 10876.7 11049.1 11079.4 11113.4 11125.0 19161.3 19249.1 19297.8 19303.8 19354.6 8277.6 8389.8 8391.0 8445.7 8518.1 4884.4 4886.3 4888.4 4906.8 4950.4 3473.2 3528.5 3542.7 3556.2 3591.0 622.9 639.4 653.1 655.8 656.7 745.6 748.6 752.0 762.6 772.2 9514.3 9604.1 9661.8 9882.5 9954.7 591.7 624.3 713.5 736.0 774.3 4431.9 4493.8 4577.0 4594.9 4641.7 65999.7 67443.3 68696.0 69009.7 69919.3 58988.7 59274.0 60643.0 61152.0 62369.4 54783.5 54875.7 55182.7 55525.7 56078.0 9076.0 9133.1 9267.5 9271.5 9278.3 37437.0 37938.4 37945.8 38424.9 38860.8 46860.8 49692.5 50886.2 51003.0 51368.0 11273.2 11371.7 11471.0 11508.3 11533.5 12380.1 12556.1 12601.3 12906.0 13400.3 10254.8 11116.0 11578.2 11880.6 12231.4 11646.9 11797.0 11878.0 12021.8 12452.8 11128.3 11231.6 11263.9 11386.1 11401.3 10239.2 10263.4 10326.5 10454.5 10470.6 9745.2 10110.2 10163.4 10180.3 10211.1 15:18:02 INFO - PROCESS | 15811 | -------- Summary: end -------- 15:18:02 INFO - PROCESS | 15811 | 15:18:03 INFO - TEST-INFO | 15811: exit 0 15:18:03 INFO - TEST-OK | dromaeo_css | took 1109832ms 15:18:03 INFO - TEST-START | kraken 15:18:03 INFO - operating with platform_type : linux_ 15:18:03 INFO - Initialising browser for kraken test... 15:18:03 INFO - TEST-INFO | started process 16044 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc2apbf/profile http://localhost:50468/getInfo.html) 15:18:08 INFO - PROCESS | 16044 | __metrics Screen width/height:1600/1200 15:18:08 INFO - PROCESS | 16044 | colorDepth:24 15:18:08 INFO - PROCESS | 16044 | Browser inner width/height: 1024/697 15:18:08 INFO - PROCESS | 16044 | __metrics 15:18:08 ERROR - PROCESS | 16044 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 15:18:09 INFO - TEST-INFO | 16044: exit 0 15:18:09 INFO - Browser initialized. 15:18:09 INFO - Running cycle 1/1 for kraken test... 15:18:09 INFO - TEST-INFO | started process 16164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc2apbf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:18:21 INFO - PROCESS | 16164 | RSS: Main: 167284736 15:18:21 INFO - PROCESS | 16164 | 15:18:24 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50468/tests/kraken/driver.html?testName=audio-beat-detection) 15:18:25 INFO - PROCESS | 16164 | RSS: Main: 172863488 15:18:25 INFO - PROCESS | 16164 | 15:18:27 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50468/tests/kraken/driver.html?testName=audio-dft) 15:18:28 INFO - PROCESS | 16164 | RSS: Main: 172298240 15:18:28 INFO - PROCESS | 16164 | 15:18:31 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50468/tests/kraken/driver.html?testName=audio-fft) 15:18:31 INFO - PROCESS | 16164 | RSS: Main: 167137280 15:18:31 INFO - PROCESS | 16164 | 15:18:34 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50468/tests/kraken/driver.html?testName=audio-oscillator) 15:18:34 INFO - PROCESS | 16164 | RSS: Main: 168132608 15:18:34 INFO - PROCESS | 16164 | 15:18:36 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50468/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:18:37 INFO - PROCESS | 16164 | RSS: Main: 168251392 15:18:37 INFO - PROCESS | 16164 | 15:18:40 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50468/tests/kraken/driver.html?testName=imaging-darkroom) 15:18:41 INFO - PROCESS | 16164 | RSS: Main: 167993344 15:18:41 INFO - PROCESS | 16164 | 15:18:44 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50468/tests/kraken/driver.html?testName=imaging-desaturate) 15:18:45 INFO - PROCESS | 16164 | RSS: Main: 168730624 15:18:45 INFO - PROCESS | 16164 | 15:18:48 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50468/tests/kraken/driver.html?testName=json-parse-financial) 15:18:48 INFO - PROCESS | 16164 | RSS: Main: 168493056 15:18:48 INFO - PROCESS | 16164 | 15:18:50 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50468/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:18:50 INFO - PROCESS | 16164 | RSS: Main: 168583168 15:18:50 INFO - PROCESS | 16164 | 15:18:52 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:18:53 INFO - PROCESS | 16164 | RSS: Main: 168787968 15:18:53 INFO - PROCESS | 16164 | 15:18:55 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:18:56 INFO - PROCESS | 16164 | RSS: Main: 169406464 15:18:56 INFO - PROCESS | 16164 | 15:18:58 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:18:59 INFO - PROCESS | 16164 | RSS: Main: 168947712 15:18:59 INFO - PROCESS | 16164 | 15:19:02 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:19:02 INFO - PROCESS | 16164 | RSS: Main: 169107456 15:19:02 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | Cycle 1(1): loaded http://localhost:50468/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50468/tests/kraken/driver.html?testName=ai-astar) 15:19:04 INFO - PROCESS | 16164 | RSS: Main: 169779200 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | __start_tp_report 15:19:04 INFO - PROCESS | 16164 | _x_x_mozilla_page_load 15:19:04 INFO - PROCESS | 16164 | _x_x_mozilla_page_load_details 15:19:04 INFO - PROCESS | 16164 | |i|pagename|runs| 15:19:04 INFO - PROCESS | 16164 | |0;ai-astar;110;104;112;109;109;110;109;108;109;112 15:19:04 INFO - PROCESS | 16164 | |1;audio-beat-detection;150;126;152;178;126;164;151;131;185;131 15:19:04 INFO - PROCESS | 16164 | |2;audio-dft;163;169;163;164;164;168;171;167;163;161 15:19:04 INFO - PROCESS | 16164 | |3;audio-fft;73;74;75;156;76;74;75;76;160;76 15:19:04 INFO - PROCESS | 16164 | |4;audio-oscillator;84;85;148;83;84;83;137;83;83;134 15:19:04 INFO - PROCESS | 16164 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;119;117;118 15:19:04 INFO - PROCESS | 16164 | |6;imaging-darkroom;147;147;148;147;149;147;146;148;149;147 15:19:04 INFO - PROCESS | 16164 | |7;imaging-desaturate;96;101;95;95;95;95;96;96;95;95 15:19:04 INFO - PROCESS | 16164 | |8;json-parse-financial;56;56;56;55;56;55;57;57;55;59 15:19:04 INFO - PROCESS | 16164 | |9;json-stringify-tinderbox;62;63;62;62;62;62;61;66;63;63 15:19:04 INFO - PROCESS | 16164 | |10;stanford-crypto-aes;80;78;86;82;82;87;81;80;111;81 15:19:04 INFO - PROCESS | 16164 | |11;stanford-crypto-ccm;121;124;131;130;121;128;125;121;144;140 15:19:04 INFO - PROCESS | 16164 | |12;stanford-crypto-pbkdf2;161;164;166;162;167;160;159;159;158;163 15:19:04 INFO - PROCESS | 16164 | |13;stanford-crypto-sha256-iterative;64;65;67;63;65;62;66;63;62;64 15:19:04 INFO - PROCESS | 16164 | __end_tp_report 15:19:04 INFO - PROCESS | 16164 | __start_cc_report 15:19:04 INFO - PROCESS | 16164 | _x_x_mozilla_cycle_collect,865 15:19:04 INFO - PROCESS | 16164 | __end_cc_report 15:19:04 INFO - PROCESS | 16164 | __startTimestamp1464733144563__endTimestamp 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | ------- Summary: start ------- 15:19:04 INFO - PROCESS | 16164 | Number of tests: 14 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#0] ai-astar Cycles:10 Average:109.20 Median:109.50 stddev:2.25 (2.1%) stddev-sans-first:2.37 15:19:04 INFO - PROCESS | 16164 | Values: 110.0 104.0 112.0 109.0 109.0 110.0 109.0 108.0 109.0 112.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:151.50 stddev:21.29 (14.1%) stddev-sans-first:22.58 15:19:04 INFO - PROCESS | 16164 | Values: 150.0 126.0 152.0 178.0 126.0 164.0 151.0 131.0 185.0 131.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#2] audio-dft Cycles:10 Average:165.30 Median:165.50 stddev:3.23 (2.0%) stddev-sans-first:3.32 15:19:04 INFO - PROCESS | 16164 | Values: 163.0 169.0 163.0 164.0 164.0 168.0 171.0 167.0 163.0 161.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#3] audio-fft Cycles:10 Average:91.50 Median:75.00 stddev:35.08 (46.8%) stddev-sans-first:36.56 15:19:04 INFO - PROCESS | 16164 | Values: 73.0 74.0 75.0 156.0 76.0 74.0 75.0 76.0 160.0 76.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#4] audio-oscillator Cycles:10 Average:100.40 Median:83.00 stddev:27.33 (32.9%) stddev-sans-first:28.33 15:19:04 INFO - PROCESS | 16164 | Values: 84.0 85.0 148.0 83.0 84.0 83.0 137.0 83.0 83.0 134.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 15:19:04 INFO - PROCESS | 16164 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 119.0 117.0 118.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:147.50 stddev:0.97 (0.7%) stddev-sans-first:1.01 15:19:04 INFO - PROCESS | 16164 | Values: 147.0 147.0 148.0 147.0 149.0 147.0 146.0 148.0 149.0 147.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:95.00 stddev:1.85 (2.0%) stddev-sans-first:1.96 15:19:04 INFO - PROCESS | 16164 | Values: 96.0 101.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:1.23 (2.2%) stddev-sans-first:1.30 15:19:04 INFO - PROCESS | 16164 | Values: 56.0 56.0 56.0 55.0 56.0 55.0 57.0 57.0 55.0 59.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:62.50 stddev:1.35 (2.2%) stddev-sans-first:1.41 15:19:04 INFO - PROCESS | 16164 | Values: 62.0 63.0 62.0 62.0 62.0 62.0 61.0 66.0 63.0 63.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:81.50 stddev:9.60 (11.8%) stddev-sans-first:10.02 15:19:04 INFO - PROCESS | 16164 | Values: 80.0 78.0 86.0 82.0 82.0 87.0 81.0 80.0 111.0 81.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:129.00 stddev:8.05 (6.2%) stddev-sans-first:8.06 15:19:04 INFO - PROCESS | 16164 | Values: 121.0 124.0 131.0 130.0 121.0 128.0 125.0 121.0 144.0 140.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.50 stddev:3.07 (1.9%) stddev-sans-first:3.24 15:19:04 INFO - PROCESS | 16164 | Values: 161.0 164.0 166.0 162.0 167.0 160.0 159.0 159.0 158.0 163.0 15:19:04 INFO - PROCESS | 16164 | 15:19:04 INFO - PROCESS | 16164 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.50 stddev:1.66 (2.6%) stddev-sans-first:1.76 15:19:04 INFO - PROCESS | 16164 | Values: 64.0 65.0 67.0 63.0 65.0 62.0 66.0 63.0 62.0 64.0 15:19:04 INFO - PROCESS | 16164 | -------- Summary: end -------- 15:19:04 INFO - PROCESS | 16164 | 15:19:05 INFO - TEST-INFO | 16164: exit 0 15:19:05 INFO - TEST-OK | kraken | took 62085ms 15:19:05 INFO - SUITE-END | took 1171s 15:19:05 INFO - Completed test suite (00:19:32) 15:19:05 INFO - javascript benchmark 15:19:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7249.624644599239, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3111.175538482302, 3181.411438883808, 3195.7123858852706, 3197.3445443684045, 3202.1194171602806, 5483.451654834515, 5537.0, 5554.028045092108, 5563.582731295149, 5580.441955804417, 5361.651492652974, 5484.067708489556, 5499.202615620733, 5523.613347065707, 5569.081101618228, 4950.950490495094, 4982.975085124587, 5096.974515127419, 5137.38351397831, 5184.274201611758, 716.1913350618117, 4857.319975203474, 4954.554090131887, 5004.62465315101, 5074.340335756365, 5007.474215207387, 5037.874053148682, 5057.519535644108, 5064.797408103672, 5073.923891141636, 5051.44434112251, 5056.696598204119, 5070.847874563769, 5072.54347108762, 5083.491650834924, 4897.3878265216845, 4992.0, 5020.347354843883, 5020.924686129711, 5026.145555255615, 5361.571074314045, 5405.891882162374, 5416.945830541684, 5420.105682562373, 5433.809816656398, 5716.28546431696, 5806.854828629276, 5874.148248503986, 5896.764129434818, 5902.557308201902, 5924.407559244084, 5930.110483427477, 5941.584089113764, 5959.910601340984, 6008.609440386361, 5528.9723551381985, 5544.528715059225, 5553.666779993213, 5557.249771280865, 5561.388247292794, 5867.589268751147, 5891.852703682356, 5951.404859514014, 6075.605085669417, 6092.786752463642, 5715.342735585377, 5738.426157384311, 5759.020966435716, 5763.423657634203, 5765.048766953406, 5823.359430462646, 5829.183914252008, 5891.9705401473575, 5910.113482977502, 5930.495907847881, 5806.21616081832, 5837.241158649434, 5877.353491115974, 5898.321693005273, 5914.6746928918465, 5808.419158084243, 5830.300363956352, 5850.736716847752, 5856.267966504187, 5930.258717660293, 4429.490608580055, 4441.178381999276, 4443.466783985942, 4454.710443821141, 4485.551444855489, 3909.257241124177, 3950.9604903951167, 3961.6830653547645, 3997.720159588801, 4020.9396859047138, 5627.071533196977, 5658.547316214693, 5666.008448521492, 5683.943160568341, 5709.371969083398, 5969.552283578749, 5977.970110149421, 5987.431194036647, 5995.100734889714, 6008.879822403527, 5433.239346491494, 5556.888862222733, 5573.944260557342, 5612.635178713371, 5643.0, 5075.111855425286, 5101.0, 5104.617153713486, 5111.335526381621, 5121.4622464641425, 6138.631682099089, 6144.907826382608, 6147.877042459126, 6158.507319414436, 6161.599496032743, 5804.303483581997, 5954.999999999914, 5982.312034116132, 5987.31145918225, 6017.939820601738, 6181.0, 6181.598196117238, 6192.256929168529, 6197.566170368031, 6201.193844800147, 5632.239647647515, 5887.116932459994, 5905.40945905406, 5906.793262235801, 5913.172155898264, 5897.144913987534, 5971.044632858721, 5985.25184351956, 5985.970070149621, 5999.160117583455, 5784.913226301524, 5817.738201781015, 5825.650460972355, 5826.271716035496, 5832.387599302012, 5626.099824028136, 5759.280089988752, 5830.708464576839, 5838.737256823369, 5847.298324200953, 5579.9163012555655, 5641.464060914206, 5682.062459694104, 5699.373068962494, 5782.942170578241, 5786.9131963019745, 5809.680467574324, 5823.359430462561, 5837.09525023622, 5852.122181672783, 5511.283533140827, 5665.121906104396, 5716.514096301695, 5727.083666613488, 5752.827415177551], "value": 5411.569073723656, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.6986671730775, 617.1390909680995, 635.1647583427795, 637.4295005969656, 648.5557393185666, 42355.78822105889, 43589.782051089736, 43648.56351436485, 43694.56305436945, 47185.52814471854, 44977.0, 45877.0, 46039.309410358874, 46208.768956155174, 46241.76879115608, 8690.869636955451, 8863.379563430562, 8893.421927574687, 8909.509976951265, 8909.95545022274, 8811.911880881205, 8825.51459669718, 8867.955660221722, 8868.778280542974, 8905.866412003825, 8886.911130888706, 8909.55452227386, 8967.0, 9007.414518056337, 9034.14175653312, 8812.691555795549, 8845.4250473719, 8854.513001784899, 8856.955715221447, 8868.290536757044, 8713.085126061736, 8809.163129502687, 8867.246284065797, 8874.29005679544, 8887.911120888837, 42470.57529424697, 42489.57510424887, 42661.0, 42784.14431711348, 42955.35566966498, 3680.5583330000572, 3716.2195938852874, 3734.477173195755, 3893.610638936112, 4084.591540845892, 31719.207019824687, 32109.0, 32130.518042229385, 32190.356192876246, 32635.18412039694, 4478.8880277992985, 4509.503954564995, 4532.365468834366, 4540.704854184501, 4618.953810461886, 341604.0, 343025.0, 345510.0, 346164.0, 347477.26261368784, 59727.0, 60407.69796150991, 60408.093878591855, 60445.0, 60466.39533604607, 36138.27723445516, 36926.261474770356, 36984.0, 38467.61532384696, 38695.0, 15906.0, 16000.359985600446, 16169.22138564757, 16181.676366472604, 16184.352625894833, 11852.70368240801, 11857.822132667845, 11962.82055769164, 11989.88010119905, 12009.279443233434, 19729.210831566863, 19850.90074549618, 19918.701219481718, 20119.19523219055, 20174.798252017586, 7151.713931442684, 7260.96369518149, 7410.481266311414, 7436.0, 7459.769138092154, 1012.9949350253202, 1083.8103331916882, 1087.581281206741, 1095.2990086344746, 1103.5034234594464, 32983.46040119886, 33161.50257746136, 33226.0, 33457.33085338328, 33613.663863361544, 1111.5442668505873, 1161.6224726963771, 1162.924409913353, 1170.12825445044, 1209.2019267283551, 16227.83772162287, 16837.663246734995, 16891.91554042222, 16949.91525042367, 16989.065601391794, 569653.0, 576661.0, 576683.0, 576894.0, 580730.0, 57539.42460575424, 58275.0, 58452.0, 58535.707321463124, 58947.0, 1337.2110454831695, 1353.3301015997145, 1363.6454521824396, 1364.8498665146826, 1364.9044566880423, 30273.394532108792, 31507.36985260328, 31810.522842156923, 31974.36051279008, 32563.837180813945, 50237.0, 51070.23394649009, 51122.74438627783, 51238.92566446025, 51427.74286128546, 441196.0, 445902.0, 447343.0, 448124.0, 452563.0, 28218.576721348785, 28628.14115576536, 28674.569881451378, 28895.0, 28947.421051579273, 48521.27218091661, 49035.264471033675, 49733.0, 50190.247146293565, 50541.0, 40244.195116096926, 41141.177176455705, 41612.37581436341, 41803.16393672171, 43013.1397372057, 40622.796886016564, 40719.946004050405, 41426.171476569696, 41584.584154158074, 42555.7872210637, 35832.64167358293, 39813.60186398099, 40503.392449112696, 41266.1746765057, 41545.0, 35593.28813423665, 37235.255294894494, 37255.25489490249, 37357.62642373541, 37498.0, 28555.857220713762, 28663.283417913884, 28729.0, 29060.0, 29131.271718206368], "value": 20131.405782975824, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.57154272586524, 375.5080844094237, 385.9266739319529, 392.289955181122, 395.95248570171583, 17209.3116275349, 18869.150888210028, 19528.804711952875, 19626.901865490654, 19700.704489432654, 18533.44399668009, 18808.0, 19043.0, 19192.71210931837, 19288.421347359603, 5579.246801681782, 5631.18347839564, 5781.450762177607, 5811.186433899257, 5882.911756323659, 5637.661740295575, 5691.8577035574235, 5704.8859022819515, 5732.054211055191, 5736.913946290809, 5527.226188333617, 5658.773649054033, 5658.773649054054, 5671.914921276184, 5716.79991200306, 5644.491995720364, 5672.12082127271, 5681.488666020036, 5690.658560486384, 5700.37295897451, 4922.901541969176, 4997.175466048134, 5034.697918124888, 5073.061483625541, 5082.415522214918, 22205.0, 22244.443888902744, 22449.32652020442, 22939.476657567655, 22983.0, 2422.8667423291713, 2468.7531246875346, 2709.552923767576, 2721.0, 2734.125079974409, 13979.09135906163, 14512.1292722437, 14551.636209094751, 14604.561863144016, 14763.33564989578, 1913.1773337464886, 1955.1885967323685, 1968.3602829080462, 1971.0637447212603, 2015.8084981926693, 78274.0, 80049.0, 80051.99999999942, 80747.0, 80901.59549202275, 9801.509924503745, 9846.901530984598, 10044.29689921713, 10098.798024039625, 10249.33379330356, 13486.190828550318, 13573.253471059186, 13591.388387522387, 13601.455941762417, 13606.795898061537, 7652.885206721904, 7729.757473377851, 7806.960965195138, 7849.529028258323, 7896.644650990719, 7542.321191092828, 7560.735374261874, 7758.418118641124, 7783.104942931522, 7802.492837965514, 6365.554411191265, 6398.200224971879, 6406.0, 6422.197225346832, 6589.6375699336995, 1815.6277963017549, 1819.7361382599713, 1836.7887692915485, 1853.8887666740038, 1876.2401227502883, 991.8363470027365, 1004.3672486333563, 1090.0298734126634, 1106.5241945963312, 1136.93696393872, 17595.912020439817, 19260.422187334403, 19287.8071219286, 19462.610747784965, 19793.802061979484, 1029.9433531155858, 1044.4516628769804, 1050.3960222871879, 1067.1196263082988, 1081.4268437728017, 10544.0, 11186.272892261977, 11231.38227397501, 11237.71905702364, 11271.492782824793, 453346.0, 457985.0, 460102.0, 462770.0, 465075.0, 80549.59725201454, 83126.0, 83664.58167709244, 84477.15522844816, 84618.57690711507, 1031.6985645932896, 1050.22808235945, 1058.2327812335996, 1060.1677683018793, 1062.718379629399, 16003.839961600235, 17167.31330746756, 17329.653406932044, 17369.826301736823, 17471.38850140239, 45186.0, 45223.547764521936, 45373.773131134134, 45497.545024549334, 45786.0, 59593.404065960516, 59688.403115968285, 59886.40113598808, 60769.392306078145, 61145.0, 8257.587120644064, 8267.62795674184, 8318.833623327379, 8339.54132522717, 8509.149085091689, 9232.307576931891, 12406.379681016093, 13199.604011879659, 13362.532311369445, 13798.448062077405, 11817.0, 11838.526458941546, 12016.579419720269, 12057.51769929193, 12086.818697719717, 11670.591529296435, 11748.823767643322, 11865.050795936477, 12174.452149653463, 12435.12954093241, 12413.06901982373, 12513.0, 12516.624501264976, 12620.0, 12778.61664150077, 7578.204288549623, 7580.9620951894885, 8814.338924580554, 8878.822423551623, 9033.412828166149, 11931.0, 12308.138430309793, 12338.13633045714, 12449.0, 12450.564230251897], "value": 9326.980156712048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1492.7686208637658, 1527.8013858198433, 1720.707479728446, 1827.5431142214447, 1861.7393564900917, 8205.712800051999, 8918.0, 8934.329925255599, 9529.380590261637, 9545.61817527299, 8986.775330616738, 9187.310951678634, 9228.8615670765, 9265.0, 9299.302552308587, 2266.7506574276817, 2303.8617682938993, 2308.1921327535397, 2310.7689231076924, 2310.8266879984067, 2259.9887000564972, 2288.8855557222073, 2346.765323467648, 2347.424880904181, 2348.424635964191, 2326.5463234669246, 2342.121704360865, 2346.4720437901506, 2388.9761102389016, 2391.0435825669697, 2245.2029529517113, 2245.6743772153027, 2252.8085112765434, 2256.751757306695, 2272.0570963050313, 2255.796978271947, 2268.5236100418765, 2269.069681430622, 2271.3640180749417, 2280.315905228425, 9250.167484926322, 9311.348205625609, 9315.860262096074, 9317.254619630414, 9351.532423378803, 5224.216367544861, 5349.14413693811, 5361.249425080453, 5385.111456609655, 5425.891482170335, 3263.5104734289807, 3307.090550098718, 3462.671046250602, 3471.878484253039, 3474.7915125092322, 1152.1474109159162, 1163.3775929877474, 1176.1214699045486, 1176.1355403778216, 1202.6392082375253, 12544.184627999242, 12662.81005784914, 12687.619371418872, 12702.0, 12731.872681273162, 4709.929351059737, 4727.83452579165, 4795.784189711471, 4833.637477189225, 4842.975785121052, 10760.892391076146, 10818.513166907507, 10835.945820270848, 10843.945780271048, 10851.51168197433, 5382.2464854920345, 5418.214358917935, 5481.862953426196, 5502.367227768777, 5512.944870551243, 4438.134563760036, 4737.28940658904, 4821.975890120527, 4835.323054772335, 5070.6957582545165, 4087.7956102195367, 4176.352665336878, 4192.81132349045, 4223.767692776865, 4242.766647834399, 1283.5122653391652, 1289.767841788487, 1295.6242689619905, 1316.1116246533552, 1320.3332317179763, 919.4069824962861, 927.7077720518015, 938.5448057692014, 951.7335146159087, 971.0047201618397, 5699.0, 5756.769729210785, 5808.099744539603, 5953.255843019622, 6110.450059494667, 439.74714539139484, 452.8890421846603, 455.5467310026545, 465.9138059458943, 470.08333749189217, 3991.1020020495157, 4008.0581063450068, 4018.7588744675413, 4032.6169013943627, 4053.310937140693, 10818.24272300947, 11595.420228988516, 11605.825912611317, 11608.30350178992, 11616.825747613793, 10996.615118470816, 11315.434228288552, 11415.143864210217, 11426.428678566039, 11428.257163284527, 8384.580770961427, 8482.915170848213, 8496.872546911802, 8531.0, 8571.785705357415, 5819.098039803837, 5827.125931110368, 5849.64902105875, 5890.705464726832, 5984.910226346696, 10094.798104038025, 10271.229657775548, 10333.173346132142, 10348.22388320894, 10428.687139385831, 10523.36887782395, 10615.097716694167, 10634.84047739269, 10708.464576771286, 10708.464576771286, 7086.751963681247, 7838.490498117604, 8046.155153708776, 8153.6738530458115, 8220.712275070344, 8954.776130596787, 9012.098790120936, 9017.0, 9039.412438191497, 9118.088191180828, 7997.200279971957, 8170.182981701782, 8189.590520474072, 8278.379121565786, 8305.335573154012, 7696.961515192388, 7891.921080789119, 7905.644246009058, 7939.722109726132, 8029.638666259915, 6942.548734332252, 6956.79129626112, 7012.474064445289, 7035.261297563887, 7126.714931402686, 4981.427135879276, 5065.670731402447, 5169.172932330809, 5202.323697919246, 5223.843284701465, 4952.4304704959395, 4988.750562471934, 4993.151164302005, 5086.720230387216, 5172.069027575073], "value": 4641.484350481025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.4139290669701, 330.7155845972464, 330.90403782902956, 338.80518701746473, 340.68146283225184, 5527.203658810888, 5633.8309850704445, 5661.1508273758955, 5684.147377893318, 5717.542596592262, 5537.307836520435, 5542.722863856811, 5550.27846379971, 5598.5086636446695, 5600.803971860985, 4127.071408932996, 4173.394857745625, 4320.935185972213, 4325.805338759747, 4333.090051089275, 4289.091354347317, 4313.978430107845, 4327.7186982846015, 4338.436003319564, 4347.13057388522, 4312.0, 4312.28847240205, 4325.134973005396, 4344.565543445646, 4369.890752731176, 4067.3695577185435, 4269.487661480611, 4272.273713468718, 4290.549492303295, 4370.628496577779, 3731.8880433587037, 3748.4377343398437, 3752.981235093807, 3753.41822017585, 3756.154865155318, 5292.676830792302, 5296.232046353296, 5370.650907690988, 5416.943695979286, 5434.483724046209, 4760.523947605247, 4905.5094490550655, 4956.851294461172, 4969.130402179604, 5009.173486374744, 5824.388439213864, 5830.970845145789, 5843.415658434123, 5845.5908086433965, 5872.647641141545, 4199.160167966419, 4226.119376877291, 4238.7032907696475, 4247.660187185018, 4262.7655478948645, 5911.96037367112, 5978.8206353809455, 6083.543734219951, 6111.419415155553, 6111.694415279218, 4326.437563116775, 4330.631896288788, 4351.586599273064, 4360.345948107746, 4381.211381951216, 4416.690831641754, 4446.599806017474, 4460.241758900995, 4464.2633965396, 4469.687121901501, 4929.359183306147, 4996.850094497171, 4998.725070121178, 5032.47159048302, 5062.443131255559, 5878.678546621145, 5893.0, 5908.320543137508, 5917.023691091008, 5926.318473375531, 1506.3372116268806, 1517.1807224098864, 1517.4613012380667, 1525.3745964154643, 1527.2745445913101, 962.764122789921, 990.4898977026837, 991.1921783746368, 993.4486360070231, 995.3629677006721, 5635.492205968387, 5654.15187721845, 5661.207430959669, 5662.0, 5711.857203569944, 5965.403459654, 5985.904933273319, 6013.5189184865985, 6046.73018666085, 6060.727267272983, 5497.670139791625, 5662.631928924607, 5698.17376480408, 5700.686462244534, 5700.8859822803315, 3213.855376508063, 3228.6609905960004, 3240.09277402323, 3260.7065364117343, 3274.8526316315824, 6194.628322300677, 6224.782132625336, 6258.496601359493, 6279.215098112736, 6328.968355158195, 6192.287886893065, 6203.4416902479, 6229.7508099676415, 6246.531510136758, 6267.6866156691985, 6021.8558473889825, 6257.030160325156, 6261.874762504815, 6286.151369565142, 6311.873762524816, 6040.1241819934985, 6041.063635136561, 6054.848628784315, 6109.877802443837, 6113.0, 5994.70026498682, 6003.0, 6034.21555197839, 6052.364501727431, 6064.0, 5967.880642387215, 6242.719077641608, 6262.937370626236, 6289.40250676185, 6302.613425046482, 4228.667969589569, 4257.0, 4259.148170365877, 4278.572142785696, 4285.271503844417, 5907.527397808251, 5960.5231581474245, 5965.0, 6029.246344206974, 6083.117947897442, 5780.364159942319, 5783.566232532493, 5856.472917437451, 5878.706684529397, 5921.082232254007, 5832.24180856486, 5932.436418540232, 5972.850678733066, 5974.492168165754, 5981.222441082806, 5170.844874653766, 5719.8570035749435, 5734.827955161351, 5795.072788353843, 5840.07996001999, 5880.001199388257, 5910.7340169693325, 5911.083782013795, 5920.851978700567, 5954.46409823118, 5573.191887176417, 5743.4543718346695, 5762.1849117527845, 5763.308402991668, 5801.419858014334], "value": 4473.2136440281365, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.443442373127, 804.4610111225478, 828.5235989306146, 850.8741518498339, 860.1269711243085, 14839.777403338945, 15425.305861236244, 15548.922255388736, 15636.140012299318, 15716.842831571681, 14208.502702405418, 14348.0, 14349.641258968531, 14350.138991660508, 14355.569332920002, 673.6968364236077, 793.1671744668075, 812.7683610171102, 818.8935438393, 820.1019883227856, 801.7113839017978, 807.0839597057324, 808.1877712898558, 808.6078252047781, 808.8180159464132, 861.0528418739399, 862.4006315610653, 865.3682811547574, 868.3183700794877, 869.9652013919435, 859.8280343931176, 864.3733293362325, 864.7924498120469, 865.3034307382554, 866.9956650216772, 621.6208113051035, 623.18985319085, 624.4941597306197, 625.7559551774812, 626.4768917953514, 14606.561803145816, 14648.19434931089, 14656.487022954252, 14709.70580588393, 14715.117092974457, 14102.294885255696, 14411.279436028157, 14466.638334041629, 14550.417983280657, 14602.34289456977, 11886.167968242227, 11894.940525297317, 12035.097367697457, 12042.518299268018, 12203.328816915066, 3778.924421511582, 3796.8101594920145, 3854.0557563396865, 3855.4987851579394, 3944.349182384903, 60864.0, 61766.38233617696, 62269.688651556455, 62303.68848155776, 62924.37075629231, 10876.72808179786, 11049.116070714324, 11079.390633515073, 11113.44432778374, 11125.0, 19161.32935347256, 19249.13378897953, 19297.807021929882, 19303.80696193048, 19354.612907741765, 8277.62750676221, 8389.790255243546, 8391.0, 8445.662173513114, 8518.063013068557, 4884.389451318585, 4886.291487734258, 4888.437829649636, 4906.779194936236, 4950.381202349707, 3473.1664400543686, 3528.4530897710897, 3542.663446972585, 3556.2354093869426, 3591.0, 622.8505158761865, 639.3829954094228, 653.1085068880943, 655.7672026430644, 656.6651007985866, 745.6383653927841, 748.6107224243362, 752.0486584470649, 762.6110683551428, 772.1892013385923, 9514.33399662031, 9604.135627793532, 9661.806763864683, 9882.505874706236, 9954.701358959242, 591.6716222496556, 624.3194917539813, 713.4934196720386, 736.0321177651372, 774.2760518914832, 4431.8670439886855, 4493.842715504941, 4577.0, 4594.862154135381, 4641.698289611232, 65999.67000164969, 67443.3255667437, 68696.0, 69009.65495172492, 69919.3008069923, 58988.7050564753, 59274.0, 60643.0, 61152.0, 62369.37630623727, 54783.45216547863, 54875.72562137164, 55182.72408637931, 55525.722371387885, 56078.0, 9075.954620226856, 9133.132352426508, 9267.490288034223, 9271.490068046322, 9278.257739380968, 37437.0, 37938.43092353672, 37945.81027094847, 38424.88663123555, 38860.805695971336, 46860.7656961713, 49692.503074970235, 50886.23670644869, 51003.0, 51368.0, 11273.154513411362, 11371.658850234506, 11471.0, 11508.309501429942, 11533.480993355486, 12380.133390662568, 12556.121071524905, 12601.306928119042, 12905.967522598357, 13400.32998350098, 10254.794904102026, 11116.0, 11578.189526733047, 11880.643580692593, 12231.449584768528, 11646.94176529112, 11797.0, 11878.0, 12021.819672704742, 12452.750944981231, 11128.332300062022, 11231.55073797039, 11263.943680281545, 11386.146039047271, 11401.258918170292, 10239.232057595858, 10263.435511046964, 10326.48367581633, 10454.529546170288, 10470.581176752845, 9745.22038236925, 10110.191184705352, 10163.44101074448, 10180.287379883337, 10211.081002709792], "value": 6880.7458588065965, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 112.0, 109.0, 109.0, 110.0, 109.0, 108.0, 109.0, 112.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 126.0, 152.0, 178.0, 126.0, 164.0, 151.0, 131.0, 185.0, 131.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 169.0, 163.0, 164.0, 164.0, 168.0, 171.0, 167.0, 163.0, 161.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 156.0, 76.0, 74.0, 75.0, 76.0, 160.0, 76.0], "value": 91.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 148.0, 83.0, 84.0, 83.0, 137.0, 83.0, 83.0, 134.0], "value": 100.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 119.0, 117.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 149.0, 147.0, 146.0, 148.0, 149.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 101.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 55.0, 56.0, 55.0, 57.0, 57.0, 55.0, 59.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 61.0, 66.0, 63.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 86.0, 82.0, 82.0, 87.0, 81.0, 80.0, 111.0, 81.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 124.0, 131.0, 130.0, 121.0, 128.0, 125.0, 121.0, 144.0, 140.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 166.0, 162.0, 167.0, 160.0, 159.0, 159.0, 158.0, 163.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 67.0, 63.0, 65.0, 62.0, 66.0, 63.0, 62.0, 64.0], "value": 64.1, "unit": "score"}]}]} 15:19:05 INFO - Return code: 0 15:19:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:19:05 ERROR - # TBPL SUCCESS # 15:19:05 INFO - Running post-action listener: _resource_record_post_action 15:19:05 INFO - ##### 15:19:05 INFO - ##### Finished run-tests step (success) 15:19:05 INFO - ##### 15:19:05 INFO - Running post-run listener: _resource_record_post_run 15:19:05 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 539430912; Read time: 12; Write time: 403688 15:19:05 INFO - TinderboxPrint: CPU usage
13.4% 15:19:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 15:19:05 INFO - TinderboxPrint: I/O write bytes / time
539,430,912 / 403,688 15:19:05 INFO - TinderboxPrint: CPU idle
8,216.7 (86.5%) 15:19:05 INFO - TinderboxPrint: CPU user
1,221.4 (12.9%) 15:19:05 INFO - TinderboxPrint: Swap in / out
0 / 0 15:19:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 247521280; Read time: 0; Write time: 207656 15:19:05 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291909632; Read time: 12; Write time: 196032 15:19:06 INFO - Running post-run listener: _upload_blobber_files 15:19:06 INFO - Blob upload gear active. 15:19:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:19:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:19:06 INFO - (blobuploader) - INFO - Open directory for files ... 15:19:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:19:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:19:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:19:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:19:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:19:07 INFO - (blobuploader) - INFO - Done attempting. 15:19:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:19:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:19:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:19:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:19:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:19:09 INFO - (blobuploader) - INFO - Done attempting. 15:19:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:19:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:19:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:19:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:19:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:19:13 INFO - (blobuploader) - INFO - Done attempting. 15:19:13 INFO - (blobuploader) - INFO - Iteration through files over. 15:19:13 INFO - Return code: 0 15:19:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:19:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:19:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fc97194e3f89d88dc371cdf279b1da3b9aee8a1bbece174800ed23d7566b8b9bae9e440ac2757c4a38630b3608a75ff9c6a415a50166510ffe6fa1ef0d9b8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2211eb2fca3f090c4e3064ce4f697c014110fe59e5e3e45165e5c791aa87133cfe02e950b8e4368dafec39bf930e37e509939359c139e1f26445d2fb980a7507", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/adf0ec0af2b6f7d925520bbd76927775949c4eac6c8a1fd8dd6179e22c8f1cb9a1b1ab4ec911da72807c5a5f99eaec774fd2a0ae7c5dddbcc713570f5cad4281"} 15:19:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:19:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:19:13 INFO - Contents: 15:19:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fc97194e3f89d88dc371cdf279b1da3b9aee8a1bbece174800ed23d7566b8b9bae9e440ac2757c4a38630b3608a75ff9c6a415a50166510ffe6fa1ef0d9b8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2211eb2fca3f090c4e3064ce4f697c014110fe59e5e3e45165e5c791aa87133cfe02e950b8e4368dafec39bf930e37e509939359c139e1f26445d2fb980a7507", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/adf0ec0af2b6f7d925520bbd76927775949c4eac6c8a1fd8dd6179e22c8f1cb9a1b1ab4ec911da72807c5a5f99eaec774fd2a0ae7c5dddbcc713570f5cad4281"} 15:19:13 INFO - Running post-run listener: copy_logs_to_upload_dir 15:19:13 INFO - Copying logs to upload dir... 15:19:13 INFO - mkdir: /builds/slave/test/build/upload/logs 15:19:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.664332 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-31 15:19:13.545664) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:13.552866) ========= 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=920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fc97194e3f89d88dc371cdf279b1da3b9aee8a1bbece174800ed23d7566b8b9bae9e440ac2757c4a38630b3608a75ff9c6a415a50166510ffe6fa1ef0d9b8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2211eb2fca3f090c4e3064ce4f697c014110fe59e5e3e45165e5c791aa87133cfe02e950b8e4368dafec39bf930e37e509939359c139e1f26445d2fb980a7507", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/adf0ec0af2b6f7d925520bbd76927775949c4eac6c8a1fd8dd6179e22c8f1cb9a1b1ab4ec911da72807c5a5f99eaec774fd2a0ae7c5dddbcc713570f5cad4281"} build_url:https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fc97194e3f89d88dc371cdf279b1da3b9aee8a1bbece174800ed23d7566b8b9bae9e440ac2757c4a38630b3608a75ff9c6a415a50166510ffe6fa1ef0d9b8cb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2211eb2fca3f090c4e3064ce4f697c014110fe59e5e3e45165e5c791aa87133cfe02e950b8e4368dafec39bf930e37e509939359c139e1f26445d2fb980a7507", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/adf0ec0af2b6f7d925520bbd76927775949c4eac6c8a1fd8dd6179e22c8f1cb9a1b1ab4ec911da72807c5a5f99eaec774fd2a0ae7c5dddbcc713570f5cad4281"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:13.577113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:13.577399) ========= 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=920470bf432669ad7e3a5387000001ca-1464731719.284915-1582122039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:13.629158) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 15:19:13.629652) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 15:19:13.630069) ========= ========= Total master_lag: 0.16 =========