builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1464628694.43 results: success (0) buildid: 20160530073029 builduid: 3497c09b4dbf4345892627dfd9ac3924 revision: 3435dd7ad71fe9003bdeee18fd38d815e033beef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.432368) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.432872) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.433151) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.449072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.449418) ========= 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=8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-30 10:18:14-- 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% 114M=0s 2016-05-30 10:18:14 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105183 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.568388) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.568716) ========= 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=8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021094 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:14.618387) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 10:18:14.618786) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3435dd7ad71fe9003bdeee18fd38d815e033beef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3435dd7ad71fe9003bdeee18fd38d815e033beef --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=8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-30 10:18:14,632 truncating revision to first 12 chars 2016-05-30 10:18:14,632 Setting DEBUG logging. 2016-05-30 10:18:14,632 attempt 1/10 2016-05-30 10:18:14,632 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3435dd7ad71f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 10:18:15,291 unpacking tar archive at: mozilla-central-3435dd7ad71f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.024286 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 10:18:15.658541) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:15.659109) ========= script_repo_revision: 3435dd7ad71fe9003bdeee18fd38d815e033beef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:15.659757) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:15.660222) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 10:18:15.671037) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-30 10:18:15.671618) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:18:15 INFO - MultiFileLogger online at 20160530 10:18:15 in /builds/slave/test 10:18:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:18:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:18:15 INFO - {'append_to_log': False, 10:18:15 INFO - 'base_work_dir': '/builds/slave/test', 10:18:15 INFO - 'blob_upload_branch': 'Firefox', 10:18:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:18:15 INFO - 'branch': 'Firefox', 10:18:15 INFO - 'buildbot_json_path': 'buildprops.json', 10:18:15 INFO - 'config_files': ('talos/linux_config.py',), 10:18:15 INFO - 'default_actions': ('clobber', 10:18:15 INFO - 'read-buildbot-config', 10:18:15 INFO - 'download-and-extract', 10:18:15 INFO - 'populate-webroot', 10:18:15 INFO - 'create-virtualenv', 10:18:15 INFO - 'install', 10:18:15 INFO - 'run-tests'), 10:18:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:18:15 INFO - 'download_minidump_stackwalk': True, 10:18:15 INFO - 'download_symbols': 'ondemand', 10:18:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:18:15 INFO - 'tooltool.py': '/tools/tooltool.py', 10:18:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:18:15 INFO - '/tools/misc-python/virtualenv.py')}, 10:18:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:18:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:18:15 INFO - 'installer_path': 'installer.exe', 10:18:15 INFO - 'log_level': 'info', 10:18:15 INFO - 'log_name': 'talos', 10:18:15 INFO - 'log_to_console': True, 10:18:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:18:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:18:15 INFO - 'opt_config_files': (), 10:18:15 INFO - 'pip_index': False, 10:18:15 INFO - 'sps_profile': False, 10:18:15 INFO - 'sps_profile_interval': 0, 10:18:15 INFO - 'suite': 'dromaeojs', 10:18:15 INFO - 'system_bits': '32', 10:18:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:18:15 INFO - 'title': 'talos-linux64-ix-038', 10:18:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:18:15 INFO - 'use_talos_json': True, 10:18:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:18:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:18:15 INFO - 'work_dir': 'build'} 10:18:15 INFO - ##### 10:18:15 INFO - ##### Running clobber step. 10:18:15 INFO - ##### 10:18:15 INFO - Running pre-action listener: _resource_record_pre_action 10:18:15 INFO - Running main action method: clobber 10:18:15 INFO - rmtree: /builds/slave/test/build 10:18:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:18:16 INFO - Running post-action listener: _resource_record_post_action 10:18:16 INFO - ##### 10:18:16 INFO - ##### Finished clobber step (success) 10:18:16 INFO - ##### 10:18:16 INFO - ##### 10:18:16 INFO - ##### Running read-buildbot-config step. 10:18:16 INFO - ##### 10:18:16 INFO - Running pre-action listener: _resource_record_pre_action 10:18:16 INFO - Running main action method: read_buildbot_config 10:18:16 INFO - Using buildbot properties: 10:18:16 INFO - { 10:18:16 INFO - "project": "", 10:18:16 INFO - "product": "firefox", 10:18:16 INFO - "script_repo_revision": "production", 10:18:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:18:16 INFO - "repository": "", 10:18:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 10:18:16 INFO - "buildid": "20160530073029", 10:18:16 INFO - "pgo_build": "True", 10:18:16 INFO - "basedir": "/builds/slave/test", 10:18:16 INFO - "buildnumber": 313, 10:18:16 INFO - "slavename": "talos-linux64-ix-038", 10:18:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:18:16 INFO - "platform": "ubuntu64_hw", 10:18:16 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 10:18:16 INFO - "branch": "mozilla-central", 10:18:16 INFO - "repo_path": "mozilla-central", 10:18:16 INFO - "revision": "3435dd7ad71fe9003bdeee18fd38d815e033beef", 10:18:16 INFO - "stage_platform": "linux64-pgo", 10:18:16 INFO - "builduid": "3497c09b4dbf4345892627dfd9ac3924", 10:18:16 INFO - "slavebuilddir": "test" 10:18:16 INFO - } 10:18:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:18:16 INFO - Running post-action listener: _resource_record_post_action 10:18:16 INFO - ##### 10:18:16 INFO - ##### Finished read-buildbot-config step (success) 10:18:16 INFO - ##### 10:18:16 INFO - ##### 10:18:16 INFO - ##### Running download-and-extract step. 10:18:16 INFO - ##### 10:18:16 INFO - Running pre-action listener: _resource_record_pre_action 10:18:16 INFO - Running main action method: download_and_extract 10:18:16 INFO - mkdir: /builds/slave/test/build/tests 10:18:16 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')]} 10:18:16 INFO - https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:18:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:18:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:18:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:17 INFO - Downloaded 1361 bytes. 10:18:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:18:17 INFO - Using the following test package requirements: 10:18:17 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:18:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:18:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:18:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'jsshell-linux-x86_64.zip'], 10:18:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:18:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:18:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:18:17 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:18:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:18:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:18:17 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:18:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:18:17 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')]} 10:18:17 INFO - https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:18:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:18:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:18:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:19 INFO - Downloaded 24410261 bytes. 10:18:19 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'] 10:18:19 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 10:18:20 INFO - Return code: 0 10:18:20 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 10:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:20 INFO - https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:18:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:18:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:18:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:22 INFO - Downloaded 24410261 bytes. 10:18:22 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'] 10:18:22 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 10:18:23 INFO - Return code: 0 10:18:23 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')]} 10:18:23 INFO - https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:18:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:18:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:18:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:24 INFO - Downloaded 10834055 bytes. 10:18:24 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'] 10:18:24 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 10:18:24 INFO - Return code: 0 10:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:24 INFO - https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MChEN6FjQW2gE_DBf0QmDw/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 10:18:26 INFO - Downloaded 58939604 bytes. 10:18:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:18:26 INFO - mkdir: /builds/slave/test/properties 10:18:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:18:26 INFO - Writing to file /builds/slave/test/properties/build_url 10:18:26 INFO - Contents: 10:18:26 INFO - build_url:https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:18:26 INFO - Running post-action listener: _resource_record_post_action 10:18:27 INFO - Running post-action listener: set_extra_try_arguments 10:18:27 INFO - ##### 10:18:27 INFO - ##### Finished download-and-extract step (success) 10:18:27 INFO - ##### 10:18:27 INFO - ##### 10:18:27 INFO - ##### Running populate-webroot step. 10:18:27 INFO - ##### 10:18:27 INFO - Running pre-action listener: _resource_record_pre_action 10:18:27 INFO - Running main action method: populate_webroot 10:18:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:18:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:18:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:18:27 INFO - '--tppagecycles', 10:18:27 INFO - '7'], 10:18:27 INFO - 'tests': ['tsvgm']}}, 10:18:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:18:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:18:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:18:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:18:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['damp', 'tps']}, 10:18:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'tests': ['damp', 'tps']}, 10:18:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['dromaeo_dom']}, 10:18:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:18:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['a11yr', 10:18:27 INFO - 'ts_paint', 10:18:27 INFO - 'tpaint', 10:18:27 INFO - 'sessionrestore', 10:18:27 INFO - 'sessionrestore_no_auto_restore', 10:18:27 INFO - 'tabpaint']}, 10:18:27 INFO - 'other-e10s': {'tests': ['a11yr', 10:18:27 INFO - 'ts_paint', 10:18:27 INFO - 'tpaint', 10:18:27 INFO - 'sessionrestore', 10:18:27 INFO - 'sessionrestore_no_auto_restore', 10:18:27 INFO - 'tabpaint']}, 10:18:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:18:27 INFO - 'ts_paint', 10:18:27 INFO - 'tpaint', 10:18:27 INFO - 'sessionrestore', 10:18:27 INFO - 'sessionrestore_no_auto_restore', 10:18:27 INFO - 'tabpaint']}, 10:18:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:18:27 INFO - 'ts_paint', 10:18:27 INFO - 'tpaint', 10:18:27 INFO - 'sessionrestore', 10:18:27 INFO - 'sessionrestore_no_auto_restore', 10:18:27 INFO - 'tabpaint']}, 10:18:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['a11yr', 10:18:27 INFO - 'ts_paint', 10:18:27 INFO - 'tpaint', 10:18:27 INFO - 'sessionrestore', 10:18:27 INFO - 'sessionrestore_no_auto_restore', 10:18:27 INFO - 'tabpaint']}, 10:18:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['a11yr', 10:18:27 INFO - 'ts_paint', 10:18:27 INFO - 'tpaint', 10:18:27 INFO - 'sessionrestore', 10:18:27 INFO - 'sessionrestore_no_auto_restore', 10:18:27 INFO - 'tabpaint']}, 10:18:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['tsvgx', 10:18:27 INFO - 'tsvgr_opacity', 10:18:27 INFO - 'tart', 10:18:27 INFO - 'tscrollx', 10:18:27 INFO - 'cart']}, 10:18:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:18:27 INFO - 'tsvgr_opacity', 10:18:27 INFO - 'tart', 10:18:27 INFO - 'tscrollx', 10:18:27 INFO - 'cart']}, 10:18:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'talos_options': ['--disable-e10s'], 10:18:27 INFO - 'tests': ['tp5o']}, 10:18:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'tests': ['tp5o']}, 10:18:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'talos_options': ['--disable-e10s', 10:18:27 INFO - '--xperf_path', 10:18:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:27 INFO - 'tests': ['tp5n']}, 10:18:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:27 INFO - 'talos_options': ['--xperf_path', 10:18:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:27 INFO - 'tests': ['tp5n']}}, 10:18:27 INFO - 'talos.zip': {'path': '', 10:18:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:18:27 INFO - Running post-action listener: _resource_record_post_action 10:18:27 INFO - ##### 10:18:27 INFO - ##### Finished populate-webroot step (success) 10:18:27 INFO - ##### 10:18:27 INFO - ##### 10:18:27 INFO - ##### Running create-virtualenv step. 10:18:27 INFO - ##### 10:18:27 INFO - Running pre-action listener: _resource_record_pre_action 10:18:27 INFO - Running main action method: create_virtualenv 10:18:27 INFO - Creating virtualenv /builds/slave/test/build/venv 10:18:27 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 10:18:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:18:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:18:27 INFO - Using real prefix '/usr' 10:18:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:18:27 INFO - Installing distribute.............................................................................................................................................................................................done. 10:18:29 INFO - Installing pip.................done. 10:18:29 INFO - Return code: 0 10:18:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:29 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 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:29 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 10:18:29 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 10:18:29 INFO - Using env: {'DISPLAY': ':0', 10:18:29 INFO - 'HOME': '/home/cltbld', 10:18:29 INFO - 'LANG': 'en_US.UTF-8', 10:18:29 INFO - 'LANGUAGE': 'en_US:en', 10:18:29 INFO - 'LOGNAME': 'cltbld', 10:18:29 INFO - 'MAIL': '/var/mail/cltbld', 10:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:29 INFO - 'MOZ_NO_REMOTE': '1', 10:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:29 INFO - 'NO_EM_RESTART': '1', 10:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:29 INFO - 'PWD': '/builds/slave/test', 10:18:29 INFO - 'SHELL': '/bin/bash', 10:18:29 INFO - 'SHLVL': '1', 10:18:29 INFO - 'TERM': 'linux', 10:18:29 INFO - 'TMOUT': '86400', 10:18:29 INFO - 'USER': 'cltbld', 10:18:29 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:29 INFO - '_': '/tools/buildbot/bin/python'} 10:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:30 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 10:18:30 INFO - Cleaning up... 10:18:30 INFO - Return code: 0 10:18:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:18:30 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')]} 10:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:30 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')]} 10:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:30 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 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:30 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 10:18:30 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 10:18:30 INFO - Using env: {'DISPLAY': ':0', 10:18:30 INFO - 'HOME': '/home/cltbld', 10:18:30 INFO - 'LANG': 'en_US.UTF-8', 10:18:30 INFO - 'LANGUAGE': 'en_US:en', 10:18:30 INFO - 'LOGNAME': 'cltbld', 10:18:30 INFO - 'MAIL': '/var/mail/cltbld', 10:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:30 INFO - 'MOZ_NO_REMOTE': '1', 10:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:30 INFO - 'NO_EM_RESTART': '1', 10:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:30 INFO - 'PWD': '/builds/slave/test', 10:18:30 INFO - 'SHELL': '/bin/bash', 10:18:30 INFO - 'SHLVL': '1', 10:18:30 INFO - 'TERM': 'linux', 10:18:30 INFO - 'TMOUT': '86400', 10:18:30 INFO - 'USER': 'cltbld', 10:18:30 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:30 INFO - '_': '/tools/buildbot/bin/python'} 10:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:30 INFO - Downloading/unpacking psutil>=3.1.1 10:18:30 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 10:18:30 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 10:18:30 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 10:18:30 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 10:18:30 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 10:18:30 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 10:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:18:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:32 INFO - Installing collected packages: psutil 10:18:32 INFO - Running setup.py install for psutil 10:18:32 INFO - building 'psutil._psutil_linux' extension 10:18:32 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 10:18:32 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 10:18:32 INFO - building 'psutil._psutil_posix' extension 10:18:32 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 10:18:32 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 10:18:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:33 INFO - Successfully installed psutil 10:18:33 INFO - Cleaning up... 10:18:36 INFO - Return code: 0 10:18:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:36 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 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:36 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 10:18:36 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 10:18:36 INFO - Using env: {'DISPLAY': ':0', 10:18:36 INFO - 'HOME': '/home/cltbld', 10:18:36 INFO - 'LANG': 'en_US.UTF-8', 10:18:36 INFO - 'LANGUAGE': 'en_US:en', 10:18:36 INFO - 'LOGNAME': 'cltbld', 10:18:36 INFO - 'MAIL': '/var/mail/cltbld', 10:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:36 INFO - 'MOZ_NO_REMOTE': '1', 10:18:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:36 INFO - 'NO_EM_RESTART': '1', 10:18:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:36 INFO - 'PWD': '/builds/slave/test', 10:18:36 INFO - 'SHELL': '/bin/bash', 10:18:36 INFO - 'SHLVL': '1', 10:18:36 INFO - 'TERM': 'linux', 10:18:36 INFO - 'TMOUT': '86400', 10:18:36 INFO - 'USER': 'cltbld', 10:18:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:36 INFO - '_': '/tools/buildbot/bin/python'} 10:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:18:36 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 10:18:36 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 10:18:36 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 10:18:36 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 10:18:36 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 10:18:36 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 10:18:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:18:38 INFO - Installing collected packages: mozsystemmonitor 10:18:38 INFO - Running setup.py install for mozsystemmonitor 10:18:38 INFO - Successfully installed mozsystemmonitor 10:18:38 INFO - Cleaning up... 10:18:38 INFO - Return code: 0 10:18:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:18: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')]} 10:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18: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')]} 10:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:38 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 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:38 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 10:18:38 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 10:18:38 INFO - Using env: {'DISPLAY': ':0', 10:18:38 INFO - 'HOME': '/home/cltbld', 10:18:38 INFO - 'LANG': 'en_US.UTF-8', 10:18:38 INFO - 'LANGUAGE': 'en_US:en', 10:18:38 INFO - 'LOGNAME': 'cltbld', 10:18:38 INFO - 'MAIL': '/var/mail/cltbld', 10:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:38 INFO - 'MOZ_NO_REMOTE': '1', 10:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:38 INFO - 'NO_EM_RESTART': '1', 10:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:38 INFO - 'PWD': '/builds/slave/test', 10:18:38 INFO - 'SHELL': '/bin/bash', 10:18:38 INFO - 'SHLVL': '1', 10:18:38 INFO - 'TERM': 'linux', 10:18:38 INFO - 'TMOUT': '86400', 10:18:38 INFO - 'USER': 'cltbld', 10:18:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:38 INFO - '_': '/tools/buildbot/bin/python'} 10:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:38 INFO - Downloading/unpacking blobuploader==1.2.4 10:18:38 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 10:18:38 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 10:18:38 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 10:18:38 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 10:18:38 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 10:18:38 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 10:18:40 INFO - Downloading blobuploader-1.2.4.tar.gz 10:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:18:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:18:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:18:41 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 10:18:41 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 10:18:41 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 10:18:41 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 10:18:41 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 10:18:41 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 10:18:41 INFO - Downloading docopt-0.6.1.tar.gz 10:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:18:41 INFO - Installing collected packages: blobuploader, requests, docopt 10:18:41 INFO - Running setup.py install for blobuploader 10:18:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:18:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:18:41 INFO - Running setup.py install for requests 10:18:41 INFO - Running setup.py install for docopt 10:18:42 INFO - Successfully installed blobuploader requests docopt 10:18:42 INFO - Cleaning up... 10:18:42 INFO - Return code: 0 10:18:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:42 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 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:42 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 10:18:42 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 10:18:42 INFO - Using env: {'DISPLAY': ':0', 10:18:42 INFO - 'HOME': '/home/cltbld', 10:18:42 INFO - 'LANG': 'en_US.UTF-8', 10:18:42 INFO - 'LANGUAGE': 'en_US:en', 10:18:42 INFO - 'LOGNAME': 'cltbld', 10:18:42 INFO - 'MAIL': '/var/mail/cltbld', 10:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:42 INFO - 'MOZ_NO_REMOTE': '1', 10:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:42 INFO - 'NO_EM_RESTART': '1', 10:18:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:42 INFO - 'PWD': '/builds/slave/test', 10:18:42 INFO - 'SHELL': '/bin/bash', 10:18:42 INFO - 'SHLVL': '1', 10:18:42 INFO - 'TERM': 'linux', 10:18:42 INFO - 'TMOUT': '86400', 10:18:42 INFO - 'USER': 'cltbld', 10:18:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:42 INFO - '_': '/tools/buildbot/bin/python'} 10:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:18:42 INFO - Running setup.py (path:/tmp/pip-i6ILTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:18:42 INFO - Running setup.py (path:/tmp/pip-8bR_k7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:18:42 INFO - Running setup.py (path:/tmp/pip-jxmixI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:18:42 INFO - Running setup.py (path:/tmp/pip-tVg7AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:18:42 INFO - Running setup.py (path:/tmp/pip-aHi7RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:18:42 INFO - Running setup.py (path:/tmp/pip-dSLY5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:18:42 INFO - Running setup.py (path:/tmp/pip-znL8On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:18:42 INFO - Running setup.py (path:/tmp/pip-Gz_sBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:18:42 INFO - Running setup.py (path:/tmp/pip-Zux0Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:18:42 INFO - Running setup.py (path:/tmp/pip-j4diEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:18:43 INFO - Running setup.py (path:/tmp/pip-erTw4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:18:43 INFO - Running setup.py (path:/tmp/pip-k6o1Qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:18:43 INFO - Running setup.py (path:/tmp/pip-kNjbD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:18:43 INFO - Running setup.py (path:/tmp/pip-8tCRxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:43 INFO - Running setup.py (path:/tmp/pip-Tsr8q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:18:43 INFO - Running setup.py (path:/tmp/pip-Rdc132-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:18:43 INFO - Running setup.py (path:/tmp/pip-OFtJN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:18:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:18:43 INFO - Running setup.py install for manifestparser 10:18:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:18:43 INFO - Running setup.py install for mozcrash 10:18:43 INFO - Running setup.py install for mozdebug 10:18:43 INFO - Running setup.py install for mozdevice 10:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:18:43 INFO - Running setup.py install for mozfile 10:18:44 INFO - Running setup.py install for mozhttpd 10:18:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozinfo 10:18:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozInstall 10:18:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozleak 10:18:44 INFO - Running setup.py install for mozlog 10:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for moznetwork 10:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozprocess 10:18:44 INFO - Running setup.py install for mozprofile 10:18:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozrunner 10:18:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:18:44 INFO - Running setup.py install for mozscreenshot 10:18:45 INFO - Running setup.py install for moztest 10:18:45 INFO - Running setup.py install for mozversion 10:18:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:18:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:18:45 INFO - Cleaning up... 10:18:45 INFO - Return code: 0 10:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:45 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')]} 10:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:45 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')]} 10:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:45 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 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:45 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 10:18:45 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 10:18:45 INFO - Using env: {'DISPLAY': ':0', 10:18:45 INFO - 'HOME': '/home/cltbld', 10:18:45 INFO - 'LANG': 'en_US.UTF-8', 10:18:45 INFO - 'LANGUAGE': 'en_US:en', 10:18:45 INFO - 'LOGNAME': 'cltbld', 10:18:45 INFO - 'MAIL': '/var/mail/cltbld', 10:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:45 INFO - 'MOZ_NO_REMOTE': '1', 10:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:45 INFO - 'NO_EM_RESTART': '1', 10:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:45 INFO - 'PWD': '/builds/slave/test', 10:18:45 INFO - 'SHELL': '/bin/bash', 10:18:45 INFO - 'SHLVL': '1', 10:18:45 INFO - 'TERM': 'linux', 10:18:45 INFO - 'TMOUT': '86400', 10:18:45 INFO - 'USER': 'cltbld', 10:18:45 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:45 INFO - '_': '/tools/buildbot/bin/python'} 10:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:18:45 INFO - Running setup.py (path:/tmp/pip-NbJwxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:18:45 INFO - Running setup.py (path:/tmp/pip-2lPEn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:18:45 INFO - Running setup.py (path:/tmp/pip-Ba1c6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:18:45 INFO - Running setup.py (path:/tmp/pip-TneBEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:18:45 INFO - Running setup.py (path:/tmp/pip-wZnqLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:18:45 INFO - Running setup.py (path:/tmp/pip-PVRdC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:18:45 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)) 10:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:18:45 INFO - Running setup.py (path:/tmp/pip-oPqebH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:18:46 INFO - Running setup.py (path:/tmp/pip-OhO1DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:18:46 INFO - Running setup.py (path:/tmp/pip-Z6NrKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:18:46 INFO - Running setup.py (path:/tmp/pip-PWMpSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:18:46 INFO - Running setup.py (path:/tmp/pip-1zqRZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:18:46 INFO - Running setup.py (path:/tmp/pip-FbDEeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:18:46 INFO - Running setup.py (path:/tmp/pip-Miw7QJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:18:46 INFO - Running setup.py (path:/tmp/pip-Jd191g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:46 INFO - Running setup.py (path:/tmp/pip-N_FkGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:18:46 INFO - Running setup.py (path:/tmp/pip-XwYJXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:18:46 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)) 10:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:18:46 INFO - Running setup.py (path:/tmp/pip-RgATXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 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)) 10:18:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:18:46 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 10:18:46 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 10:18:46 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 10:18:46 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 10:18:46 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 10:18:46 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 10:18:48 INFO - Downloading blessings-1.6.tar.gz 10:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:18:48 INFO - Installing collected packages: blessings 10:18:48 INFO - Running setup.py install for blessings 10:18:48 INFO - Successfully installed blessings 10:18:48 INFO - Cleaning up... 10:18:48 INFO - Return code: 0 10:18:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:49 INFO - Reading from file tmpfile_stdout 10:18:49 INFO - Current package versions: 10:18:49 INFO - argparse == 1.2.1 10:18:49 INFO - blessings == 1.6 10:18:49 INFO - blobuploader == 1.2.4 10:18:49 INFO - docopt == 0.6.1 10:18:49 INFO - manifestparser == 1.1 10:18:49 INFO - mozInstall == 1.12 10:18:49 INFO - mozcrash == 0.17 10:18:49 INFO - mozdebug == 0.1 10:18:49 INFO - mozdevice == 0.48 10:18:49 INFO - mozfile == 1.2 10:18:49 INFO - mozhttpd == 0.7 10:18:49 INFO - mozinfo == 0.9 10:18:49 INFO - mozleak == 0.1 10:18:49 INFO - mozlog == 3.2 10:18:49 INFO - moznetwork == 0.27 10:18:49 INFO - mozprocess == 0.22 10:18:49 INFO - mozprofile == 0.28 10:18:49 INFO - mozrunner == 6.11 10:18:49 INFO - mozscreenshot == 0.1 10:18:49 INFO - mozsystemmonitor == 0.3 10:18:49 INFO - moztest == 0.7 10:18:49 INFO - mozversion == 1.4 10:18:49 INFO - psutil == 3.1.1 10:18:49 INFO - requests == 1.2.3 10:18:49 INFO - wsgiref == 0.1.2 10:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 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 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:49 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 10:18:49 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 10:18:49 INFO - Using env: {'DISPLAY': ':0', 10:18:49 INFO - 'HOME': '/home/cltbld', 10:18:49 INFO - 'LANG': 'en_US.UTF-8', 10:18:49 INFO - 'LANGUAGE': 'en_US:en', 10:18:49 INFO - 'LOGNAME': 'cltbld', 10:18:49 INFO - 'MAIL': '/var/mail/cltbld', 10:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:49 INFO - 'MOZ_NO_REMOTE': '1', 10:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:49 INFO - 'NO_EM_RESTART': '1', 10:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:49 INFO - 'PWD': '/builds/slave/test', 10:18:49 INFO - 'SHELL': '/bin/bash', 10:18:49 INFO - 'SHLVL': '1', 10:18:49 INFO - 'TERM': 'linux', 10:18:49 INFO - 'TMOUT': '86400', 10:18:49 INFO - 'USER': 'cltbld', 10:18:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:49 INFO - '_': '/tools/buildbot/bin/python'} 10:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 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)) 10:18:49 INFO - Cleaning up... 10:18:49 INFO - Return code: 0 10:18:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:18:49 INFO - Using env: {'DISPLAY': ':0', 10:18:49 INFO - 'HOME': '/home/cltbld', 10:18:49 INFO - 'LANG': 'en_US.UTF-8', 10:18:49 INFO - 'LANGUAGE': 'en_US:en', 10:18:49 INFO - 'LOGNAME': 'cltbld', 10:18:49 INFO - 'MAIL': '/var/mail/cltbld', 10:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:49 INFO - 'MOZ_NO_REMOTE': '1', 10:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:49 INFO - 'NO_EM_RESTART': '1', 10:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:49 INFO - 'PWD': '/builds/slave/test', 10:18:49 INFO - 'SHELL': '/bin/bash', 10:18:49 INFO - 'SHLVL': '1', 10:18:49 INFO - 'TERM': 'linux', 10:18:49 INFO - 'TMOUT': '86400', 10:18:49 INFO - 'USER': 'cltbld', 10:18:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:49 INFO - '_': '/tools/buildbot/bin/python'} 10:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:49 INFO - Downloading/unpacking jsonschema 10:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:18:51 INFO - Downloading/unpacking functools32 (from jsonschema) 10:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:51 INFO - Downloading functools32-3.2.3-2.tar.gz 10:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:18:51 INFO - warning: no files found matching '*.txt' 10:18:51 INFO - no previously-included directories found matching 'build' 10:18:51 INFO - no previously-included directories found matching 'dist' 10:18:51 INFO - no previously-included directories found matching '.git*' 10:18:51 INFO - Installing collected packages: jsonschema, functools32 10:18:51 INFO - Running setup.py install for functools32 10:18:51 INFO - warning: no files found matching '*.txt' 10:18:51 INFO - no previously-included directories found matching 'build' 10:18:51 INFO - no previously-included directories found matching 'dist' 10:18:51 INFO - no previously-included directories found matching '.git*' 10:18:51 INFO - Successfully installed jsonschema functools32 10:18:51 INFO - Cleaning up... 10:18:51 INFO - Return code: 0 10:18:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1c1934af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, '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': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', '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 10:18:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:18:51 INFO - Using env: {'DISPLAY': ':0', 10:18:51 INFO - 'HOME': '/home/cltbld', 10:18:51 INFO - 'LANG': 'en_US.UTF-8', 10:18:51 INFO - 'LANGUAGE': 'en_US:en', 10:18:51 INFO - 'LOGNAME': 'cltbld', 10:18:51 INFO - 'MAIL': '/var/mail/cltbld', 10:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:51 INFO - 'MOZ_NO_REMOTE': '1', 10:18:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:18:51 INFO - 'NO_EM_RESTART': '1', 10:18:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:51 INFO - 'PWD': '/builds/slave/test', 10:18:51 INFO - 'SHELL': '/bin/bash', 10:18:51 INFO - 'SHLVL': '1', 10:18:51 INFO - 'TERM': 'linux', 10:18:51 INFO - 'TMOUT': '86400', 10:18:51 INFO - 'USER': 'cltbld', 10:18:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:51 INFO - '_': '/tools/buildbot/bin/python'} 10:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:52 INFO - Downloading/unpacking flake8 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:18:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:18:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:18:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:18:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:18:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:18:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:18:54 INFO - no previously-included directories found matching 'docs/_build' 10:18:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:18:54 INFO - Running setup.py install for pep8 10:18:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:18:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:18:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:18:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:18:54 INFO - no previously-included directories found matching 'docs/_build' 10:18:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:18:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:18:54 INFO - Cleaning up... 10:18:54 INFO - Return code: 0 10:18:54 INFO - Running post-action listener: _resource_record_post_action 10:18:54 INFO - Running post-action listener: _start_resource_monitoring 10:18:54 INFO - Starting resource monitoring. 10:18:54 INFO - ##### 10:18:54 INFO - ##### Finished create-virtualenv step (success) 10:18:54 INFO - ##### 10:18:54 INFO - ##### 10:18:54 INFO - ##### Running install step. 10:18:54 INFO - ##### 10:18:54 INFO - Running pre-action listener: _resource_record_pre_action 10:18:54 INFO - Running main action method: install 10:18:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:55 INFO - Reading from file tmpfile_stdout 10:18:55 INFO - Detecting whether we're running mozinstall >=1.0... 10:18:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:18:55 INFO - Reading from file tmpfile_stdout 10:18:55 INFO - Output received: 10:18:55 INFO - Usage: mozinstall [options] installer 10:18:55 INFO - Options: 10:18:55 INFO - -h, --help show this help message and exit 10:18:55 INFO - -d DEST, --destination=DEST 10:18:55 INFO - Directory to install application into. [default: 10:18:55 INFO - "/builds/slave/test"] 10:18:55 INFO - --app=APP Application being installed. [default: firefox] 10:18:55 INFO - mkdir: /builds/slave/test/build/application 10:18:55 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'] 10:18:55 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 10:19:11 INFO - Reading from file tmpfile_stdout 10:19:11 INFO - Output received: 10:19:11 INFO - /builds/slave/test/build/application/firefox/firefox 10:19:11 INFO - Running post-action listener: _resource_record_post_action 10:19:11 INFO - ##### 10:19:11 INFO - ##### Finished install step (success) 10:19:11 INFO - ##### 10:19:11 INFO - ##### 10:19:11 INFO - ##### Running run-tests step. 10:19:11 INFO - ##### 10:19:11 INFO - Running pre-action listener: _resource_record_pre_action 10:19:11 INFO - Running main action method: run_tests 10:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:19:11 INFO - Python 2.7.3 10:19:11 INFO - Return code: 0 10:19:11 INFO - grabbing minidump binary from tooltool 10:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:11 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 0x12729c0>, '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 0x12738c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1273d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:19:11 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 10:19:11 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 10:19:11 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 10:19:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:19:12 INFO - Return code: 0 10:19:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:19:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:19:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:19:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:19:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:19:12 INFO - Return code: 0 10:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:19:12 INFO - Using env: {'DISPLAY': ':0', 10:19:12 INFO - 'HOME': '/home/cltbld', 10:19:12 INFO - 'LANG': 'en_US.UTF-8', 10:19:12 INFO - 'LANGUAGE': 'en_US:en', 10:19:12 INFO - 'LOGNAME': 'cltbld', 10:19:12 INFO - 'MAIL': '/var/mail/cltbld', 10:19:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:19:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:12 INFO - 'MOZ_NO_REMOTE': '1', 10:19:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:12 INFO - 'NO_EM_RESTART': '1', 10:19:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:12 INFO - 'PWD': '/builds/slave/test', 10:19:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:19:12 INFO - 'SHELL': '/bin/bash', 10:19:12 INFO - 'SHLVL': '1', 10:19:12 INFO - 'TERM': 'linux', 10:19:12 INFO - 'TMOUT': '86400', 10:19:12 INFO - 'USER': 'cltbld', 10:19:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046', 10:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:12 INFO - '_': '/tools/buildbot/bin/python'} 10:19:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:19:13 INFO - mozversion application_buildid: 20160530073029 10:19:13 INFO - mozversion application_changeset: 3435dd7ad71fe9003bdeee18fd38d815e033beef 10:19:13 INFO - mozversion application_display_name: Nightly 10:19:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:19:13 INFO - mozversion application_name: Firefox 10:19:13 INFO - mozversion application_remotingname: firefox 10:19:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:19:13 INFO - mozversion application_vendor: Mozilla 10:19:13 INFO - mozversion application_version: 49.0a1 10:19:13 INFO - mozversion platform_buildid: 20160530073029 10:19:13 INFO - mozversion platform_changeset: 3435dd7ad71fe9003bdeee18fd38d815e033beef 10:19:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:19:13 INFO - mozversion platform_version: 49.0a1 10:19:13 INFO - using testdate: 1464628753 10:19:13 INFO - actual date: 1464628753 10:19:13 INFO - starting webserver on 'localhost:47107' 10:19:13 INFO - SUITE-START | Running 2 tests 10:19:13 INFO - TEST-START | dromaeo_css 10:19:13 INFO - operating with platform_type : linux_ 10:19:13 INFO - Initialising browser for dromaeo_css test... 10:19:13 INFO - TEST-INFO | started process 4779 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdXjvLE/profile http://localhost:47107/getInfo.html) 10:19:17 INFO - PROCESS | 4779 | __metrics Screen width/height:1600/1200 10:19:17 INFO - PROCESS | 4779 | colorDepth:24 10:19:17 INFO - PROCESS | 4779 | Browser inner width/height: 1024/697 10:19:17 INFO - PROCESS | 4779 | __metrics 10:19:19 ERROR - PROCESS | 4779 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:19:19 INFO - TEST-INFO | 4779: exit 0 10:19:19 INFO - Browser initialized. 10:19:19 INFO - Running cycle 1/1 for dromaeo_css test... 10:19:19 INFO - TEST-INFO | started process 4862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdXjvLE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:31 INFO - PROCESS | 4862 | RSS: Main: 155987968 10:19:31 INFO - PROCESS | 4862 | 10:20:22 INFO - PROCESS | 4862 | 1464628822855 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] 10:22:31 INFO - PROCESS | 4862 | Cycle 1(1): loaded http://localhost:47107/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47107/tests/dromaeo/cssquery-ext.html) 10:22:32 INFO - PROCESS | 4862 | RSS: Main: 255078400 10:22:32 INFO - PROCESS | 4862 | 10:25:34 INFO - PROCESS | 4862 | Cycle 1(1): loaded http://localhost:47107/tests/dromaeo/cssquery-ext.html (next: http://localhost:47107/tests/dromaeo/cssquery-jquery.html) 10:25:35 INFO - PROCESS | 4862 | RSS: Main: 234311680 10:25:35 INFO - PROCESS | 4862 | 10:28:37 INFO - PROCESS | 4862 | Cycle 1(1): loaded http://localhost:47107/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47107/tests/dromaeo/cssquery-mootools.html) 10:28:37 INFO - PROCESS | 4862 | RSS: Main: 233713664 10:28:37 INFO - PROCESS | 4862 | 10:31:39 INFO - PROCESS | 4862 | Cycle 1(1): loaded http://localhost:47107/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47107/tests/dromaeo/cssquery-prototype.html) 10:31:40 INFO - PROCESS | 4862 | RSS: Main: 243564544 10:31:40 INFO - PROCESS | 4862 | 10:34:43 INFO - PROCESS | 4862 | Cycle 1(1): loaded http://localhost:47107/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47107/tests/dromaeo/cssquery-yui.html) 10:34:43 INFO - PROCESS | 4862 | RSS: Main: 216629248 10:34:43 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | Cycle 1(1): loaded http://localhost:47107/tests/dromaeo/cssquery-yui.html (next: http://localhost:47107/tests/dromaeo/cssquery-dojo.html) 10:37:46 INFO - PROCESS | 4862 | RSS: Main: 273313792 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | __start_tp_report 10:37:46 INFO - PROCESS | 4862 | _x_x_mozilla_page_load 10:37:46 INFO - PROCESS | 4862 | _x_x_mozilla_page_load_details 10:37:46 INFO - PROCESS | 4862 | |i|pagename|runs| 10:37:46 INFO - PROCESS | 4862 | |0;dojo.html;3165.8733650653976;3187.681231876812;3212.6947939945726;3213.9196520087003;3223.6131664200284;5339.733013349336;5415.593830462712;5447.618666693328;5543.223948647187;5544.390117087117;5453.618246722721;5467.097928841737;5506.91739623906;5535.861603459915;5583;4956.578690811276;4973.104841128596;5000.449950505442;5023.673461225027;5158.097332966735;5061.342025536694;5111.591072714193;5119.539241468267;5128.794848206067;5132.204508301201;4864.100141473821;4905.877353066184;4938;4977.90044199114;5003.874903127415;4942.654014219006;4964.230544265644;4984.177610694231;5014.398272207322;5059.544640982329;4964.67729597575;4987.825426110105;4995.050940321327;4999.9500004999845;5041.395032596112;5278.815241466568;5348.786048558053;5414.16080507518;5480.013597552439;5482.095454250044;5876.324222714357;5877.030290002144;5904.409559044104;5936.436038576328;5937.940620593825;5697.344805347397;5980.700964951735;5984.371640977678;5985.221921150222;5988.251468566429;5431.375391829951;5483.369412517532;5491.945080549223;5578.721063946868;5579.972100139555;6034.973727609008;6037.061844161678;6058.792430998138;6092.3603021682975;6097.939020609737;5536.197251398606;5749.108888122348;5836.182934389189;5841.386654401312;5849.122631605208;5852.122181672698;5890.4698577128265;5904.232449781501;5909.172715819789;5952.880942381128;5811.244538210006;5846.181534585162;5852.707364631837;5853.824385268364;5857.619254748428;5580.39476162628;5774.162746401832;5825.970870145622;5871.442212989759;5875.794347197913;4384.517703052662;4386.956130438719;4390.582894625006;4428;4431.180231657154;3920.1179734559496;3936.4095385691803;3937.330653788863;3963.128111815396;3971.146203566247;5491.066518691832;5540.307461567304;5543.251661025791;5563.693996830213;5633.6619802811965;5894.734736936848;5899.410058994066;5912.46787789101;5920.31916329619;5960.791372301948;5595.944040559542;5602.467765562265;5604.355499117572;5604.915926261109;5605.915911261334;4865.537773911473;4998.700077995332;5015.523525265167;5018.899622007612;5035.269885866602;6137.754489820447;6139.416755408229;6144.416280453439;6163.476104531165;6167.629942203482;5897.970510147422;5911.793087241926;5919.200907877382;5932.673702946379;5937.376575459514;5944.910826337522;6083.7870674526175;6090.75683550213;6126.540509461897;6186.783462578788;5763.135529670583;5848.883022339614;5856.648601083949;5865.94134058654;5894.440028197314;5627.831165065055;5963.194968679346;5980.461758441761;5982.730777115127;5986.191864098291;5783.248177736868;5832.591718579828;5850.180974663465;5878.030125029322;5885.646861188171;5833.241678581757;5838.32859221178;5845.327787304344;5846.093855452412;5846.649201047951;5429.945700542944;5562.3047119110115;5751.654900705971;5758.222639943554;5760.366359700346;5750.396208398058;5816.127580862904;5836.883262334644;5854.94145058561;5857.941420585739;5667.149927510742;5668.943310566841;5669.262995810518;5673.120666296731;5674.375818660027 10:37:46 INFO - PROCESS | 4862 | |1;ext.html;609.999600654926;646.5183438338437;648.3192647719893;653.7744478155038;660.2209392916358;33372;36829.81585092074;36871.631283687224;36948.076298092565;43154.35268470975;45084.323735144;45695.31457028139;45728.771356143254;45837.54162458375;45841.54158458423;8601.397902146853;8759.51822649754;8768.517731524764;8781.253593444533;8781.429207101519;8655.437396569203;8767.123287671246;8791.868121978176;8792.120787921189;8799.692010779623;8783.253423459013;8828.558572071403;8834.42576232546;8850.291976641853;8861.202491775739;8773.385862989593;8803.339749518747;8805.339599530062;8808.691695790681;8810.471371717686;8737.563121843883;8821.25019373354;8822.735317940473;8823.294136469067;8825.955870220672;41316.1736765263;41698.58301417008;41732.58267417348;41744;41746;3429.742769292313;3613.259281847215;3657.3233951719017;3851.961480385188;3875.476810630557;32413.351732965446;32459.18852028718;32683.18292042694;32866.342673146406;34244.6575534244;4258.978705106486;4267.91464170718;4344.131173765228;4425.579569940883;4446.488653804821;342875;344239;344240;344438.9999999975;346163;56317.43682563204;56808;56962.71518642463;56992;57033.42966570281;61752.38247617556;61976;62067;62094.37905620886;62195;15773.44792932242;16236;16312.67374652524;16703.248353824107;16753.497395077924;12289.139760216878;12379.257244565355;12389.256644601353;12391.132620716644;12394.256344619353;19384.39370978538;19453.22855284755;19538.12097401431;19570.54877652856;19624.116914738872;7156.463265255126;7214.891776623354;7242.311980361857;7418.925810741932;7437.665305061285;996.850472429127;1046.4401545173448;1081.7403823082404;1083.3445765311874;1084.3060441317564;32294.677053229167;32470.675293247237;32554.18614534608;32809.3438131236;33331.83334083314;1025.261811495722;1071.4856868703066;1107.4960892793758;1117.100733909211;1184.1769969870857;16185.757213641804;16570.420035298706;16720.6655866882;16749.162541872858;16790.578393048378;261756;262105;262208;262208;547195;58946.70526647339;59196.70401647964;59228.40771592315;59465.70267148723;59470;1237.8824011718873;1347.9326033698471;1364.4269406849148;1387.7571425000785;1395.3790563199482;29551.635869129987;33518.659253630554;33674.158146046546;33708.157296067795;33830.49254261139;50494.2425863605;50657.74671126768;50817.745911270205;51008.234876477625;51069.74465127651;412003;414405;415714;418700;421187;24134.637980429958;27713;45282.773586133175;45338.546614533436;45545.772271138434;44345.77827110844;49920.50079499304;50321.49678503168;50348.74825625995;51009.744951275;40688;42549.78725106355;42858.785706072515;43011.784941076345;44151.558484414745;40320.596794031684;42538.361924570534;43427.8861401843;43865.78067109644;44805.77597112124;42712.57287427086;43370.13259734725;43518.12963740644;43652;43946.34080488859;35133.82433087818;37448.251034979694;37975.810120949216;38495.80752096222;38690.613093869826;28923.7107628921;28972.855135724185;29037.564436533892;29190;29310.560341594468 10:37:46 INFO - PROCESS | 4862 | |2;jquery.html;399.8960270329715;405.5680700054444;406.82506522195456;407.39095052895925;423.6991735867534;13852.307384630752;16670.249838757256;16918.154092295397;18277.908610456947;18328.266869325227;15231.6953660927;17741.201645925932;18520.444386668387;18969.62060758784;19677.901610491965;5612.887742245163;5785.450382213683;5819.650820950736;5822.679752613604;5833.212516310307;4181.560936101711;5679.772809087632;5767.480926716595;6033.306169790487;6394.072859435379;6382.648954307511;6450.129232553592;6463.159789227394;6470.935290647104;6496.805095847132;6349.42855143037;6388.456981156607;6482.416582507573;6495.123158373606;6558.540902136852;5567.693776842272;5758.337791153987;5781.913271300934;5786.363500015037;5837.6497410155525;22842.428939276484;22857.54284914309;23150.884245578833;23692.170774022827;24168.516629667483;2239.731232252124;2404.446977195237;2423.6728041714496;2509.849409035464;2518.357818756224;14179.716405671932;14446.566603001927;14449.494267700686;14993.775093373608;15230.086194828345;1913.4929243750416;1965.2335589120255;1992.7708313543837;2001.409584172658;2004.6992951057312;81255;82661.17338826595;83783.58108209481;84370;84559;10755.354678719303;10789.730256743487;10906.945465272624;11168;11216.327020378803;15302.540923772305;15307.387704491919;15388;15393.307301171475;15416.537503874903;7801.960990195126;7944.285014348737;8013.8397232056195;8144.511329320259;8165.550894700729;6998.895016574756;7463.962680186564;7469.9253007470315;7620.695172193161;7658.73194438192;6428.903566446507;6757.459403247827;6788.864222715518;6823.829404264834;6919.550229235084;1868.719692046204;1927.255550307016;1955.8826470411536;1982.9405117846488;2015.7177995080701;981.7741919358444;987.7086259553449;1049.4018409506507;1049.7585555322319;1056.181459368995;18948.31550534834;19342.4197274082;19487.90256048739;19505.414837554898;19516.12177452018;982.0866594067586;982.7101005203482;1068.5939343049592;1071.410724101741;1090.095220966596;10593.735156620993;11000.779984400428;11001.559937602406;11008.229423940407;11011.394373309384;450903;451549;455988;461913;464343;88949.55525222332;89588;90621.54689226512;90811.09188908026;92303;1004.4174378860243;1009.5204777730667;1021.560728886571;1022.8210063239052;1031.8142734307746;16737.414190503274;16948.40680576174;16973.151342433077;17232.74150887763;17545.385911493035;29665.851670741507;33892.49161262633;36307.45538816867;38542.42186367263;39980.40029399503;49547.33246826773;53219;55175;55601.721991389786;58630.706846465495;7959;8242.793930151794;8277.917220827956;8449.535275559903;8451.323894088335;12006.63980080599;12197.451114700038;12288.1398302118;12477.5008999639;12586.622401327975;10631.840522392326;10815.837762433412;11082.667519974413;11359.375234361858;11419.086473081967;8980.563109902645;9643.710688679352;10750.408727520062;10818.781915556108;11368.147388945697;11196.94401527987;11316.6039188628;11554.76890462203;11603.709907252387;11713.648590542298;8202.794930126794;8370.121137280497;8456.450330728483;8481.703140390056;8615.526146062026;8519.914800851911;10151.492425378849;10218.948905255425;10382.688519344432;10559 10:37:46 INFO - PROCESS | 4862 | |3;mootools.html;1317.1241124652106;1463.7145756577465;1520.0043971198866;1667.9583010424744;1838.9816101838978;8548.187922147394;8783.209511144003;8825.911740882597;8854.114588541142;9112.271018318535;8589.91410085899;8734.38859279849;9047.502387368691;9069.682561110363;9069.90930090699;2112.218479162712;2168.6421740412893;2169.566086782642;2355.6584295277216;2421.055788242587;2413.4449076712367;2479.752024797515;2483.279848843833;2504.9373765655823;2514.2080244722947;2371.608684567053;2382.297222319411;2383.4756353602265;2390.3547090686666;2415.10641062807;2084.72898523191;2362.2558893948353;2368.348704106376;2388.4267775733874;2398.70016247969;2391.940201494959;2432.5742994975985;2434.440078781872;2435.9147429839873;2438.500107477963;8702.956485217597;8744.431611945203;8768.079351668046;8784.648614055493;8793.164649358301;5440.619156659035;5570.275864137636;5574.665520068809;5575.330960284751;5583.776648934037;3344.5150453184397;3403.897883063512;3507.4563442666426;3547.7516573839844;3552.3250582389264;1172.2614752705827;1172.5544293168628;1189.9405029748477;1191.52934590837;1203.422357268516;12803;13019.934900325437;13059.934700326532;13082.607521774362;13098.934505327412;4892.975535122373;4979.3277907482025;5058.317127187783;5064.797408103708;5086.186210206423;10965.725856853483;11018.779624407467;11060.446977651247;11084.556617735201;11092.722681933015;5647.745851436613;5656.236408084938;5660.094384898396;5660.801871934463;5666.291713535808;4394.604485596312;4668.836590719308;5051.898962020739;5123.129068058439;5179.067767801756;4199.139176468817;4278.7860606969525;4293.205756934977;4318.373835793793;4327.32926396409;1278.9232646041266;1342.1007924690286;1356.3353956561411;1358.6671265540006;1359.4154513559067;863.4128792421213;972.3776782858976;978.9461579613047;991.5092029445326;1018.1345856022322;5975.910361344583;6015.969920150371;6088.208532890696;6138.508919286446;6142.477889379364;474.37382654895896;485.1001392417016;489.05368112702035;489.53249646587426;498.03381440006274;4117.732347397409;4158.750474971512;4179.9791001045405;4190.28765109932;4254.234237837157;11163.72090697739;11344.829827552594;11397.544098235978;11403.144764142722;11463.369514676606;10984.670459886056;11312.321260724382;11326.377049262204;11388.772224555629;11389.829152562717;8514.74455766328;8638.95680521606;8648.1784230498;8660.48037117775;8668.349873759493;6078.635281883101;6285.748570057147;6313.305536390902;6362.809115726535;6368.936310636835;10520.10579100785;10616.8938310619;10853.186011049362;10886.836697449702;10913.727156821144;10969.835452468378;11160.776784464102;11162.330260184415;11184.21710480291;11236.494357754083;7368.189499155196;7531.133919599316;7632.3512501438;8077.798055048671;8150.347972162332;8718.564071796513;9228.95385523068;9250.537473126451;9251.953740231256;9252.907470925205;7904.407169462428;7953.204679532;8038.155993620586;8191.79520511992;8312.709055183039;8013.559254241072;8025.518468891885;8039.437239393186;8082.151374105634;8223.09545949933;7224.313690199423;7233.3851622612665;7254.709811607477;7314.158871729819;7364.447666425147;5194.662346947436;5250.396204436386;5380.784768609212;5421.566274698093;5462.453754624665;5352.545033672026;5408.296921400351;5427.674339539641;5561.054620714569;5570.359408668055 10:37:46 INFO - PROCESS | 4862 | |4;prototype.html;328.3076271349624;335.8085891042106;342.8063144323456;345.2594185472164;345.35418766905883;5019.573336266412;5123.897522049561;5130.461301563338;5160.483951604842;5186;5094.694318340902;5121.615878809085;5192.558632516231;5204.687718736878;5218.243354713564;4089.89775255618;4123.628873401393;4128.649064829478;4133.5659755725665;4140.730852494593;3899.161680238748;3972.423998520213;4060.4518390017265;4078.938815917764;4118.6499147572495;4015.437838702584;4040.919181616366;4048.5141782985934;4052.5542190359038;4055.574164712707;4059.269331520325;4074.816633251481;4091.89770255743;4095.1809638072355;4104.630583247507;3402.1494626343415;3534.929301413957;3542.7954495471404;3636.327279453283;3647.325244829715;4988.401391832967;5008.574271186917;5032.572231360339;5044.9495505045215;5051.898962020739;4637.466691330506;4637.86086417408;4646.558576935186;4668.731887678605;4672.345871577982;5231.927454871742;5404.32445944257;5426.158945363475;5428.6742795432;5468.699221542814;3948.2893079245723;3960.980195099035;3965.2466031453932;3965.7422267552515;3968.6229808168177;5549.77800887964;5701.6864072476;5714.171445140473;5724.082432385156;5735.369109397963;3958.7228893977144;3978.562358140602;3983.880483585497;3985.322495175827;3989.982554448597;3995.320795464778;4036.29364861154;4042.555318914917;4043.353063509883;4043.838246470108;4592.724436533752;4625.560571745679;4628.236341003764;4637.420322459692;4641.303804429363;5250.501202385767;5480.232767412565;5482.930828488486;5518.503334699896;5534.584906132056;1381.3369582600408;1402.2077526197666;1417.340936464549;1420.2117824607235;1422.6727852593754;863.9654413823503;900.9234215091659;918.4076270805293;925.3938670171049;936.5176933879058;4709.693869898446;4738.905221895543;4743.932615161561;4783.880402989953;4819.927701084486;5031.892983543615;5093.242831223222;5109.74811171258;5111.952049829776;5113.258577506241;4725.40932383452;4751.667383283137;4784.832530861403;4795.664303498722;4884.926726099111;2961.600183975179;2996.310848504856;2996.9850150749107;2997.4754417976765;3003.4232028184942;5784.479396854304;5790.160426738184;5791.855203619859;5824.970875145597;5831.504322132581;5548.778048878;5737.282839645044;5758.74085666146;5765.509931655773;5784.797532086357;5793.652380857162;5841.795537156179;5845.503132233808;5865.765369385262;5867.941320586825;5418.780774325745;5578.884223155304;5595.692236926845;5641.830745077655;5661.2923384576925;5555.36120342834;5575.944240557705;5582.0789569722165;5582.692951887685;5606.719664016864;5526.944730552643;5584.497395234448;5821.76712931478;5825.184474173704;5836.387179346278;4024.69814763888;4024.8390064397095;4038.3548775552063;4038.65671417921;4052.7163098582814;5606.971965140148;5620.690862002629;5624.606277560531;5666.433356664301;5680;5293.38551741707;5470.699111548903;5475.890482190413;5492.642978206404;5499;5435.2934118564335;5448.945510544844;5457.508824205841;5470.64440811346;5475.72621368938;5311.336082989626;5332.733363331896;5349.1976203569775;5377;5385.165299378603;5174.922376164285;5481.260029896072;5510.641808282609;5542.919130769623;5543.307086614173;5360.463953604608;5360.758765855468;5361.678299302054;5368;5385.192221166856 10:37:46 INFO - PROCESS | 4862 | |5;yui.html;775.3874439193038;789.120131053875;812.236497692169;821.700079470993;856.36628894618;14697.26513674316;14831.184284864341;15895.999999999985;16348.918255408706;16384.180790960465;15631.687366252669;15649.217539123027;15671.373145074213;15697.529074127766;15707.057576545387;823.5305875650896;826.2316046077145;828.200786241277;830.7050996896105;831.2601784411848;693.7814588404661;807.8545861744913;825.5624519004937;827.1067247372853;832.317499650287;865.3899001204136;866.4324867211986;868.405142477405;868.6655637579546;869.217704066339;716.2837162837163;866.7659731872391;866.8049688820028;866.9436486628412;867.2324992381724;610.5817515002237;619.1610367951406;620.1689735754067;624.2696045626627;625.2621906150786;14519.564413067625;14582.854171458255;14665.413383464755;14676.486322978752;14684.118952862756;12986.415611297514;13519;13609.795853062213;13690.863091369158;13839.584812455643;11527.654170374819;11685.182037257395;11832.467538960767;11984.64046078619;12076.516939322417;3830.5977872323283;3874.612538746103;3894.6689531389866;3905.082305658168;4041.2725709372303;57947.710261448425;59999.20463696682;60486;60486.395136048515;60746.392536074076;10559.788804224027;10574.841377379345;10686.786264274826;10721.91009838122;11048.613298534512;19437;19540;19597.608047838963;19625;19654.90172549128;8478.788030299293;8583.785405364792;8597;8763.91236087631;8898.510581918057;4816.373871396696;4874.171390863562;4882.780274887638;4937.407511098691;4966.354373931438;3470.3406352793395;3588.820558972041;3590.8563657453933;3592.084018575299;3622.9275414491563;683.217715715503;684.0491716514049;692.8856738638169;712.6864179760888;714.5141303913285;705.971761129549;711.5232794028013;746.518495570365;769.4729110559285;786.5084322298563;9607.57110296298;9894.77662409983;9963.868607667056;10077.798444031225;10223.87320455671;587.7678317064735;691.9067872761026;722.8590424867206;726.785598248518;757.3713817531436;4525;4776.737279449663;4793.448753393334;4810.6632535722165;4821.975890120527;60491;62266.377336226964;62490.687546561974;65692;66732;32517.4230834608;52298.47701523012;59634.70182649059;60469.395306046375;61215;49839;50672;51497.48502514927;55159.72420137873;58270;9682.661106861226;9816.460094694861;9981.550830212513;10034.849477257701;10106.797864042532;36648.63351366452;36725.63274367329;36873.63126368702;37612;38135.809320953216;45885.77057114693;50652;50870;51016;51600;12422.254664720145;12479;12555.623331300076;12603.18079324841;13289.069765116348;13408.664783380493;13659.931700341434;13687.589372318846;13967.30163491801;14056.297185140498;13051.412686428943;13117.803232951323;13514.324283785574;13535.932320338336;13966.720665586428;13507.257100859155;13610.455581776618;13644.454221831413;13654.142231478603;13746.450141994208;13217.603471895858;13323.86676133265;13509.324533773075;13528.661783455493;13920.58238252854;10646.148308135176;10912;11104.5558177672;11126.332420054823;11389.829152562552;10744.247902646739;11006.889931100586;11077.279976801483;11081.73353258985;11197.066184304884 10:37:46 INFO - PROCESS | 4862 | __end_tp_report 10:37:46 INFO - PROCESS | 4862 | __start_cc_report 10:37:46 INFO - PROCESS | 4862 | _x_x_mozilla_cycle_collect,910 10:37:46 INFO - PROCESS | 4862 | __end_cc_report 10:37:46 INFO - PROCESS | 4862 | __startTimestamp1464629866627__endTimestamp 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | ------- Summary: start ------- 10:37:46 INFO - PROCESS | 4862 | Number of tests: 6 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | [#0] dojo.html Cycles:165 Average:5469.45 Median:5669.26 stddev:638.87 (11.3%) stddev-sans-first:614.74 10:37:46 INFO - PROCESS | 4862 | Values: 3165.9 3187.7 3212.7 3213.9 3223.6 5339.7 5415.6 5447.6 5543.2 5544.4 5453.6 5467.1 5506.9 5535.9 5583.0 4956.6 4973.1 5000.4 5023.7 5158.1 5061.3 5111.6 5119.5 5128.8 5132.2 4864.1 4905.9 4938.0 4977.9 5003.9 4942.7 4964.2 4984.2 5014.4 5059.5 4964.7 4987.8 4995.1 5000.0 5041.4 5278.8 5348.8 5414.2 5480.0 5482.1 5876.3 5877.0 5904.4 5936.4 5937.9 5697.3 5980.7 5984.4 5985.2 5988.3 5431.4 5483.4 5491.9 5578.7 5580.0 6035.0 6037.1 6058.8 6092.4 6097.9 5536.2 5749.1 5836.2 5841.4 5849.1 5852.1 5890.5 5904.2 5909.2 5952.9 5811.2 5846.2 5852.7 5853.8 5857.6 5580.4 5774.2 5826.0 5871.4 5875.8 4384.5 4387.0 4390.6 4428.0 4431.2 3920.1 3936.4 3937.3 3963.1 3971.1 5491.1 5540.3 5543.3 5563.7 5633.7 5894.7 5899.4 5912.5 5920.3 5960.8 5595.9 5602.5 5604.4 5604.9 5605.9 4865.5 4998.7 5015.5 5018.9 5035.3 6137.8 6139.4 6144.4 6163.5 6167.6 5898.0 5911.8 5919.2 5932.7 5937.4 5944.9 6083.8 6090.8 6126.5 6186.8 5763.1 5848.9 5856.6 5865.9 5894.4 5627.8 5963.2 5980.5 5982.7 5986.2 5783.2 5832.6 5850.2 5878.0 5885.6 5833.2 5838.3 5845.3 5846.1 5846.6 5429.9 5562.3 5751.7 5758.2 5760.4 5750.4 5816.1 5836.9 5854.9 5857.9 5667.1 5668.9 5669.3 5673.1 5674.4 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | [#1] ext.html Cycles:180 Average:53965.82 Median:231862.68 stddev:97012.32 (41.8%) stddev-sans-first:97201.75 10:37:46 INFO - PROCESS | 4862 | Values: 610.0 646.5 648.3 653.8 660.2 33372.0 36829.8 36871.6 36948.1 43154.4 45084.3 45695.3 45728.8 45837.5 45841.5 8601.4 8759.5 8768.5 8781.3 8781.4 8655.4 8767.1 8791.9 8792.1 8799.7 8783.3 8828.6 8834.4 8850.3 8861.2 8773.4 8803.3 8805.3 8808.7 8810.5 8737.6 8821.3 8822.7 8823.3 8826.0 41316.2 41698.6 41732.6 41744.0 41746.0 3429.7 3613.3 3657.3 3852.0 3875.5 32413.4 32459.2 32683.2 32866.3 34244.7 4259.0 4267.9 4344.1 4425.6 4446.5 342875.0 344239.0 344240.0 344439.0 346163.0 56317.4 56808.0 56962.7 56992.0 57033.4 61752.4 61976.0 62067.0 62094.4 62195.0 15773.4 16236.0 16312.7 16703.2 16753.5 12289.1 12379.3 12389.3 12391.1 12394.3 19384.4 19453.2 19538.1 19570.5 19624.1 7156.5 7214.9 7242.3 7418.9 7437.7 996.9 1046.4 1081.7 1083.3 1084.3 32294.7 32470.7 32554.2 32809.3 33331.8 1025.3 1071.5 1107.5 1117.1 1184.2 16185.8 16570.4 16720.7 16749.2 16790.6 261756.0 262105.0 262208.0 262208.0 547195.0 58946.7 59196.7 59228.4 59465.7 59470.0 1237.9 1347.9 1364.4 1387.8 1395.4 29551.6 33518.7 33674.2 33708.2 33830.5 50494.2 50657.7 50817.7 51008.2 51069.7 412003.0 414405.0 415714.0 418700.0 421187.0 24134.6 27713.0 45282.8 45338.5 45545.8 44345.8 49920.5 50321.5 50348.7 51009.7 40688.0 42549.8 42858.8 43011.8 44151.6 40320.6 42538.4 43427.9 43865.8 44805.8 42712.6 43370.1 43518.1 43652.0 43946.3 35133.8 37448.3 37975.8 38495.8 38690.6 28923.7 28972.9 29037.6 29190.0 29310.6 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | [#2] jquery.html Cycles:180 Average:27813.26 Median:2466.76 stddev:75485.71 (3060.1%) stddev-sans-first:75669.40 10:37:46 INFO - PROCESS | 4862 | Values: 399.9 405.6 406.8 407.4 423.7 13852.3 16670.2 16918.2 18277.9 18328.3 15231.7 17741.2 18520.4 18969.6 19677.9 5612.9 5785.5 5819.7 5822.7 5833.2 4181.6 5679.8 5767.5 6033.3 6394.1 6382.6 6450.1 6463.2 6470.9 6496.8 6349.4 6388.5 6482.4 6495.1 6558.5 5567.7 5758.3 5781.9 5786.4 5837.6 22842.4 22857.5 23150.9 23692.2 24168.5 2239.7 2404.4 2423.7 2509.8 2518.4 14179.7 14446.6 14449.5 14993.8 15230.1 1913.5 1965.2 1992.8 2001.4 2004.7 81255.0 82661.2 83783.6 84370.0 84559.0 10755.4 10789.7 10906.9 11168.0 11216.3 15302.5 15307.4 15388.0 15393.3 15416.5 7802.0 7944.3 8013.8 8144.5 8165.6 6998.9 7464.0 7469.9 7620.7 7658.7 6428.9 6757.5 6788.9 6823.8 6919.6 1868.7 1927.3 1955.9 1982.9 2015.7 981.8 987.7 1049.4 1049.8 1056.2 18948.3 19342.4 19487.9 19505.4 19516.1 982.1 982.7 1068.6 1071.4 1090.1 10593.7 11000.8 11001.6 11008.2 11011.4 450903.0 451549.0 455988.0 461913.0 464343.0 88949.6 89588.0 90621.5 90811.1 92303.0 1004.4 1009.5 1021.6 1022.8 1031.8 16737.4 16948.4 16973.2 17232.7 17545.4 29665.9 33892.5 36307.5 38542.4 39980.4 49547.3 53219.0 55175.0 55601.7 58630.7 7959.0 8242.8 8277.9 8449.5 8451.3 12006.6 12197.5 12288.1 12477.5 12586.6 10631.8 10815.8 11082.7 11359.4 11419.1 8980.6 9643.7 10750.4 10818.8 11368.1 11196.9 11316.6 11554.8 11603.7 11713.6 8202.8 8370.1 8456.5 8481.7 8615.5 8519.9 10151.5 10218.9 10382.7 10559.0 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | [#3] mootools.html Cycles:180 Average:6045.37 Median:489.29 stddev:3493.92 (714.1%) stddev-sans-first:3485.65 10:37:46 INFO - PROCESS | 4862 | Values: 1317.1 1463.7 1520.0 1668.0 1839.0 8548.2 8783.2 8825.9 8854.1 9112.3 8589.9 8734.4 9047.5 9069.7 9069.9 2112.2 2168.6 2169.6 2355.7 2421.1 2413.4 2479.8 2483.3 2504.9 2514.2 2371.6 2382.3 2383.5 2390.4 2415.1 2084.7 2362.3 2368.3 2388.4 2398.7 2391.9 2432.6 2434.4 2435.9 2438.5 8703.0 8744.4 8768.1 8784.6 8793.2 5440.6 5570.3 5574.7 5575.3 5583.8 3344.5 3403.9 3507.5 3547.8 3552.3 1172.3 1172.6 1189.9 1191.5 1203.4 12803.0 13019.9 13059.9 13082.6 13098.9 4893.0 4979.3 5058.3 5064.8 5086.2 10965.7 11018.8 11060.4 11084.6 11092.7 5647.7 5656.2 5660.1 5660.8 5666.3 4394.6 4668.8 5051.9 5123.1 5179.1 4199.1 4278.8 4293.2 4318.4 4327.3 1278.9 1342.1 1356.3 1358.7 1359.4 863.4 972.4 978.9 991.5 1018.1 5975.9 6016.0 6088.2 6138.5 6142.5 474.4 485.1 489.1 489.5 498.0 4117.7 4158.8 4180.0 4190.3 4254.2 11163.7 11344.8 11397.5 11403.1 11463.4 10984.7 11312.3 11326.4 11388.8 11389.8 8514.7 8639.0 8648.2 8660.5 8668.3 6078.6 6285.7 6313.3 6362.8 6368.9 10520.1 10616.9 10853.2 10886.8 10913.7 10969.8 11160.8 11162.3 11184.2 11236.5 7368.2 7531.1 7632.4 8077.8 8150.3 8718.6 9229.0 9250.5 9252.0 9252.9 7904.4 7953.2 8038.2 8191.8 8312.7 8013.6 8025.5 8039.4 8082.2 8223.1 7224.3 7233.4 7254.7 7314.2 7364.4 5194.7 5250.4 5380.8 5421.6 5462.5 5352.5 5408.3 5427.7 5561.1 5570.4 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | [#4] prototype.html Cycles:180 Average:4561.35 Median:5112.61 stddev:1334.06 (26.1%) stddev-sans-first:1299.42 10:37:46 INFO - PROCESS | 4862 | Values: 328.3 335.8 342.8 345.3 345.4 5019.6 5123.9 5130.5 5160.5 5186.0 5094.7 5121.6 5192.6 5204.7 5218.2 4089.9 4123.6 4128.6 4133.6 4140.7 3899.2 3972.4 4060.5 4078.9 4118.6 4015.4 4040.9 4048.5 4052.6 4055.6 4059.3 4074.8 4091.9 4095.2 4104.6 3402.1 3534.9 3542.8 3636.3 3647.3 4988.4 5008.6 5032.6 5044.9 5051.9 4637.5 4637.9 4646.6 4668.7 4672.3 5231.9 5404.3 5426.2 5428.7 5468.7 3948.3 3961.0 3965.2 3965.7 3968.6 5549.8 5701.7 5714.2 5724.1 5735.4 3958.7 3978.6 3983.9 3985.3 3990.0 3995.3 4036.3 4042.6 4043.4 4043.8 4592.7 4625.6 4628.2 4637.4 4641.3 5250.5 5480.2 5482.9 5518.5 5534.6 1381.3 1402.2 1417.3 1420.2 1422.7 864.0 900.9 918.4 925.4 936.5 4709.7 4738.9 4743.9 4783.9 4819.9 5031.9 5093.2 5109.7 5112.0 5113.3 4725.4 4751.7 4784.8 4795.7 4884.9 2961.6 2996.3 2997.0 2997.5 3003.4 5784.5 5790.2 5791.9 5825.0 5831.5 5548.8 5737.3 5758.7 5765.5 5784.8 5793.7 5841.8 5845.5 5865.8 5867.9 5418.8 5578.9 5595.7 5641.8 5661.3 5555.4 5575.9 5582.1 5582.7 5606.7 5526.9 5584.5 5821.8 5825.2 5836.4 4024.7 4024.8 4038.4 4038.7 4052.7 5607.0 5620.7 5624.6 5666.4 5680.0 5293.4 5470.7 5475.9 5492.6 5499.0 5435.3 5448.9 5457.5 5470.6 5475.7 5311.3 5332.7 5349.2 5377.0 5385.2 5174.9 5481.3 5510.6 5542.9 5543.3 5360.5 5360.8 5361.7 5368.0 5385.2 10:37:46 INFO - PROCESS | 4862 | 10:37:46 INFO - PROCESS | 4862 | [#5] yui.html Cycles:180 Average:15560.34 Median:25205.39 stddev:18005.48 (71.4%) stddev-sans-first:18021.75 10:37:46 INFO - PROCESS | 4862 | Values: 775.4 789.1 812.2 821.7 856.4 14697.3 14831.2 15896.0 16348.9 16384.2 15631.7 15649.2 15671.4 15697.5 15707.1 823.5 826.2 828.2 830.7 831.3 693.8 807.9 825.6 827.1 832.3 865.4 866.4 868.4 868.7 869.2 716.3 866.8 866.8 866.9 867.2 610.6 619.2 620.2 624.3 625.3 14519.6 14582.9 14665.4 14676.5 14684.1 12986.4 13519.0 13609.8 13690.9 13839.6 11527.7 11685.2 11832.5 11984.6 12076.5 3830.6 3874.6 3894.7 3905.1 4041.3 57947.7 59999.2 60486.0 60486.4 60746.4 10559.8 10574.8 10686.8 10721.9 11048.6 19437.0 19540.0 19597.6 19625.0 19654.9 8478.8 8583.8 8597.0 8763.9 8898.5 4816.4 4874.2 4882.8 4937.4 4966.4 3470.3 3588.8 3590.9 3592.1 3622.9 683.2 684.0 692.9 712.7 714.5 706.0 711.5 746.5 769.5 786.5 9607.6 9894.8 9963.9 10077.8 10223.9 587.8 691.9 722.9 726.8 757.4 4525.0 4776.7 4793.4 4810.7 4822.0 60491.0 62266.4 62490.7 65692.0 66732.0 32517.4 52298.5 59634.7 60469.4 61215.0 49839.0 50672.0 51497.5 55159.7 58270.0 9682.7 9816.5 9981.6 10034.8 10106.8 36648.6 36725.6 36873.6 37612.0 38135.8 45885.8 50652.0 50870.0 51016.0 51600.0 12422.3 12479.0 12555.6 12603.2 13289.1 13408.7 13659.9 13687.6 13967.3 14056.3 13051.4 13117.8 13514.3 13535.9 13966.7 13507.3 13610.5 13644.5 13654.1 13746.5 13217.6 13323.9 13509.3 13528.7 13920.6 10646.1 10912.0 11104.6 11126.3 11389.8 10744.2 11006.9 11077.3 11081.7 11197.1 10:37:46 INFO - PROCESS | 4862 | -------- Summary: end -------- 10:37:46 INFO - PROCESS | 4862 | 10:37:47 INFO - TEST-INFO | 4862: exit 0 10:37:47 INFO - TEST-OK | dromaeo_css | took 1114235ms 10:37:47 INFO - TEST-START | kraken 10:37:47 INFO - operating with platform_type : linux_ 10:37:47 INFO - Initialising browser for kraken test... 10:37:47 INFO - TEST-INFO | started process 5043 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLtUpXX/profile http://localhost:47107/getInfo.html) 10:37:51 INFO - PROCESS | 5043 | __metrics Screen width/height:1600/1200 10:37:51 INFO - PROCESS | 5043 | colorDepth:24 10:37:51 INFO - PROCESS | 5043 | Browser inner width/height: 1024/697 10:37:51 INFO - PROCESS | 5043 | __metrics 10:37:53 ERROR - PROCESS | 5043 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:37:54 INFO - TEST-INFO | 5043: exit 0 10:37:54 INFO - Browser initialized. 10:37:54 INFO - Running cycle 1/1 for kraken test... 10:37:54 INFO - TEST-INFO | started process 5127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLtUpXX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:38:05 INFO - PROCESS | 5127 | RSS: Main: 155234304 10:38:05 INFO - PROCESS | 5127 | 10:38:08 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47107/tests/kraken/driver.html?testName=audio-beat-detection) 10:38:09 INFO - PROCESS | 5127 | RSS: Main: 214294528 10:38:09 INFO - PROCESS | 5127 | 10:38:11 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47107/tests/kraken/driver.html?testName=audio-dft) 10:38:12 INFO - PROCESS | 5127 | RSS: Main: 197857280 10:38:12 INFO - PROCESS | 5127 | 10:38:15 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47107/tests/kraken/driver.html?testName=audio-fft) 10:38:16 INFO - PROCESS | 5127 | RSS: Main: 268460032 10:38:16 INFO - PROCESS | 5127 | 10:38:18 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47107/tests/kraken/driver.html?testName=audio-oscillator) 10:38:18 INFO - PROCESS | 5127 | RSS: Main: 179396608 10:38:18 INFO - PROCESS | 5127 | 10:38:21 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47107/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:38:21 INFO - PROCESS | 5127 | RSS: Main: 184025088 10:38:21 INFO - PROCESS | 5127 | 10:38:24 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47107/tests/kraken/driver.html?testName=imaging-darkroom) 10:38:25 INFO - PROCESS | 5127 | RSS: Main: 219848704 10:38:25 INFO - PROCESS | 5127 | 10:38:28 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47107/tests/kraken/driver.html?testName=imaging-desaturate) 10:38:29 INFO - PROCESS | 5127 | RSS: Main: 219877376 10:38:29 INFO - PROCESS | 5127 | 10:38:32 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47107/tests/kraken/driver.html?testName=json-parse-financial) 10:38:33 INFO - PROCESS | 5127 | RSS: Main: 220237824 10:38:33 INFO - PROCESS | 5127 | 10:38:34 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47107/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:38:35 INFO - PROCESS | 5127 | RSS: Main: 208961536 10:38:35 INFO - PROCESS | 5127 | 10:38:36 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:38:37 INFO - PROCESS | 5127 | RSS: Main: 210042880 10:38:37 INFO - PROCESS | 5127 | 10:38:39 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:38:40 INFO - PROCESS | 5127 | RSS: Main: 216379392 10:38:40 INFO - PROCESS | 5127 | 10:38:42 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:38:43 INFO - PROCESS | 5127 | RSS: Main: 224595968 10:38:43 INFO - PROCESS | 5127 | 10:38:46 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:38:46 INFO - PROCESS | 5127 | RSS: Main: 223813632 10:38:46 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | Cycle 1(1): loaded http://localhost:47107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47107/tests/kraken/driver.html?testName=ai-astar) 10:38:48 INFO - PROCESS | 5127 | RSS: Main: 266518528 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | __start_tp_report 10:38:48 INFO - PROCESS | 5127 | _x_x_mozilla_page_load 10:38:48 INFO - PROCESS | 5127 | _x_x_mozilla_page_load_details 10:38:48 INFO - PROCESS | 5127 | |i|pagename|runs| 10:38:48 INFO - PROCESS | 5127 | |0;ai-astar;111;106;111;112;110;109;109;110;109;109 10:38:48 INFO - PROCESS | 5127 | |1;audio-beat-detection;157;123;129;173;129;124;163;122;172;135 10:38:48 INFO - PROCESS | 5127 | |2;audio-dft;165;160;159;160;158;160;160;161;160;162 10:38:48 INFO - PROCESS | 5127 | |3;audio-fft;73;74;76;129;76;77;75;74;108;74 10:38:48 INFO - PROCESS | 5127 | |4;audio-oscillator;82;82;118;83;82;84;126;85;84;135 10:38:48 INFO - PROCESS | 5127 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;116;117 10:38:48 INFO - PROCESS | 5127 | |6;imaging-darkroom;145;145;147;145;146;146;146;145;145;145 10:38:48 INFO - PROCESS | 5127 | |7;imaging-desaturate;96;94;95;95;95;95;96;95;95;95 10:38:48 INFO - PROCESS | 5127 | |8;json-parse-financial;54;56;56;55;56;54;56;55;56;55 10:38:48 INFO - PROCESS | 5127 | |9;json-stringify-tinderbox;64;64;64;64;65;64;64;64;64;64 10:38:48 INFO - PROCESS | 5127 | |10;stanford-crypto-aes;79;79;78;79;79;81;80;80;112;80 10:38:48 INFO - PROCESS | 5127 | |11;stanford-crypto-ccm;128;124;119;119;130;120;118;132;137;122 10:38:48 INFO - PROCESS | 5127 | |12;stanford-crypto-pbkdf2;160;161;161;161;166;158;167;162;159;158 10:38:48 INFO - PROCESS | 5127 | |13;stanford-crypto-sha256-iterative;63;63;64;65;62;63;62;62;63;60 10:38:48 INFO - PROCESS | 5127 | __end_tp_report 10:38:48 INFO - PROCESS | 5127 | __start_cc_report 10:38:48 INFO - PROCESS | 5127 | _x_x_mozilla_cycle_collect,1640 10:38:48 INFO - PROCESS | 5127 | __end_cc_report 10:38:48 INFO - PROCESS | 5127 | __startTimestamp1464629928644__endTimestamp 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | ------- Summary: start ------- 10:38:48 INFO - PROCESS | 5127 | Number of tests: 14 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:1.65 (1.5%) stddev-sans-first:1.67 10:38:48 INFO - PROCESS | 5127 | Values: 111.0 106.0 111.0 112.0 110.0 109.0 109.0 110.0 109.0 109.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:146.00 stddev:21.07 (14.4%) stddev-sans-first:21.70 10:38:48 INFO - PROCESS | 5127 | Values: 157.0 123.0 129.0 173.0 129.0 124.0 163.0 122.0 172.0 135.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.00 stddev:1.90 (1.2%) stddev-sans-first:1.12 10:38:48 INFO - PROCESS | 5127 | Values: 165.0 160.0 159.0 160.0 158.0 160.0 160.0 161.0 160.0 162.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#3] audio-fft Cycles:10 Average:83.60 Median:74.50 stddev:19.09 (25.6%) stddev-sans-first:19.85 10:38:48 INFO - PROCESS | 5127 | Values: 73.0 74.0 76.0 129.0 76.0 77.0 75.0 74.0 108.0 74.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:82.50 stddev:21.27 (25.8%) stddev-sans-first:21.94 10:38:48 INFO - PROCESS | 5127 | Values: 82.0 82.0 118.0 83.0 82.0 84.0 126.0 85.0 84.0 135.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 10:38:48 INFO - PROCESS | 5127 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 10:38:48 INFO - PROCESS | 5127 | Values: 145.0 145.0 147.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 10:38:48 INFO - PROCESS | 5127 | Values: 96.0 94.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:0.82 (1.5%) stddev-sans-first:0.73 10:38:48 INFO - PROCESS | 5127 | Values: 54.0 56.0 56.0 55.0 56.0 54.0 56.0 55.0 56.0 55.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 10:38:48 INFO - PROCESS | 5127 | Values: 64.0 64.0 64.0 64.0 65.0 64.0 64.0 64.0 64.0 64.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:79.50 stddev:10.33 (13.0%) stddev-sans-first:10.87 10:38:48 INFO - PROCESS | 5127 | Values: 79.0 79.0 78.0 79.0 79.0 81.0 80.0 80.0 112.0 80.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:126.00 stddev:6.52 (5.2%) stddev-sans-first:6.82 10:38:48 INFO - PROCESS | 5127 | Values: 128.0 124.0 119.0 119.0 130.0 120.0 118.0 132.0 137.0 122.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:161.00 stddev:3.06 (1.9%) stddev-sans-first:3.21 10:38:48 INFO - PROCESS | 5127 | Values: 160.0 161.0 161.0 161.0 166.0 158.0 167.0 162.0 159.0 158.0 10:38:48 INFO - PROCESS | 5127 | 10:38:48 INFO - PROCESS | 5127 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.34 (2.1%) stddev-sans-first:1.41 10:38:48 INFO - PROCESS | 5127 | Values: 63.0 63.0 64.0 65.0 62.0 63.0 62.0 62.0 63.0 60.0 10:38:48 INFO - PROCESS | 5127 | -------- Summary: end -------- 10:38:48 INFO - PROCESS | 5127 | 10:38:49 INFO - TEST-INFO | 5127: exit 0 10:38:49 INFO - TEST-OK | kraken | took 62313ms 10:38:49 INFO - SUITE-END | took 1176s 10:38:49 INFO - Completed test suite (00:19:36) 10:38:49 INFO - javascript benchmark 10:38:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3165.8733650653976, 3187.681231876812, 3212.6947939945726, 3213.9196520087003, 3223.6131664200284, 5339.733013349336, 5415.593830462712, 5447.618666693328, 5543.223948647187, 5544.390117087117, 5453.618246722721, 5467.097928841737, 5506.91739623906, 5535.861603459915, 5583.0, 4956.578690811276, 4973.104841128596, 5000.449950505442, 5023.673461225027, 5158.097332966735, 5061.342025536694, 5111.591072714193, 5119.539241468267, 5128.794848206067, 5132.204508301201, 4864.100141473821, 4905.877353066184, 4938.0, 4977.90044199114, 5003.874903127415, 4942.654014219006, 4964.230544265644, 4984.177610694231, 5014.398272207322, 5059.544640982329, 4964.67729597575, 4987.825426110105, 4995.050940321327, 4999.9500004999845, 5041.395032596112, 5278.815241466568, 5348.786048558053, 5414.16080507518, 5480.013597552439, 5482.095454250044, 5876.324222714357, 5877.030290002144, 5904.409559044104, 5936.436038576328, 5937.940620593825, 5697.344805347397, 5980.700964951735, 5984.371640977678, 5985.221921150222, 5988.251468566429, 5431.375391829951, 5483.369412517532, 5491.945080549223, 5578.721063946868, 5579.972100139555, 6034.973727609008, 6037.061844161678, 6058.792430998138, 6092.3603021682975, 6097.939020609737, 5536.197251398606, 5749.108888122348, 5836.182934389189, 5841.386654401312, 5849.122631605208, 5852.122181672698, 5890.4698577128265, 5904.232449781501, 5909.172715819789, 5952.880942381128, 5811.244538210006, 5846.181534585162, 5852.707364631837, 5853.824385268364, 5857.619254748428, 5580.39476162628, 5774.162746401832, 5825.970870145622, 5871.442212989759, 5875.794347197913, 4384.517703052662, 4386.956130438719, 4390.582894625006, 4428.0, 4431.180231657154, 3920.1179734559496, 3936.4095385691803, 3937.330653788863, 3963.128111815396, 3971.146203566247, 5491.066518691832, 5540.307461567304, 5543.251661025791, 5563.693996830213, 5633.6619802811965, 5894.734736936848, 5899.410058994066, 5912.46787789101, 5920.31916329619, 5960.791372301948, 5595.944040559542, 5602.467765562265, 5604.355499117572, 5604.915926261109, 5605.915911261334, 4865.537773911473, 4998.700077995332, 5015.523525265167, 5018.899622007612, 5035.269885866602, 6137.754489820447, 6139.416755408229, 6144.416280453439, 6163.476104531165, 6167.629942203482, 5897.970510147422, 5911.793087241926, 5919.200907877382, 5932.673702946379, 5937.376575459514, 5944.910826337522, 6083.7870674526175, 6090.75683550213, 6126.540509461897, 6186.783462578788, 5763.135529670583, 5848.883022339614, 5856.648601083949, 5865.94134058654, 5894.440028197314, 5627.831165065055, 5963.194968679346, 5980.461758441761, 5982.730777115127, 5986.191864098291, 5783.248177736868, 5832.591718579828, 5850.180974663465, 5878.030125029322, 5885.646861188171, 5833.241678581757, 5838.32859221178, 5845.327787304344, 5846.093855452412, 5846.649201047951, 5429.945700542944, 5562.3047119110115, 5751.654900705971, 5758.222639943554, 5760.366359700346, 5750.396208398058, 5816.127580862904, 5836.883262334644, 5854.94145058561, 5857.941420585739, 5667.149927510742, 5668.943310566841, 5669.262995810518, 5673.120666296731, 5674.375818660027], "value": 5425.40766273108, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.999600654926, 646.5183438338437, 648.3192647719893, 653.7744478155038, 660.2209392916358, 33372.0, 36829.81585092074, 36871.631283687224, 36948.076298092565, 43154.35268470975, 45084.323735144, 45695.31457028139, 45728.771356143254, 45837.54162458375, 45841.54158458423, 8601.397902146853, 8759.51822649754, 8768.517731524764, 8781.253593444533, 8781.429207101519, 8655.437396569203, 8767.123287671246, 8791.868121978176, 8792.120787921189, 8799.692010779623, 8783.253423459013, 8828.558572071403, 8834.42576232546, 8850.291976641853, 8861.202491775739, 8773.385862989593, 8803.339749518747, 8805.339599530062, 8808.691695790681, 8810.471371717686, 8737.563121843883, 8821.25019373354, 8822.735317940473, 8823.294136469067, 8825.955870220672, 41316.1736765263, 41698.58301417008, 41732.58267417348, 41744.0, 41746.0, 3429.742769292313, 3613.259281847215, 3657.3233951719017, 3851.961480385188, 3875.476810630557, 32413.351732965446, 32459.18852028718, 32683.18292042694, 32866.342673146406, 34244.6575534244, 4258.978705106486, 4267.91464170718, 4344.131173765228, 4425.579569940883, 4446.488653804821, 342875.0, 344239.0, 344240.0, 344438.9999999975, 346163.0, 56317.43682563204, 56808.0, 56962.71518642463, 56992.0, 57033.42966570281, 61752.38247617556, 61976.0, 62067.0, 62094.37905620886, 62195.0, 15773.44792932242, 16236.0, 16312.67374652524, 16703.248353824107, 16753.497395077924, 12289.139760216878, 12379.257244565355, 12389.256644601353, 12391.132620716644, 12394.256344619353, 19384.39370978538, 19453.22855284755, 19538.12097401431, 19570.54877652856, 19624.116914738872, 7156.463265255126, 7214.891776623354, 7242.311980361857, 7418.925810741932, 7437.665305061285, 996.850472429127, 1046.4401545173448, 1081.7403823082404, 1083.3445765311874, 1084.3060441317564, 32294.677053229167, 32470.675293247237, 32554.18614534608, 32809.3438131236, 33331.83334083314, 1025.261811495722, 1071.4856868703066, 1107.4960892793758, 1117.100733909211, 1184.1769969870857, 16185.757213641804, 16570.420035298706, 16720.6655866882, 16749.162541872858, 16790.578393048378, 261756.0, 262105.0, 262208.0, 262208.0, 547195.0, 58946.70526647339, 59196.70401647964, 59228.40771592315, 59465.70267148723, 59470.0, 1237.8824011718873, 1347.9326033698471, 1364.4269406849148, 1387.7571425000785, 1395.3790563199482, 29551.635869129987, 33518.659253630554, 33674.158146046546, 33708.157296067795, 33830.49254261139, 50494.2425863605, 50657.74671126768, 50817.745911270205, 51008.234876477625, 51069.74465127651, 412003.0, 414405.0, 415714.0, 418700.0, 421187.0, 24134.637980429958, 27713.0, 45282.773586133175, 45338.546614533436, 45545.772271138434, 44345.77827110844, 49920.50079499304, 50321.49678503168, 50348.74825625995, 51009.744951275, 40688.0, 42549.78725106355, 42858.785706072515, 43011.784941076345, 44151.558484414745, 40320.596794031684, 42538.361924570534, 43427.8861401843, 43865.78067109644, 44805.77597112124, 42712.57287427086, 43370.13259734725, 43518.12963740644, 43652.0, 43946.34080488859, 35133.82433087818, 37448.251034979694, 37975.810120949216, 38495.80752096222, 38690.613093869826, 28923.7107628921, 28972.855135724185, 29037.564436533892, 29190.0, 29310.560341594468], "value": 20085.4869648726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.8960270329715, 405.5680700054444, 406.82506522195456, 407.39095052895925, 423.6991735867534, 13852.307384630752, 16670.249838757256, 16918.154092295397, 18277.908610456947, 18328.266869325227, 15231.6953660927, 17741.201645925932, 18520.444386668387, 18969.62060758784, 19677.901610491965, 5612.887742245163, 5785.450382213683, 5819.650820950736, 5822.679752613604, 5833.212516310307, 4181.560936101711, 5679.772809087632, 5767.480926716595, 6033.306169790487, 6394.072859435379, 6382.648954307511, 6450.129232553592, 6463.159789227394, 6470.935290647104, 6496.805095847132, 6349.42855143037, 6388.456981156607, 6482.416582507573, 6495.123158373606, 6558.540902136852, 5567.693776842272, 5758.337791153987, 5781.913271300934, 5786.363500015037, 5837.6497410155525, 22842.428939276484, 22857.54284914309, 23150.884245578833, 23692.170774022827, 24168.516629667483, 2239.731232252124, 2404.446977195237, 2423.6728041714496, 2509.849409035464, 2518.357818756224, 14179.716405671932, 14446.566603001927, 14449.494267700686, 14993.775093373608, 15230.086194828345, 1913.4929243750416, 1965.2335589120255, 1992.7708313543837, 2001.409584172658, 2004.6992951057312, 81255.0, 82661.17338826595, 83783.58108209481, 84370.0, 84559.0, 10755.354678719303, 10789.730256743487, 10906.945465272624, 11168.0, 11216.327020378803, 15302.540923772305, 15307.387704491919, 15388.0, 15393.307301171475, 15416.537503874903, 7801.960990195126, 7944.285014348737, 8013.8397232056195, 8144.511329320259, 8165.550894700729, 6998.895016574756, 7463.962680186564, 7469.9253007470315, 7620.695172193161, 7658.73194438192, 6428.903566446507, 6757.459403247827, 6788.864222715518, 6823.829404264834, 6919.550229235084, 1868.719692046204, 1927.255550307016, 1955.8826470411536, 1982.9405117846488, 2015.7177995080701, 981.7741919358444, 987.7086259553449, 1049.4018409506507, 1049.7585555322319, 1056.181459368995, 18948.31550534834, 19342.4197274082, 19487.90256048739, 19505.414837554898, 19516.12177452018, 982.0866594067586, 982.7101005203482, 1068.5939343049592, 1071.410724101741, 1090.095220966596, 10593.735156620993, 11000.779984400428, 11001.559937602406, 11008.229423940407, 11011.394373309384, 450903.0, 451549.0, 455988.0, 461913.0, 464343.0, 88949.55525222332, 89588.0, 90621.54689226512, 90811.09188908026, 92303.0, 1004.4174378860243, 1009.5204777730667, 1021.560728886571, 1022.8210063239052, 1031.8142734307746, 16737.414190503274, 16948.40680576174, 16973.151342433077, 17232.74150887763, 17545.385911493035, 29665.851670741507, 33892.49161262633, 36307.45538816867, 38542.42186367263, 39980.40029399503, 49547.33246826773, 53219.0, 55175.0, 55601.721991389786, 58630.706846465495, 7959.0, 8242.793930151794, 8277.917220827956, 8449.535275559903, 8451.323894088335, 12006.63980080599, 12197.451114700038, 12288.1398302118, 12477.5008999639, 12586.622401327975, 10631.840522392326, 10815.837762433412, 11082.667519974413, 11359.375234361858, 11419.086473081967, 8980.563109902645, 9643.710688679352, 10750.408727520062, 10818.781915556108, 11368.147388945697, 11196.94401527987, 11316.6039188628, 11554.76890462203, 11603.709907252387, 11713.648590542298, 8202.794930126794, 8370.121137280497, 8456.450330728483, 8481.703140390056, 8615.526146062026, 8519.914800851911, 10151.492425378849, 10218.948905255425, 10382.688519344432, 10559.0], "value": 9253.602401128815, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.1241124652106, 1463.7145756577465, 1520.0043971198866, 1667.9583010424744, 1838.9816101838978, 8548.187922147394, 8783.209511144003, 8825.911740882597, 8854.114588541142, 9112.271018318535, 8589.91410085899, 8734.38859279849, 9047.502387368691, 9069.682561110363, 9069.90930090699, 2112.218479162712, 2168.6421740412893, 2169.566086782642, 2355.6584295277216, 2421.055788242587, 2413.4449076712367, 2479.752024797515, 2483.279848843833, 2504.9373765655823, 2514.2080244722947, 2371.608684567053, 2382.297222319411, 2383.4756353602265, 2390.3547090686666, 2415.10641062807, 2084.72898523191, 2362.2558893948353, 2368.348704106376, 2388.4267775733874, 2398.70016247969, 2391.940201494959, 2432.5742994975985, 2434.440078781872, 2435.9147429839873, 2438.500107477963, 8702.956485217597, 8744.431611945203, 8768.079351668046, 8784.648614055493, 8793.164649358301, 5440.619156659035, 5570.275864137636, 5574.665520068809, 5575.330960284751, 5583.776648934037, 3344.5150453184397, 3403.897883063512, 3507.4563442666426, 3547.7516573839844, 3552.3250582389264, 1172.2614752705827, 1172.5544293168628, 1189.9405029748477, 1191.52934590837, 1203.422357268516, 12803.0, 13019.934900325437, 13059.934700326532, 13082.607521774362, 13098.934505327412, 4892.975535122373, 4979.3277907482025, 5058.317127187783, 5064.797408103708, 5086.186210206423, 10965.725856853483, 11018.779624407467, 11060.446977651247, 11084.556617735201, 11092.722681933015, 5647.745851436613, 5656.236408084938, 5660.094384898396, 5660.801871934463, 5666.291713535808, 4394.604485596312, 4668.836590719308, 5051.898962020739, 5123.129068058439, 5179.067767801756, 4199.139176468817, 4278.7860606969525, 4293.205756934977, 4318.373835793793, 4327.32926396409, 1278.9232646041266, 1342.1007924690286, 1356.3353956561411, 1358.6671265540006, 1359.4154513559067, 863.4128792421213, 972.3776782858976, 978.9461579613047, 991.5092029445326, 1018.1345856022322, 5975.910361344583, 6015.969920150371, 6088.208532890696, 6138.508919286446, 6142.477889379364, 474.37382654895896, 485.1001392417016, 489.05368112702035, 489.53249646587426, 498.03381440006274, 4117.732347397409, 4158.750474971512, 4179.9791001045405, 4190.28765109932, 4254.234237837157, 11163.72090697739, 11344.829827552594, 11397.544098235978, 11403.144764142722, 11463.369514676606, 10984.670459886056, 11312.321260724382, 11326.377049262204, 11388.772224555629, 11389.829152562717, 8514.74455766328, 8638.95680521606, 8648.1784230498, 8660.48037117775, 8668.349873759493, 6078.635281883101, 6285.748570057147, 6313.305536390902, 6362.809115726535, 6368.936310636835, 10520.10579100785, 10616.8938310619, 10853.186011049362, 10886.836697449702, 10913.727156821144, 10969.835452468378, 11160.776784464102, 11162.330260184415, 11184.21710480291, 11236.494357754083, 7368.189499155196, 7531.133919599316, 7632.3512501438, 8077.798055048671, 8150.347972162332, 8718.564071796513, 9228.95385523068, 9250.537473126451, 9251.953740231256, 9252.907470925205, 7904.407169462428, 7953.204679532, 8038.155993620586, 8191.79520511992, 8312.709055183039, 8013.559254241072, 8025.518468891885, 8039.437239393186, 8082.151374105634, 8223.09545949933, 7224.313690199423, 7233.3851622612665, 7254.709811607477, 7314.158871729819, 7364.447666425147, 5194.662346947436, 5250.396204436386, 5380.784768609212, 5421.566274698093, 5462.453754624665, 5352.545033672026, 5408.296921400351, 5427.674339539641, 5561.054620714569, 5570.359408668055], "value": 4735.42121693724, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.3076271349624, 335.8085891042106, 342.8063144323456, 345.2594185472164, 345.35418766905883, 5019.573336266412, 5123.897522049561, 5130.461301563338, 5160.483951604842, 5186.0, 5094.694318340902, 5121.615878809085, 5192.558632516231, 5204.687718736878, 5218.243354713564, 4089.89775255618, 4123.628873401393, 4128.649064829478, 4133.5659755725665, 4140.730852494593, 3899.161680238748, 3972.423998520213, 4060.4518390017265, 4078.938815917764, 4118.6499147572495, 4015.437838702584, 4040.919181616366, 4048.5141782985934, 4052.5542190359038, 4055.574164712707, 4059.269331520325, 4074.816633251481, 4091.89770255743, 4095.1809638072355, 4104.630583247507, 3402.1494626343415, 3534.929301413957, 3542.7954495471404, 3636.327279453283, 3647.325244829715, 4988.401391832967, 5008.574271186917, 5032.572231360339, 5044.9495505045215, 5051.898962020739, 4637.466691330506, 4637.86086417408, 4646.558576935186, 4668.731887678605, 4672.345871577982, 5231.927454871742, 5404.32445944257, 5426.158945363475, 5428.6742795432, 5468.699221542814, 3948.2893079245723, 3960.980195099035, 3965.2466031453932, 3965.7422267552515, 3968.6229808168177, 5549.77800887964, 5701.6864072476, 5714.171445140473, 5724.082432385156, 5735.369109397963, 3958.7228893977144, 3978.562358140602, 3983.880483585497, 3985.322495175827, 3989.982554448597, 3995.320795464778, 4036.29364861154, 4042.555318914917, 4043.353063509883, 4043.838246470108, 4592.724436533752, 4625.560571745679, 4628.236341003764, 4637.420322459692, 4641.303804429363, 5250.501202385767, 5480.232767412565, 5482.930828488486, 5518.503334699896, 5534.584906132056, 1381.3369582600408, 1402.2077526197666, 1417.340936464549, 1420.2117824607235, 1422.6727852593754, 863.9654413823503, 900.9234215091659, 918.4076270805293, 925.3938670171049, 936.5176933879058, 4709.693869898446, 4738.905221895543, 4743.932615161561, 4783.880402989953, 4819.927701084486, 5031.892983543615, 5093.242831223222, 5109.74811171258, 5111.952049829776, 5113.258577506241, 4725.40932383452, 4751.667383283137, 4784.832530861403, 4795.664303498722, 4884.926726099111, 2961.600183975179, 2996.310848504856, 2996.9850150749107, 2997.4754417976765, 3003.4232028184942, 5784.479396854304, 5790.160426738184, 5791.855203619859, 5824.970875145597, 5831.504322132581, 5548.778048878, 5737.282839645044, 5758.74085666146, 5765.509931655773, 5784.797532086357, 5793.652380857162, 5841.795537156179, 5845.503132233808, 5865.765369385262, 5867.941320586825, 5418.780774325745, 5578.884223155304, 5595.692236926845, 5641.830745077655, 5661.2923384576925, 5555.36120342834, 5575.944240557705, 5582.0789569722165, 5582.692951887685, 5606.719664016864, 5526.944730552643, 5584.497395234448, 5821.76712931478, 5825.184474173704, 5836.387179346278, 4024.69814763888, 4024.8390064397095, 4038.3548775552063, 4038.65671417921, 4052.7163098582814, 5606.971965140148, 5620.690862002629, 5624.606277560531, 5666.433356664301, 5680.0, 5293.38551741707, 5470.699111548903, 5475.890482190413, 5492.642978206404, 5499.0, 5435.2934118564335, 5448.945510544844, 5457.508824205841, 5470.64440811346, 5475.72621368938, 5311.336082989626, 5332.733363331896, 5349.1976203569775, 5377.0, 5385.165299378603, 5174.922376164285, 5481.260029896072, 5510.641808282609, 5542.919130769623, 5543.307086614173, 5360.463953604608, 5360.758765855468, 5361.678299302054, 5368.0, 5385.192221166856], "value": 4140.783013797112, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.3874439193038, 789.120131053875, 812.236497692169, 821.700079470993, 856.36628894618, 14697.26513674316, 14831.184284864341, 15895.999999999985, 16348.918255408706, 16384.180790960465, 15631.687366252669, 15649.217539123027, 15671.373145074213, 15697.529074127766, 15707.057576545387, 823.5305875650896, 826.2316046077145, 828.200786241277, 830.7050996896105, 831.2601784411848, 693.7814588404661, 807.8545861744913, 825.5624519004937, 827.1067247372853, 832.317499650287, 865.3899001204136, 866.4324867211986, 868.405142477405, 868.6655637579546, 869.217704066339, 716.2837162837163, 866.7659731872391, 866.8049688820028, 866.9436486628412, 867.2324992381724, 610.5817515002237, 619.1610367951406, 620.1689735754067, 624.2696045626627, 625.2621906150786, 14519.564413067625, 14582.854171458255, 14665.413383464755, 14676.486322978752, 14684.118952862756, 12986.415611297514, 13519.0, 13609.795853062213, 13690.863091369158, 13839.584812455643, 11527.654170374819, 11685.182037257395, 11832.467538960767, 11984.64046078619, 12076.516939322417, 3830.5977872323283, 3874.612538746103, 3894.6689531389866, 3905.082305658168, 4041.2725709372303, 57947.710261448425, 59999.20463696682, 60486.0, 60486.395136048515, 60746.392536074076, 10559.788804224027, 10574.841377379345, 10686.786264274826, 10721.91009838122, 11048.613298534512, 19437.0, 19540.0, 19597.608047838963, 19625.0, 19654.90172549128, 8478.788030299293, 8583.785405364792, 8597.0, 8763.91236087631, 8898.510581918057, 4816.373871396696, 4874.171390863562, 4882.780274887638, 4937.407511098691, 4966.354373931438, 3470.3406352793395, 3588.820558972041, 3590.8563657453933, 3592.084018575299, 3622.9275414491563, 683.217715715503, 684.0491716514049, 692.8856738638169, 712.6864179760888, 714.5141303913285, 705.971761129549, 711.5232794028013, 746.518495570365, 769.4729110559285, 786.5084322298563, 9607.57110296298, 9894.77662409983, 9963.868607667056, 10077.798444031225, 10223.87320455671, 587.7678317064735, 691.9067872761026, 722.8590424867206, 726.785598248518, 757.3713817531436, 4525.0, 4776.737279449663, 4793.448753393334, 4810.6632535722165, 4821.975890120527, 60491.0, 62266.377336226964, 62490.687546561974, 65692.0, 66732.0, 32517.4230834608, 52298.47701523012, 59634.70182649059, 60469.395306046375, 61215.0, 49839.0, 50672.0, 51497.48502514927, 55159.72420137873, 58270.0, 9682.661106861226, 9816.460094694861, 9981.550830212513, 10034.849477257701, 10106.797864042532, 36648.63351366452, 36725.63274367329, 36873.63126368702, 37612.0, 38135.809320953216, 45885.77057114693, 50652.0, 50870.0, 51016.0, 51600.0, 12422.254664720145, 12479.0, 12555.623331300076, 12603.18079324841, 13289.069765116348, 13408.664783380493, 13659.931700341434, 13687.589372318846, 13967.30163491801, 14056.297185140498, 13051.412686428943, 13117.803232951323, 13514.324283785574, 13535.932320338336, 13966.720665586428, 13507.257100859155, 13610.455581776618, 13644.454221831413, 13654.142231478603, 13746.450141994208, 13217.603471895858, 13323.86676133265, 13509.324533773075, 13528.661783455493, 13920.58238252854, 10646.148308135176, 10912.0, 11104.5558177672, 11126.332420054823, 11389.829152562552, 10744.247902646739, 11006.889931100586, 11077.279976801483, 11081.73353258985, 11197.066184304884], "value": 7029.308179098901, "unit": "score"}], "name": "dromaeo_css", "value": 7197.318060890732, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 111.0, 112.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 129.0, 173.0, 129.0, 124.0, 163.0, 122.0, 172.0, 135.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 160.0, 159.0, 160.0, 158.0, 160.0, 160.0, 161.0, 160.0, 162.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 76.0, 129.0, 76.0, 77.0, 75.0, 74.0, 108.0, 74.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 118.0, 83.0, 82.0, 84.0, 126.0, 85.0, 84.0, 135.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 56.0, 55.0, 56.0, 54.0, 56.0, 55.0, 56.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 79.0, 79.0, 81.0, 80.0, 80.0, 112.0, 80.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 124.0, 119.0, 119.0, 130.0, 120.0, 118.0, 132.0, 137.0, 122.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 166.0, 158.0, 167.0, 162.0, 159.0, 158.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 65.0, 62.0, 63.0, 62.0, 62.0, 63.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1500.9, "alertThreshold": 2.0}]} 10:38:49 INFO - Return code: 0 10:38:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:38:49 ERROR - # TBPL SUCCESS # 10:38:49 INFO - Running post-action listener: _resource_record_post_action 10:38:49 INFO - ##### 10:38:49 INFO - ##### Finished run-tests step (success) 10:38:49 INFO - ##### 10:38:49 INFO - Running post-run listener: _resource_record_post_run 10:38:50 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 196608; Write bytes: 303321088; Read time: 40; Write time: 512268 10:38:50 INFO - TinderboxPrint: CPU usage
13.2% 10:38:50 INFO - TinderboxPrint: I/O read bytes / time
196,608 / 40 10:38:50 INFO - TinderboxPrint: I/O write bytes / time
303,321,088 / 512,268 10:38:50 INFO - TinderboxPrint: CPU idle
8,272.7 (86.8%) 10:38:50 INFO - TinderboxPrint: CPU user
1,207.5 (12.7%) 10:38:50 INFO - TinderboxPrint: Swap in / out
0 / 0 10:38:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 17477632; Read time: 0; Write time: 4668 10:38:50 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 196608; Write bytes: 285843456; Read time: 40; Write time: 507600 10:38:50 INFO - Running post-run listener: _upload_blobber_files 10:38:50 INFO - Blob upload gear active. 10:38:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:38:50 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 10:38:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:38:50 INFO - (blobuploader) - INFO - Open directory for files ... 10:38:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:51 INFO - (blobuploader) - INFO - Done attempting. 10:38:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:38:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:55 INFO - (blobuploader) - INFO - Done attempting. 10:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:38:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:56 INFO - (blobuploader) - INFO - Done attempting. 10:38:56 INFO - (blobuploader) - INFO - Iteration through files over. 10:38:56 INFO - Return code: 0 10:38:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:38:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:38:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e42e13c195641b645aa33847b4a0842619c054606fd193c290585bd5c1ca3bdaf47ad25141402975296334645de4876bf0c36388121b9dcb912f26ff2254dbb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcb364b2c99c105aaa3d9bc4e5bfdb322c54701d0d3292992039a02e16a3b9eb257a61ccac482c61f3a0c0483df9a360d55490109a7325f452e1e673ab4040d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924b0491849a5d435fb0f1d1aa59dbee4db82b1d928e129cb5dee35f6aea3262b57df33975a6d15242de2e9e7deb82f04c8506c1e0b75c3f639535138ba753df"} 10:38:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:38:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:38:56 INFO - Contents: 10:38:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e42e13c195641b645aa33847b4a0842619c054606fd193c290585bd5c1ca3bdaf47ad25141402975296334645de4876bf0c36388121b9dcb912f26ff2254dbb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcb364b2c99c105aaa3d9bc4e5bfdb322c54701d0d3292992039a02e16a3b9eb257a61ccac482c61f3a0c0483df9a360d55490109a7325f452e1e673ab4040d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924b0491849a5d435fb0f1d1aa59dbee4db82b1d928e129cb5dee35f6aea3262b57df33975a6d15242de2e9e7deb82f04c8506c1e0b75c3f639535138ba753df"} 10:38:56 INFO - Running post-run listener: copy_logs_to_upload_dir 10:38:56 INFO - Copying logs to upload dir... 10:38:56 INFO - mkdir: /builds/slave/test/build/upload/logs 10:38:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.501484 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-30 10:38:56.192473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 10:38:56.196166) ========= 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=8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e42e13c195641b645aa33847b4a0842619c054606fd193c290585bd5c1ca3bdaf47ad25141402975296334645de4876bf0c36388121b9dcb912f26ff2254dbb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcb364b2c99c105aaa3d9bc4e5bfdb322c54701d0d3292992039a02e16a3b9eb257a61ccac482c61f3a0c0483df9a360d55490109a7325f452e1e673ab4040d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924b0491849a5d435fb0f1d1aa59dbee4db82b1d928e129cb5dee35f6aea3262b57df33975a6d15242de2e9e7deb82f04c8506c1e0b75c3f639535138ba753df"} build_url:https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011488 build_url: 'https://queue.taskcluster.net/v1/task/MChEN6FjQW2gE_DBf0QmDw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e42e13c195641b645aa33847b4a0842619c054606fd193c290585bd5c1ca3bdaf47ad25141402975296334645de4876bf0c36388121b9dcb912f26ff2254dbb0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcb364b2c99c105aaa3d9bc4e5bfdb322c54701d0d3292992039a02e16a3b9eb257a61ccac482c61f3a0c0483df9a360d55490109a7325f452e1e673ab4040d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/924b0491849a5d435fb0f1d1aa59dbee4db82b1d928e129cb5dee35f6aea3262b57df33975a6d15242de2e9e7deb82f04c8506c1e0b75c3f639535138ba753df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 10:38:56.223417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:38:56.223785) ========= 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=8783db0840cc980e80bb0bd400000236-1464628693.724872-1323915046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004482 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:38:56.307576) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 10:38:56.307954) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 10:38:56.308272) ========= ========= Total master_lag: 0.17 =========