builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1463795991.59 results: success (0) buildid: 20160520163014 builduid: a7d25137649d41518f1fade00404d4f5 revision: 45709b7b6466abcd984d5736c0e64eb12b0044cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.594994) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.595462) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.595761) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.613693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.614118) ========= 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=826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 18:59:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-05-20 18:59:53 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121752 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.749668) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.750014) ========= 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=826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017706 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.799846) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:51.800217) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45709b7b6466abcd984d5736c0e64eb12b0044cc --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 45709b7b6466abcd984d5736c0e64eb12b0044cc --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=826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 18:59:53,224 truncating revision to first 12 chars 2016-05-20 18:59:53,224 Setting DEBUG logging. 2016-05-20 18:59:53,224 attempt 1/10 2016-05-20 18:59:53,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45709b7b6466?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 18:59:53,527 unpacking tar archive at: mozilla-central-45709b7b6466/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:52.463563) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:52.463894) ========= script_repo_revision: 45709b7b6466abcd984d5736c0e64eb12b0044cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:52.464285) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:52.464553) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:59:52.473346) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-20 18:59:52.473614) ========= /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=826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:59:53 INFO - MultiFileLogger online at 20160520 18:59:53 in /builds/slave/test 18:59:53 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 18:59:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:59:53 INFO - {'append_to_log': False, 18:59:53 INFO - 'base_work_dir': '/builds/slave/test', 18:59:53 INFO - 'blob_upload_branch': 'Firefox', 18:59:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:59:53 INFO - 'branch': 'Firefox', 18:59:53 INFO - 'buildbot_json_path': 'buildprops.json', 18:59:53 INFO - 'config_files': ('talos/linux_config.py',), 18:59:53 INFO - 'default_actions': ('clobber', 18:59:53 INFO - 'read-buildbot-config', 18:59:53 INFO - 'download-and-extract', 18:59:53 INFO - 'populate-webroot', 18:59:53 INFO - 'create-virtualenv', 18:59:53 INFO - 'install', 18:59:53 INFO - 'run-tests'), 18:59:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:59:53 INFO - 'download_minidump_stackwalk': True, 18:59:53 INFO - 'download_symbols': 'ondemand', 18:59:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:59:53 INFO - 'tooltool.py': '/tools/tooltool.py', 18:59:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:59:53 INFO - '/tools/misc-python/virtualenv.py')}, 18:59:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:59:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:59:53 INFO - 'installer_path': 'installer.exe', 18:59:53 INFO - 'log_level': 'info', 18:59:53 INFO - 'log_name': 'talos', 18:59:53 INFO - 'log_to_console': True, 18:59:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:59:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:59:53 INFO - 'opt_config_files': (), 18:59:53 INFO - 'pip_index': False, 18:59:53 INFO - 'sps_profile': False, 18:59:53 INFO - 'sps_profile_interval': 0, 18:59:53 INFO - 'suite': 'dromaeojs', 18:59:53 INFO - 'system_bits': '32', 18:59:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:59:53 INFO - 'title': 'talos-linux64-ix-052', 18:59:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:59:53 INFO - 'use_talos_json': True, 18:59:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:59:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:59:53 INFO - 'work_dir': 'build'} 18:59:53 INFO - ##### 18:59:53 INFO - ##### Running clobber step. 18:59:53 INFO - ##### 18:59:53 INFO - Running pre-action listener: _resource_record_pre_action 18:59:53 INFO - Running main action method: clobber 18:59:53 INFO - rmtree: /builds/slave/test/build 18:59:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:59:54 INFO - Running post-action listener: _resource_record_post_action 18:59:54 INFO - ##### 18:59:54 INFO - ##### Finished clobber step (success) 18:59:54 INFO - ##### 18:59:54 INFO - ##### 18:59:54 INFO - ##### Running read-buildbot-config step. 18:59:54 INFO - ##### 18:59:54 INFO - Running pre-action listener: _resource_record_pre_action 18:59:54 INFO - Running main action method: read_buildbot_config 18:59:54 INFO - Using buildbot properties: 18:59:54 INFO - { 18:59:54 INFO - "project": "", 18:59:54 INFO - "product": "firefox", 18:59:54 INFO - "script_repo_revision": "production", 18:59:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:59:54 INFO - "repository": "", 18:59:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:59:54 INFO - "buildid": "20160520163014", 18:59:54 INFO - "pgo_build": "True", 18:59:54 INFO - "basedir": "/builds/slave/test", 18:59:54 INFO - "buildnumber": 772, 18:59:54 INFO - "slavename": "talos-linux64-ix-052", 18:59:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:59:54 INFO - "platform": "ubuntu64_hw", 18:59:54 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:59:54 INFO - "branch": "mozilla-central", 18:59:54 INFO - "repo_path": "mozilla-central", 18:59:54 INFO - "revision": "45709b7b6466abcd984d5736c0e64eb12b0044cc", 18:59:54 INFO - "stage_platform": "linux64-pgo", 18:59:54 INFO - "builduid": "a7d25137649d41518f1fade00404d4f5", 18:59:54 INFO - "slavebuilddir": "test" 18:59:54 INFO - } 18:59:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:59:54 INFO - Running post-action listener: _resource_record_post_action 18:59:54 INFO - ##### 18:59:54 INFO - ##### Finished read-buildbot-config step (success) 18:59:54 INFO - ##### 18:59:54 INFO - ##### 18:59:54 INFO - ##### Running download-and-extract step. 18:59:54 INFO - ##### 18:59:54 INFO - Running pre-action listener: _resource_record_pre_action 18:59:54 INFO - Running main action method: download_and_extract 18:59:54 INFO - mkdir: /builds/slave/test/build/tests 18:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:54 INFO - https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:59:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:59:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:59:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 18:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 18:59:56 INFO - Downloaded 1361 bytes. 18:59:56 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:59:56 INFO - Using the following test package requirements: 18:59:56 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:56 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:59:56 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:59:56 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'jsshell-linux-x86_64.zip'], 18:59:56 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:59:56 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:56 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:59:56 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:59:56 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:59:56 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:59:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:56 INFO - https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:59:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:59:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:59:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 18:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 18:59:57 INFO - Downloaded 24389070 bytes. 18:59:57 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'] 18:59:57 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 18:59:59 INFO - Return code: 0 18:59:59 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 18:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:59 INFO - https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:59:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:59:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:59:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 18:59:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 19:00:00 INFO - Downloaded 24389070 bytes. 19:00:00 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'] 19:00:00 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 19:00:02 INFO - Return code: 0 19:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:02 INFO - https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:00:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:00:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:00:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 19:00:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 19:00:03 INFO - Downloaded 10840359 bytes. 19:00:03 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'] 19:00:03 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 19:00:03 INFO - Return code: 0 19:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:03 INFO - https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:00:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:00:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:00:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 19:00:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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 19:00:05 INFO - Downloaded 58611165 bytes. 19:00:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:00:05 INFO - mkdir: /builds/slave/test/properties 19:00:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:05 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:05 INFO - Contents: 19:00:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:00:07 INFO - Running post-action listener: _resource_record_post_action 19:00:07 INFO - Running post-action listener: set_extra_try_arguments 19:00:07 INFO - ##### 19:00:07 INFO - ##### Finished download-and-extract step (success) 19:00:07 INFO - ##### 19:00:07 INFO - ##### 19:00:07 INFO - ##### Running populate-webroot step. 19:00:07 INFO - ##### 19:00:07 INFO - Running pre-action listener: _resource_record_pre_action 19:00:07 INFO - Running main action method: populate_webroot 19:00:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:00:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:00:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:00:07 INFO - '--tppagecycles', 19:00:07 INFO - '7'], 19:00:07 INFO - 'tests': ['tsvgm']}}, 19:00:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['damp', 'tps']}, 19:00:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'tests': ['damp', 'tps']}, 19:00:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['dromaeo_dom']}, 19:00:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['a11yr', 19:00:07 INFO - 'ts_paint', 19:00:07 INFO - 'tpaint', 19:00:07 INFO - 'sessionrestore', 19:00:07 INFO - 'sessionrestore_no_auto_restore', 19:00:07 INFO - 'tabpaint']}, 19:00:07 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:07 INFO - 'ts_paint', 19:00:07 INFO - 'tpaint', 19:00:07 INFO - 'sessionrestore', 19:00:07 INFO - 'sessionrestore_no_auto_restore', 19:00:07 INFO - 'tabpaint']}, 19:00:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:00:07 INFO - 'ts_paint', 19:00:07 INFO - 'tpaint', 19:00:07 INFO - 'sessionrestore', 19:00:07 INFO - 'sessionrestore_no_auto_restore', 19:00:07 INFO - 'tabpaint']}, 19:00:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:00:07 INFO - 'ts_paint', 19:00:07 INFO - 'tpaint', 19:00:07 INFO - 'sessionrestore', 19:00:07 INFO - 'sessionrestore_no_auto_restore', 19:00:07 INFO - 'tabpaint']}, 19:00:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['a11yr', 19:00:07 INFO - 'ts_paint', 19:00:07 INFO - 'tpaint', 19:00:07 INFO - 'sessionrestore', 19:00:07 INFO - 'sessionrestore_no_auto_restore', 19:00:07 INFO - 'tabpaint']}, 19:00:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['a11yr', 19:00:07 INFO - 'ts_paint', 19:00:07 INFO - 'tpaint', 19:00:07 INFO - 'sessionrestore', 19:00:07 INFO - 'sessionrestore_no_auto_restore', 19:00:07 INFO - 'tabpaint']}, 19:00:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['tsvgx', 19:00:07 INFO - 'tsvgr_opacity', 19:00:07 INFO - 'tart', 19:00:07 INFO - 'tscrollx', 19:00:07 INFO - 'cart']}, 19:00:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:07 INFO - 'tsvgr_opacity', 19:00:07 INFO - 'tart', 19:00:07 INFO - 'tscrollx', 19:00:07 INFO - 'cart']}, 19:00:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'talos_options': ['--disable-e10s'], 19:00:07 INFO - 'tests': ['tp5o']}, 19:00:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'tests': ['tp5o']}, 19:00:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'talos_options': ['--disable-e10s', 19:00:07 INFO - '--xperf_path', 19:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:07 INFO - 'tests': ['tp5n']}, 19:00:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:07 INFO - 'talos_options': ['--xperf_path', 19:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:07 INFO - 'tests': ['tp5n']}}, 19:00:07 INFO - 'talos.zip': {'path': '', 19:00:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:00:07 INFO - Running post-action listener: _resource_record_post_action 19:00:07 INFO - ##### 19:00:07 INFO - ##### Finished populate-webroot step (success) 19:00:07 INFO - ##### 19:00:07 INFO - ##### 19:00:07 INFO - ##### Running create-virtualenv step. 19:00:07 INFO - ##### 19:00:07 INFO - Running pre-action listener: _resource_record_pre_action 19:00:07 INFO - Running main action method: create_virtualenv 19:00:07 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:07 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 19:00:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:00:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:07 INFO - Using real prefix '/usr' 19:00:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:00:07 INFO - Installing distribute.............................................................................................................................................................................................done. 19:00:09 INFO - Installing pip.................done. 19:00:09 INFO - Return code: 0 19:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:00:09 INFO - Using env: {'DISPLAY': ':0', 19:00:09 INFO - 'HOME': '/home/cltbld', 19:00:09 INFO - 'LANG': 'en_US.UTF-8', 19:00:09 INFO - 'LANGUAGE': 'en_US:en', 19:00:09 INFO - 'LOGNAME': 'cltbld', 19:00:09 INFO - 'MAIL': '/var/mail/cltbld', 19:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:09 INFO - 'MOZ_NO_REMOTE': '1', 19:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:09 INFO - 'NO_EM_RESTART': '1', 19:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:09 INFO - 'PWD': '/builds/slave/test', 19:00:09 INFO - 'SHELL': '/bin/bash', 19:00:09 INFO - 'SHLVL': '1', 19:00:09 INFO - 'TERM': 'linux', 19:00:09 INFO - 'TMOUT': '86400', 19:00:09 INFO - 'USER': 'cltbld', 19:00:09 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:09 INFO - '_': '/tools/buildbot/bin/python'} 19:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:10 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 19:00:10 INFO - Cleaning up... 19:00:10 INFO - Return code: 0 19:00:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:00:10 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')]} 19:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:10 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')]} 19:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:10 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 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:10 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 19:00:10 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 19:00:10 INFO - Using env: {'DISPLAY': ':0', 19:00:10 INFO - 'HOME': '/home/cltbld', 19:00:10 INFO - 'LANG': 'en_US.UTF-8', 19:00:10 INFO - 'LANGUAGE': 'en_US:en', 19:00:10 INFO - 'LOGNAME': 'cltbld', 19:00:10 INFO - 'MAIL': '/var/mail/cltbld', 19:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:10 INFO - 'MOZ_NO_REMOTE': '1', 19:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:10 INFO - 'NO_EM_RESTART': '1', 19:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:10 INFO - 'PWD': '/builds/slave/test', 19:00:10 INFO - 'SHELL': '/bin/bash', 19:00:10 INFO - 'SHLVL': '1', 19:00:10 INFO - 'TERM': 'linux', 19:00:10 INFO - 'TMOUT': '86400', 19:00:10 INFO - 'USER': 'cltbld', 19:00:10 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:10 INFO - '_': '/tools/buildbot/bin/python'} 19:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:10 INFO - Downloading/unpacking psutil>=3.1.1 19:00:10 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 19:00:10 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 19:00:10 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 19:00:10 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 19:00:10 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 19:00:10 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 19:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:12 INFO - Installing collected packages: psutil 19:00:12 INFO - Running setup.py install for psutil 19:00:12 INFO - building 'psutil._psutil_linux' extension 19:00:12 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 19:00:12 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 19:00:12 INFO - building 'psutil._psutil_posix' extension 19:00:12 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 19:00:12 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 19:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:12 INFO - Successfully installed psutil 19:00:12 INFO - Cleaning up... 19:00:12 INFO - Return code: 0 19:00:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:00:12 INFO - Using env: {'DISPLAY': ':0', 19:00:12 INFO - 'HOME': '/home/cltbld', 19:00:12 INFO - 'LANG': 'en_US.UTF-8', 19:00:12 INFO - 'LANGUAGE': 'en_US:en', 19:00:12 INFO - 'LOGNAME': 'cltbld', 19:00:12 INFO - 'MAIL': '/var/mail/cltbld', 19:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:12 INFO - 'MOZ_NO_REMOTE': '1', 19:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:12 INFO - 'NO_EM_RESTART': '1', 19:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:12 INFO - 'PWD': '/builds/slave/test', 19:00:12 INFO - 'SHELL': '/bin/bash', 19:00:12 INFO - 'SHLVL': '1', 19:00:12 INFO - 'TERM': 'linux', 19:00:12 INFO - 'TMOUT': '86400', 19:00:12 INFO - 'USER': 'cltbld', 19:00:12 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:12 INFO - '_': '/tools/buildbot/bin/python'} 19:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:00:13 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 19:00:13 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 19:00:13 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 19:00:13 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 19:00:13 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 19:00:13 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 19:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:00:15 INFO - Installing collected packages: mozsystemmonitor 19:00:15 INFO - Running setup.py install for mozsystemmonitor 19:00:15 INFO - Successfully installed mozsystemmonitor 19:00:15 INFO - Cleaning up... 19:00:15 INFO - Return code: 0 19:00:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:00:15 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')]} 19:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:15 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')]} 19:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:15 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 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:15 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 19:00:15 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 19:00:15 INFO - Using env: {'DISPLAY': ':0', 19:00:15 INFO - 'HOME': '/home/cltbld', 19:00:15 INFO - 'LANG': 'en_US.UTF-8', 19:00:15 INFO - 'LANGUAGE': 'en_US:en', 19:00:15 INFO - 'LOGNAME': 'cltbld', 19:00:15 INFO - 'MAIL': '/var/mail/cltbld', 19:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:15 INFO - 'MOZ_NO_REMOTE': '1', 19:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:15 INFO - 'NO_EM_RESTART': '1', 19:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:15 INFO - 'PWD': '/builds/slave/test', 19:00:15 INFO - 'SHELL': '/bin/bash', 19:00:15 INFO - 'SHLVL': '1', 19:00:15 INFO - 'TERM': 'linux', 19:00:15 INFO - 'TMOUT': '86400', 19:00:15 INFO - 'USER': 'cltbld', 19:00:15 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:15 INFO - '_': '/tools/buildbot/bin/python'} 19:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:15 INFO - Downloading/unpacking blobuploader==1.2.4 19:00:15 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 19:00:15 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 19:00:15 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 19:00:15 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 19:00:15 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 19:00:15 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 19:00:17 INFO - Downloading blobuploader-1.2.4.tar.gz 19:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:00:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:00:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 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 19:00:17 INFO - Downloading docopt-0.6.1.tar.gz 19:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:00:18 INFO - Installing collected packages: blobuploader, requests, docopt 19:00:18 INFO - Running setup.py install for blobuploader 19:00:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:00:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:00:18 INFO - Running setup.py install for requests 19:00:18 INFO - Running setup.py install for docopt 19:00:18 INFO - Successfully installed blobuploader requests docopt 19:00:18 INFO - Cleaning up... 19:00:18 INFO - Return code: 0 19:00:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:18 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')]} 19:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:18 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')]} 19:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:18 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 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:18 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 19:00:18 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 19:00:18 INFO - Using env: {'DISPLAY': ':0', 19:00:18 INFO - 'HOME': '/home/cltbld', 19:00:18 INFO - 'LANG': 'en_US.UTF-8', 19:00:18 INFO - 'LANGUAGE': 'en_US:en', 19:00:18 INFO - 'LOGNAME': 'cltbld', 19:00:18 INFO - 'MAIL': '/var/mail/cltbld', 19:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:18 INFO - 'MOZ_NO_REMOTE': '1', 19:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:18 INFO - 'NO_EM_RESTART': '1', 19:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:18 INFO - 'PWD': '/builds/slave/test', 19:00:18 INFO - 'SHELL': '/bin/bash', 19:00:18 INFO - 'SHLVL': '1', 19:00:18 INFO - 'TERM': 'linux', 19:00:18 INFO - 'TMOUT': '86400', 19:00:18 INFO - 'USER': 'cltbld', 19:00:18 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:18 INFO - '_': '/tools/buildbot/bin/python'} 19:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:18 INFO - Running setup.py (path:/tmp/pip-IhrY9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:18 INFO - Running setup.py (path:/tmp/pip-5SXIEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:18 INFO - Running setup.py (path:/tmp/pip-a23M_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:19 INFO - Running setup.py (path:/tmp/pip-5IPjb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:19 INFO - Running setup.py (path:/tmp/pip-Bq0w7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:19 INFO - Running setup.py (path:/tmp/pip-0tixOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:19 INFO - Running setup.py (path:/tmp/pip-tLMBlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:19 INFO - Running setup.py (path:/tmp/pip-4MsHxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:19 INFO - Running setup.py (path:/tmp/pip-pevcJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:19 INFO - Running setup.py (path:/tmp/pip-LenBWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:19 INFO - Running setup.py (path:/tmp/pip-XAZupf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:19 INFO - Running setup.py (path:/tmp/pip-GF2zmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:19 INFO - Running setup.py (path:/tmp/pip-ow8zZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:19 INFO - Running setup.py (path:/tmp/pip-ei6evK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:19 INFO - Running setup.py (path:/tmp/pip-2v8rWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:19 INFO - Running setup.py (path:/tmp/pip-34TjFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:19 INFO - Running setup.py (path:/tmp/pip-I6418K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:00:20 INFO - Running setup.py install for manifestparser 19:00:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Running setup.py install for mozcrash 19:00:20 INFO - Running setup.py install for mozdebug 19:00:20 INFO - Running setup.py install for mozdevice 19:00:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Running setup.py install for mozfile 19:00:20 INFO - Running setup.py install for mozhttpd 19:00:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Running setup.py install for mozinfo 19:00:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Running setup.py install for mozInstall 19:00:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:00:20 INFO - Running setup.py install for mozleak 19:00:20 INFO - Running setup.py install for mozlog 19:00:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:00:21 INFO - Running setup.py install for moznetwork 19:00:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:00:21 INFO - Running setup.py install for mozprocess 19:00:21 INFO - Running setup.py install for mozprofile 19:00:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:00:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:00:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:00:21 INFO - Running setup.py install for mozrunner 19:00:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:00:21 INFO - Running setup.py install for mozscreenshot 19:00:21 INFO - Running setup.py install for moztest 19:00:21 INFO - Running setup.py install for mozversion 19:00:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:00:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:00:21 INFO - Cleaning up... 19:00:21 INFO - Return code: 0 19:00:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:21 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 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:21 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 19:00:21 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 19:00:21 INFO - Using env: {'DISPLAY': ':0', 19:00:21 INFO - 'HOME': '/home/cltbld', 19:00:21 INFO - 'LANG': 'en_US.UTF-8', 19:00:21 INFO - 'LANGUAGE': 'en_US:en', 19:00:21 INFO - 'LOGNAME': 'cltbld', 19:00:21 INFO - 'MAIL': '/var/mail/cltbld', 19:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:21 INFO - 'MOZ_NO_REMOTE': '1', 19:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:21 INFO - 'NO_EM_RESTART': '1', 19:00:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:21 INFO - 'PWD': '/builds/slave/test', 19:00:21 INFO - 'SHELL': '/bin/bash', 19:00:21 INFO - 'SHLVL': '1', 19:00:21 INFO - 'TERM': 'linux', 19:00:21 INFO - 'TMOUT': '86400', 19:00:21 INFO - 'USER': 'cltbld', 19:00:21 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:21 INFO - '_': '/tools/buildbot/bin/python'} 19:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:22 INFO - Running setup.py (path:/tmp/pip-dBgpxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:22 INFO - Running setup.py (path:/tmp/pip-g2ycMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:22 INFO - Running setup.py (path:/tmp/pip-ktm7MB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:22 INFO - Running setup.py (path:/tmp/pip-Rgd2PX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:22 INFO - Running setup.py (path:/tmp/pip-zacySZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:22 INFO - Running setup.py (path:/tmp/pip-WXOGZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:22 INFO - Running setup.py (path:/tmp/pip-NuB3es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:22 INFO - Running setup.py (path:/tmp/pip-IvX7iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:22 INFO - Running setup.py (path:/tmp/pip-sqLOA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:22 INFO - Running setup.py (path:/tmp/pip-Ws2VpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:22 INFO - Running setup.py (path:/tmp/pip-VJlkDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:22 INFO - Running setup.py (path:/tmp/pip-fHcm0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:22 INFO - Running setup.py (path:/tmp/pip-Yq75RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:22 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)) 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:23 INFO - Running setup.py (path:/tmp/pip-YWSe3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:23 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)) 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:23 INFO - Running setup.py (path:/tmp/pip-9HEnUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:23 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)) 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:23 INFO - Running setup.py (path:/tmp/pip-34DdyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:23 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)) 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:23 INFO - Running setup.py (path:/tmp/pip-tX45kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:23 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)) 19:00:23 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)) 19:00:23 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)) 19:00:23 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)) 19:00:23 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)) 19:00:23 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)) 19:00:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00:23 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 19:00:23 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 19:00:23 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 19:00:23 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 19:00:23 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 19:00:23 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 19:00:25 INFO - Downloading blessings-1.6.tar.gz 19:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:25 INFO - Installing collected packages: blessings 19:00:25 INFO - Running setup.py install for blessings 19:00:25 INFO - Successfully installed blessings 19:00:25 INFO - Cleaning up... 19:00:25 INFO - Return code: 0 19:00:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:25 INFO - Reading from file tmpfile_stdout 19:00:25 INFO - Current package versions: 19:00:25 INFO - argparse == 1.2.1 19:00:25 INFO - blessings == 1.6 19:00:25 INFO - blobuploader == 1.2.4 19:00:25 INFO - docopt == 0.6.1 19:00:25 INFO - manifestparser == 1.1 19:00:25 INFO - mozInstall == 1.12 19:00:25 INFO - mozcrash == 0.17 19:00:25 INFO - mozdebug == 0.1 19:00:25 INFO - mozdevice == 0.48 19:00:25 INFO - mozfile == 1.2 19:00:25 INFO - mozhttpd == 0.7 19:00:25 INFO - mozinfo == 0.9 19:00:25 INFO - mozleak == 0.1 19:00:25 INFO - mozlog == 3.2 19:00:25 INFO - moznetwork == 0.27 19:00:25 INFO - mozprocess == 0.22 19:00:25 INFO - mozprofile == 0.28 19:00:25 INFO - mozrunner == 6.11 19:00:25 INFO - mozscreenshot == 0.1 19:00:25 INFO - mozsystemmonitor == 0.3 19:00:25 INFO - moztest == 0.7 19:00:25 INFO - mozversion == 1.4 19:00:25 INFO - psutil == 3.1.1 19:00:25 INFO - requests == 1.2.3 19:00:25 INFO - wsgiref == 0.1.2 19:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:25 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')]} 19:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:25 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')]} 19:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:25 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 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:25 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 19:00:25 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 19:00:25 INFO - Using env: {'DISPLAY': ':0', 19:00:25 INFO - 'HOME': '/home/cltbld', 19:00:25 INFO - 'LANG': 'en_US.UTF-8', 19:00:25 INFO - 'LANGUAGE': 'en_US:en', 19:00:25 INFO - 'LOGNAME': 'cltbld', 19:00:25 INFO - 'MAIL': '/var/mail/cltbld', 19:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:25 INFO - 'MOZ_NO_REMOTE': '1', 19:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:25 INFO - 'NO_EM_RESTART': '1', 19:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:25 INFO - 'PWD': '/builds/slave/test', 19:00:25 INFO - 'SHELL': '/bin/bash', 19:00:25 INFO - 'SHLVL': '1', 19:00:25 INFO - 'TERM': 'linux', 19:00:25 INFO - 'TMOUT': '86400', 19:00:25 INFO - 'USER': 'cltbld', 19:00:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:25 INFO - '_': '/tools/buildbot/bin/python'} 19:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 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)) 19:00:25 INFO - Cleaning up... 19:00:25 INFO - Return code: 0 19:00:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00:25 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')]} 19:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:25 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')]} 19:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:25 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 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:25 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 19:00:25 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 19:00:25 INFO - Using env: {'DISPLAY': ':0', 19:00:25 INFO - 'HOME': '/home/cltbld', 19:00:25 INFO - 'LANG': 'en_US.UTF-8', 19:00:25 INFO - 'LANGUAGE': 'en_US:en', 19:00:25 INFO - 'LOGNAME': 'cltbld', 19:00:25 INFO - 'MAIL': '/var/mail/cltbld', 19:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:25 INFO - 'MOZ_NO_REMOTE': '1', 19:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:25 INFO - 'NO_EM_RESTART': '1', 19:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:25 INFO - 'PWD': '/builds/slave/test', 19:00:25 INFO - 'SHELL': '/bin/bash', 19:00:25 INFO - 'SHLVL': '1', 19:00:25 INFO - 'TERM': 'linux', 19:00:25 INFO - 'TMOUT': '86400', 19:00:25 INFO - 'USER': 'cltbld', 19:00:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:25 INFO - '_': '/tools/buildbot/bin/python'} 19:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:26 INFO - Downloading/unpacking jsonschema 19:00:26 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 19:00:26 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 19:00:26 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 19:00:26 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 19:00:26 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 19:00:26 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 19:00:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:00:27 INFO - Downloading/unpacking functools32 (from jsonschema) 19:00:27 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 19:00:27 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 19:00:27 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 19:00:27 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 19:00:27 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 19:00:27 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 19:00:28 INFO - Downloading functools32-3.2.3-2.tar.gz 19:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:00:28 INFO - warning: no files found matching '*.txt' 19:00:28 INFO - no previously-included directories found matching 'build' 19:00:28 INFO - no previously-included directories found matching 'dist' 19:00:28 INFO - no previously-included directories found matching '.git*' 19:00:28 INFO - Installing collected packages: jsonschema, functools32 19:00:28 INFO - Running setup.py install for functools32 19:00:28 INFO - warning: no files found matching '*.txt' 19:00:28 INFO - no previously-included directories found matching 'build' 19:00:28 INFO - no previously-included directories found matching 'dist' 19:00:28 INFO - no previously-included directories found matching '.git*' 19:00:28 INFO - Successfully installed jsonschema functools32 19:00:28 INFO - Cleaning up... 19:00:28 INFO - Return code: 0 19:00:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:28 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 0x7faf9eb2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23008a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23cb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, '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': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', '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 19:00:28 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 19:00:28 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 19:00:28 INFO - Using env: {'DISPLAY': ':0', 19:00:28 INFO - 'HOME': '/home/cltbld', 19:00:28 INFO - 'LANG': 'en_US.UTF-8', 19:00:28 INFO - 'LANGUAGE': 'en_US:en', 19:00:28 INFO - 'LOGNAME': 'cltbld', 19:00:28 INFO - 'MAIL': '/var/mail/cltbld', 19:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:28 INFO - 'MOZ_NO_REMOTE': '1', 19:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:28 INFO - 'NO_EM_RESTART': '1', 19:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:28 INFO - 'PWD': '/builds/slave/test', 19:00:28 INFO - 'SHELL': '/bin/bash', 19:00:28 INFO - 'SHLVL': '1', 19:00:28 INFO - 'TERM': 'linux', 19:00:28 INFO - 'TMOUT': '86400', 19:00:28 INFO - 'USER': 'cltbld', 19:00:28 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:28 INFO - '_': '/tools/buildbot/bin/python'} 19:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:28 INFO - Downloading/unpacking flake8 19:00:28 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 19:00:28 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 19:00:28 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 19:00:28 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 19:00:28 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 19:00:28 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 19:00:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:00:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:00:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:00:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:00:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:00:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:00:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:00:31 INFO - no previously-included directories found matching 'docs/_build' 19:00:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:00:31 INFO - Running setup.py install for pep8 19:00:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:00:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:00:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:00:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:00:31 INFO - no previously-included directories found matching 'docs/_build' 19:00:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:00:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:00:31 INFO - Cleaning up... 19:00:31 INFO - Return code: 0 19:00:31 INFO - Running post-action listener: _resource_record_post_action 19:00:31 INFO - Running post-action listener: _start_resource_monitoring 19:00:31 INFO - Starting resource monitoring. 19:00:31 INFO - ##### 19:00:31 INFO - ##### Finished create-virtualenv step (success) 19:00:31 INFO - ##### 19:00:31 INFO - ##### 19:00:31 INFO - ##### Running install step. 19:00:31 INFO - ##### 19:00:31 INFO - Running pre-action listener: _resource_record_pre_action 19:00:31 INFO - Running main action method: install 19:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:31 INFO - Reading from file tmpfile_stdout 19:00:31 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:31 INFO - Reading from file tmpfile_stdout 19:00:31 INFO - Output received: 19:00:31 INFO - Usage: mozinstall [options] installer 19:00:31 INFO - Options: 19:00:31 INFO - -h, --help show this help message and exit 19:00:31 INFO - -d DEST, --destination=DEST 19:00:31 INFO - Directory to install application into. [default: 19:00:31 INFO - "/builds/slave/test"] 19:00:31 INFO - --app=APP Application being installed. [default: firefox] 19:00:31 INFO - mkdir: /builds/slave/test/build/application 19:00:31 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'] 19:00:31 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 19:00:48 INFO - Reading from file tmpfile_stdout 19:00:48 INFO - Output received: 19:00:48 INFO - /builds/slave/test/build/application/firefox/firefox 19:00:48 INFO - Running post-action listener: _resource_record_post_action 19:00:48 INFO - ##### 19:00:48 INFO - ##### Finished install step (success) 19:00:48 INFO - ##### 19:00:48 INFO - ##### 19:00:48 INFO - ##### Running run-tests step. 19:00:48 INFO - ##### 19:00:48 INFO - Running pre-action listener: _resource_record_pre_action 19:00:48 INFO - Running main action method: run_tests 19:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:00:48 INFO - Python 2.7.3 19:00:48 INFO - Return code: 0 19:00:48 INFO - grabbing minidump binary from tooltool 19:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:48 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 0x245a9c0>, '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 0x245b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x245bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:00:48 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 19:00:48 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 19:00:48 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 19:00:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:00:48 INFO - Return code: 0 19:00:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:00:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:00:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:00:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:00:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:00:49 INFO - Return code: 0 19:00:49 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/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 19:00:49 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/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 19:00:49 INFO - Using env: {'DISPLAY': ':0', 19:00:49 INFO - 'HOME': '/home/cltbld', 19:00:49 INFO - 'LANG': 'en_US.UTF-8', 19:00:49 INFO - 'LANGUAGE': 'en_US:en', 19:00:49 INFO - 'LOGNAME': 'cltbld', 19:00:49 INFO - 'MAIL': '/var/mail/cltbld', 19:00:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:49 INFO - 'MOZ_NO_REMOTE': '1', 19:00:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:49 INFO - 'NO_EM_RESTART': '1', 19:00:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:49 INFO - 'PWD': '/builds/slave/test', 19:00:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:49 INFO - 'SHELL': '/bin/bash', 19:00:49 INFO - 'SHLVL': '1', 19:00:49 INFO - 'TERM': 'linux', 19:00:49 INFO - 'TMOUT': '86400', 19:00:49 INFO - 'USER': 'cltbld', 19:00:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532', 19:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:49 INFO - '_': '/tools/buildbot/bin/python'} 19:00:49 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/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 19:00:49 INFO - mozversion application_buildid: 20160520163014 19:00:49 INFO - mozversion application_changeset: 45709b7b6466abcd984d5736c0e64eb12b0044cc 19:00:49 INFO - mozversion application_display_name: Nightly 19:00:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:00:49 INFO - mozversion application_name: Firefox 19:00:49 INFO - mozversion application_remotingname: firefox 19:00:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:00:49 INFO - mozversion application_vendor: Mozilla 19:00:49 INFO - mozversion application_version: 49.0a1 19:00:49 INFO - mozversion platform_buildid: 20160520163014 19:00:49 INFO - mozversion platform_changeset: 45709b7b6466abcd984d5736c0e64eb12b0044cc 19:00:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:00:49 INFO - mozversion platform_version: 49.0a1 19:00:49 INFO - using testdate: 1463796049 19:00:49 INFO - actual date: 1463796049 19:00:49 INFO - starting webserver on 'localhost:52562' 19:00:49 INFO - SUITE-START | Running 2 tests 19:00:49 INFO - TEST-START | dromaeo_css 19:00:49 INFO - operating with platform_type : linux_ 19:00:49 INFO - Initialising browser for dromaeo_css test... 19:00:49 INFO - TEST-INFO | started process 12749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0BYjRV/profile http://localhost:52562/getInfo.html) 19:00:53 INFO - PROCESS | 12749 | __metrics Screen width/height:1600/1200 19:00:53 INFO - PROCESS | 12749 | colorDepth:24 19:00:53 INFO - PROCESS | 12749 | Browser inner width/height: 1024/697 19:00:53 INFO - PROCESS | 12749 | __metrics 19:00:54 ERROR - PROCESS | 12749 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:00:55 INFO - TEST-INFO | 12749: exit 0 19:00:55 INFO - Browser initialized. 19:00:55 INFO - Running cycle 1/1 for dromaeo_css test... 19:00:55 INFO - TEST-INFO | started process 12830 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0BYjRV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:06 INFO - PROCESS | 12830 | RSS: Main: 153165824 19:01:06 INFO - PROCESS | 12830 | 19:01:57 INFO - PROCESS | 12830 | 1463796117890 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] 19:04:05 INFO - PROCESS | 12830 | Cycle 1(1): loaded http://localhost:52562/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52562/tests/dromaeo/cssquery-ext.html) 19:04:06 INFO - PROCESS | 12830 | RSS: Main: 256167936 19:04:06 INFO - PROCESS | 12830 | 19:07:08 INFO - PROCESS | 12830 | Cycle 1(1): loaded http://localhost:52562/tests/dromaeo/cssquery-ext.html (next: http://localhost:52562/tests/dromaeo/cssquery-jquery.html) 19:07:09 INFO - PROCESS | 12830 | RSS: Main: 238018560 19:07:09 INFO - PROCESS | 12830 | 19:10:11 INFO - PROCESS | 12830 | Cycle 1(1): loaded http://localhost:52562/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52562/tests/dromaeo/cssquery-mootools.html) 19:10:12 INFO - PROCESS | 12830 | RSS: Main: 240783360 19:10:12 INFO - PROCESS | 12830 | 19:13:14 INFO - PROCESS | 12830 | Cycle 1(1): loaded http://localhost:52562/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52562/tests/dromaeo/cssquery-prototype.html) 19:13:14 INFO - PROCESS | 12830 | RSS: Main: 239902720 19:13:14 INFO - PROCESS | 12830 | 19:16:17 INFO - PROCESS | 12830 | Cycle 1(1): loaded http://localhost:52562/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52562/tests/dromaeo/cssquery-yui.html) 19:16:17 INFO - PROCESS | 12830 | RSS: Main: 221294592 19:16:17 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | Cycle 1(1): loaded http://localhost:52562/tests/dromaeo/cssquery-yui.html (next: http://localhost:52562/tests/dromaeo/cssquery-dojo.html) 19:19:20 INFO - PROCESS | 12830 | RSS: Main: 282669056 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | __start_tp_report 19:19:20 INFO - PROCESS | 12830 | _x_x_mozilla_page_load 19:19:20 INFO - PROCESS | 12830 | _x_x_mozilla_page_load_details 19:19:20 INFO - PROCESS | 12830 | |i|pagename|runs| 19:19:20 INFO - PROCESS | 12830 | |0;dojo.html;3127.937441251174;3140.120766185468;3175.380800743856;3177.062766483887;3179.491281394976;5344.545713614348;5408.296921400222;5422.566194704424;5465.098258787295;5491.615586908918;5481.698506582136;5486.698231597261;5488.039593071207;5527.474889885464;5547.472990065948;4926.334944782453;4937.185364414867;5086.745662716868;5152.9484705152845;5163.922541161885;5076.796928122871;5082.466341034194;5133.204353325222;5152.175651895697;5157;4841.903161936759;4934.679245849026;4959.752012399365;5003.849884503471;5008.824691135793;5005.099082165245;5015.824446144404;5016.172331565287;5030.723310217936;5061.316722242487;4899.2896030075435;4963.70217786934;4986.90026199474;4991.950080499221;5049.419316778581;5425.294711687495;5487.917681234785;5498.697571633558;5575.275214222166;5582.27430434041;5939.4357536034;5974.701264936779;6003.6097653652805;6009;6011.068284415923;5786.623869448473;5804.187413762077;6047.939520604782;6051.697415129226;6052.303985041733;5462.0987537056335;5514.724263786795;5548.86127846803;5553.222548843165;5559.972200138974;6042.818715438456;6043.365446628129;6048.092786082123;6052.697365131726;6052.818415447544;5596.2165296858475;5606.243157173811;5615.943840561624;5797.942020579824;5803.129530570448;5756.395578464201;5760.337561180434;5803.854903627359;5811.953848307261;5815.04051831443;5679.091345384719;5726.599138060295;5727.684977326204;5729.799457019068;5735;5682.829515114553;5687.630304030225;5816.447437493432;5830.416958304136;5892.322382925932;4455.331700244989;4467.8212871484775;4469.798859051285;4470.642348612103;4503.482099558527;3951.7036222283446;3974.28462876685;3976.0855003349393;4013.8996525086523;4029.81865816039;5351.545118664879;5387.9461205388225;5478.808241711601;5534.252875861787;5543.6396634218645;5908.940910590925;5943.821685349447;5949.25634295713;5954.07711804671;6011.158437818709;5548.029094908375;5560.277163968739;5567.025770490148;5568.443155684399;5573.721313934287;4738.620910327166;4798.616110711135;4808.206645903387;4813.229883218668;4819.614430845524;5975.223220981245;5976.103584462278;5976.850578735566;5982.431668991526;5983.910241346383;5691.23168372281;5785.1322301654245;5807.8548036299435;5816.418358164319;5818.679972601378;5981.611195272293;6022.608530445507;6054.878902422015;6108.786192483241;6110.694465276807;5802.100674395476;5808.361080281081;5813.15709222152;5817.185594016756;5817.767289308549;5838.241028666246;5859.179714839841;5872.471477567039;5931.703414829328;5940.55445841555;5724.084146536701;5780.132980053026;5781.82654520365;5786.710664466676;5834.504067154339;5785.190073389813;5793.739281732248;5793.768249270151;5799.130130480462;5807.332156802022;5514.91727624094;5537.5569954402745;5553.111502159635;5696.316442026983;5708.143778433268;5725.456081672234;5727.513161381329;5735.97132014354;5760.913586296293;5782.335031471266;5579.944200558105;5588.692621905834;5588.944110558842;5600.915986260291;5658.264425624642 19:19:20 INFO - PROCESS | 12830 | |1;ext.html;609.9725512351944;632.6457183976974;652.7584793626359;654.2606854254692;666.8932970724682;33300.50049249263;43228;44519.33221001687;44561;47312.634368281535;45255.547444525546;45761.31358029632;45791.542084579145;45820.3126953096;45835;8555.828883422328;8658.913410865905;8698.739037828875;8712.390132690714;8725.956370218171;8718.564071796385;8871.600777964975;8890.555472226362;8898.37711360205;8900.154485323885;8902.732918012438;8970.058143894896;8976.416532925372;8982.550872456384;8996.550172491381;8659.177378149066;8694.652213911437;8709.172628600336;8718.782030449225;8725.301975841949;8714.69498567547;8828.514431706253;8854.335924805666;8866.512341821197;8870.55647217643;41584.58415415837;41711.582884171075;41732.165356692996;41845.37231941523;41860.79069604663;3313.867445302185;3380.678835510623;3457.4122399192197;3563.750537462379;4063.8374465021366;30711.692883071108;31518.054458366285;33335.6666433335;33347.16632084193;33399.6660033399;4247;4388.517263101056;4403.559644035603;4430.756308403036;4439.2675208590545;339699;345296;347262.9999999975;347322;347681.26159369294;65565.67217163884;65787.34212657908;66455.33544664588;66482.33517664859;66519;68800;68872.31127688658;69181.65409173022;69433.65283173551;69469.3053069473;17384;17573.824261757476;17587.73618395725;17606.559836003948;17608.207630656652;13182.868171318356;13274;13281.667958300926;13293.26887021223;13328;19345.51636209106;20828;20943.266985655733;20986.2654807081;21004.684929726067;6727.764528241488;7679.385649148055;7835.412344074217;7878.842423151505;7913.169117242722;961.6393852305386;982.6855406270068;1044.1490185498947;1074.333912973961;1092.5848177692426;31571.842140789147;32038;32068.679313207034;32148.678513214567;32175.35649287048;1131.8924702153283;1180.8701042885275;1204.2292932523192;1244.3902487780922;1245.975080498385;16413.1793410329;16688.6662266754;16742;16779.244933978;16826.411075612294;501628;504632;506579;508610;508616;59695.10457343143;61256.69371653203;61437.38562614406;61519.38480615226;61528;1270.4283072617175;1302.615728360136;1308.2542950518305;1408.0777091005202;1412.9788053179004;27913.441731164858;33364;33698.80957737739;33705.325893482484;33894;50024.74987625039;51534;51580.48419515907;51588.22617660663;51714;390850;399237;399290;400228;404275;25807.354816128995;28220.15339539817;29005.854970725013;29174.7082529172;29396.41207175802;44840.32739508845;47547;49041.754791225816;50100;50255.246171308194;39837.40243896286;42027.57972420359;42062.57937420587;42499.787501062296;43107;41662.37506437345;41710;42469.787651061546;42626;42788.572114279705;40617.796911015255;41220.38169427401;41833.163336733705;42167.15665686731;44313.556864430946;34398.312033759685;37280.44079338758;37308.25383492369;37919.43120853134;38139.61860381471;27996.600169991827;28420.147395578166;28657;28838.567421489115;28970.7102928968 19:19:20 INFO - PROCESS | 12830 | |2;jquery.html;382.4970164234032;384.7825978322246;397.5309135220442;397.8726807421625;401.90555219523407;18022.5494362641;18888.62222755544;20540.178392864305;20562;20587.27944521942;11848.052155827534;12155.878441215586;12367.567135150271;12519.749605007893;12549.937250313758;5088.465711100327;5256.737163141846;5263.34208223972;5271.130263506536;5286.524212820846;3807.3527500325013;5528.55771538278;5819.30168379795;5828.679422631753;5914.260717410324;5706.572007099463;5840.883182336351;5853.209816674737;5890.499307558841;5906.704664766766;5677.290338707661;5740.712964351807;5817.331006934214;5829.12563115534;5829.329627092875;3723.94414083789;5318.547923426513;5633.211350410946;5645.491905728504;5697.344805347397;13924;13934.233617151154;16124.838751612568;23115.306540803802;23127;2160.114353115215;2166.599179151862;2191.561687662474;2310.3761984264156;2460.1020627470843;9466.57400416976;10233.539490722935;10612.522436490377;10655.041046305869;11438.142139339583;1933.622943526013;1985.0967809646577;2038.7655419626785;2053.301877361689;2184.530325979906;78120.60939695266;81672.59163704203;82035.5898220511;83008;83419.5829020857;10909.727256818484;10932.508037138348;11220.382878941737;11275.718107047389;11384.146189035855;15479.613009674624;15488.14815185153;15554.300056497485;15556.455524056604;15599.454019109276;7881.763547093597;7906.802329941797;8012;8071.717489887826;8107.675692972333;7401.296876796696;7512.586807725518;7599.050118735158;7841.137474877759;8184.6726130955285;4790.4251489821445;4793.832215872428;4800.439857046404;4813.085513752376;4899.265110233494;1820.1051923913546;1824.616830465617;1986.632472992501;1997.270996086447;2028.7768345481986;918.2470374293149;982.6904525074578;1006.6929586476195;1032.9018743219383;1088.6951653537021;18620.348287809862;19030.524236894187;19457.513562160777;19528.12123454448;19658.803411965982;956.636478138303;965.6089283840056;966.7824739433571;973.0804389851522;996.5415908682088;10920.453977301104;10981.61564345244;10992.505337259843;11044.558217671363;11121.220604703209;486273;491667;494857;508242;510021;85947;87750.12249877419;88225.55887220651;88753.55623221843;88959.77600560038;942.5805516545208;964.0841200859212;969.699393188114;973.8782652168479;983.8524221366853;16811.747823782407;16927.40754073651;17287.82712172862;17377.739333909747;17447.738283926006;38360;38677;38695.80652096721;39919.40120898247;40081.599184007784;54530.72734636301;56763;56963.42704363588;57063.42936570581;57080.714596426755;7360.116785985716;7674.155842857393;8357.623906924082;8624.741257762276;8723.476591404537;12847.164934279243;13017.284049377375;13254.403551839998;13324.133931294822;13732.65668358299;11320.886791131983;11630.6510804676;11638.5344586219;11694.649160525198;11876.703082422662;11254.38100904458;11456.599019034295;11500.309981401142;11774.646760597196;12022.519099235933;11343.546258149581;11500.769984600094;11502.252353596989;11713;12151.392430378623;7668.6165691716305;7706;7727.613619319124;7816.531008139303;7833.373330133461;11330.716732081764;11425.485853136463;11652.30086194831;11745.412729363668;11745.647630571097 19:19:20 INFO - PROCESS | 12830 | |3;mootools.html;1384.2663388404148;1474.771410431383;1525.153539785419;1643.276958138419;1746.2142036083758;7472.925270747291;7822.178671239516;7864.449488535804;8049.436539442241;8050.154733752945;7831.765047048598;8004.119546849842;8018.318442932343;8019.639116239769;8021.438499305051;1943.2518480385083;2084.6560317547624;2117.364790562825;2128.5636444528914;2213.2806837777707;2156.6010288096754;2171.4245724882917;2174.3803016140405;2178.4227179797367;2181.9890900545474;2186.267600353883;2223.6108680980847;2228.0864845413385;2228.4874478869788;2232.352617740853;1899.7150427435927;2101.443117573844;2165.956680866374;2172.0985790896757;2185.7595664476894;2062.267894897305;2079.334612923865;2081.7814129516332;2084.8123668869875;2087.14427084896;7703.422243331773;7747.922520774777;7761;7766.495177813424;7770.611469426506;4790.42514898211;4898.804047838083;4905.6320775941595;4914.680545764514;4928.654994150411;3039.7416219621136;3142.402943440739;3146.4179126861377;3166.62000559932;3194.8881789137267;995.2187532786728;1013.5236438873718;1026.2508368890676;1033.9896601034043;1057.836035414512;10441.164706823436;10757.462126893624;10773.892261077446;11081.390523521202;11094.500747466383;4102.979485102615;4139.19285739284;4187.5184353799095;4224.4508213931995;4227.091175397304;8794.252488538546;8812.691555795516;8819.382643214913;8834.514101724339;8834.734957951272;4385.736855788599;4841.8547443576745;4842.394700662418;4861.440934292531;4861.829835955725;4460.77696115193;4531.048479819209;4564.132814765184;4566.337881007235;4584.931226031612;3672.5225720656144;3691.206390626028;3772.5095737553943;3794.715396345285;3808.9428658570146;1093.8413929980109;1105.4251789069638;1107.0534692837507;1113.04834366617;1122.4275619433984;869.599984007351;894.4320356573622;912.2610685344891;922.2207234886511;933.4819175357605;5204.9219261711105;5287;5297.788088476418;5356.0894647909945;5356.8660783479945;419.88939255668583;427.8160391031824;429.60806985367185;431.7323259579018;440.6474820143872;3550.840212190406;3636.7636103653176;3638.5451818522683;3639.47227651989;3659.1949771050326;9606.135447809591;9827.4103553787;9911.504424778732;9923.801523969625;9948.35335703177;9570.425774453555;9627.326087173831;9666.661666841768;9671.081247281498;9684.08001239881;7498.6625601848045;7580.3177714004905;7614.809629759189;7671.501352412187;7700.768976930701;5318.4149743527405;5357.651752636066;5405.24326594285;5482.616216864782;5550.69471179089;8976.506292153734;9073.228775554151;9113.77215569616;9132.634694612141;9145.451272923647;8928;9200.21798147165;9352.485613291063;9453.716388508356;9504.334696571173;6230.43926046658;6697.196336439659;6870.175578930561;6914.688839002157;6967.581945083311;7644.312011918954;7646.617669116633;7721.7683469496005;7727.845443091219;7741.496802707806;6992.545484543489;7229.49393542468;7246.927530724625;7338.192798792022;7364.5581265124265;7078.504504684623;7085.82285442868;7112.644367781693;7170.605616690923;7198.244184360567;6296;6504.512161587994;6514.706838192198;6590.7693230736695;6662.467002639681;4822.614190864792;4854.80580776765;4903.950960490492;4971.751412429292;4989.326440930428;4763.2378819390115;4773.11697335994;4816.373871396696;4818.204996175592;4911.877203069952 19:19:20 INFO - PROCESS | 12830 | |4;prototype.html;319.0762741862309;321.78118879162173;324.8294645311211;332.18945772315533;332.67730301607446;5256.684598924057;5287.629865909388;5296.311479507659;5300.973495132529;5336.759845806938;5197.220416937461;5200.68795872247;5214.478552144784;5233.921491177626;5254.527092561669;3929.8821035368983;4002.1395399988996;4073.5926407359175;4097.262492751303;4124.608162224584;4079.9592004080023;4092.9590704092875;4094.2016306820183;4116.600355879001;4123.237201117788;3946.1910308386714;4094.877153685394;4100.487439070116;4103.2409004334295;4126.09225970286;3999.2401443725744;4076.4496792932873;4084.8570300039355;4114.423980642712;4134.276501612236;3635.345637785224;3636.945445818315;3637.108908317453;3661.4141737322166;3674.2835147146316;5031.295618613397;5041.445441001487;5059.316992206079;5097.7960881564695;5104.438511763703;4494.303382975611;4628.629709623222;4649.977005058881;4665.323528088408;4678.883027924295;5378.757955891994;5386.596005299579;5394.217838413408;5398.217258497535;5408.4591540845995;4000.5199376074765;4016.136530645925;4022.839086436539;4024.215278020787;4027.154297597479;5386.218998245272;5667.546596272288;5677.785663591203;5689.914651280193;5701.857453563653;3939.901502462404;3941.7832019239218;3954.584768599313;3961.900952476211;3970.3448930926074;4013.695548946604;4015.437838702584;4021.959780402217;4022.7586344819406;4043.555208927016;4440.000999775025;4572.88567785808;4579.542045795461;4644.2801365788355;4656.953430465652;5412.458754124556;5419.268398766116;5445.863853403617;5453.536449401845;5493.148561972901;1403.4877269796655;1422.1680317014661;1427.7001829615897;1431.1842249917447;1442.9422823087168;932.6176267730198;943.2548286853446;979.5445118020154;1003.1473247739364;1005.4269066632096;5183.688978661293;5198.948010519922;5237.423883372826;5275.076861549213;5289.497497737709;5521.834344969658;5549.19536667181;5572.498475137257;5595.804146854922;5617.578681598897;5021.799128034911;5216.295800067019;5244;5302.969742995274;5307.275135580951;2974.3605124897817;2986.805857619248;2998.6401631804324;3010.6838781928022;3013.9246518837203;5470.781168753285;5710.685912274865;5732.6847023413275;5765.798197063083;5786.276715410574;5635.154726790932;5673.404292549223;5675.432456754292;5692.686902220335;5699.173619825185;5781.82654520365;5800.535957123504;5801.128441421523;5814.215080964101;5820.388859169726;5342.9465705342445;5587.3015873015875;5634.943650563442;5659.179418984142;5698.430156984434;5526.889462210814;5565.749541270571;5569.248151499658;5570.58220633446;5622.634528755618;5745.2818397700285;5845.18167456548;5882.735276912635;5904.61619994699;5912.320083190488;3940.133170702441;3986;3996.9200615988098;3999.280129576704;4005.2189822984237;5670.347909990239;5697.208367899782;5713.9428605715075;5715.342735585461;5715.514181294637;5444.482774136451;5497.038018346853;5562.694051827027;5572.60991730575;5578.693171875586;5449.182622606482;5501.752421141138;5513.145462453326;5518.42056584069;5538.418466061005;5209;5248.685078895279;5429.448159365092;5462.863428414163;5472.699001554953;5496.203050557567;5522.2544956430365;5546.944530554642;5550.639208451437;5558.276444542226;5420.701861397503;5427.402985671652;5438.809641662523;5451.700156491431;5477.616566840442 19:19:20 INFO - PROCESS | 12830 | |5;yui.html;804.1395706593944;815.5025434484963;826.0500424511811;833.4291010657698;846.5809424334955;14834.48079317224;15534;15762.448314308987;15777.684446311066;15811.288492017859;14493.49272775453;14539.054961427526;14549.563513094598;14555.854441455582;14563.1990240537;691.3501308769766;810.7648781853255;826.4917075998278;829.2163905109674;839.3369238301734;818.602977555888;824.5217773691274;830.0371568979984;830.161536847782;832.488019867783;872.650939624149;874.9125087491232;883.1301168349171;883.8320719063389;883.8983516895576;727.487121579288;850.706506255344;862.8360611483861;870.9738707838743;875.9562021898879;634.3688030409726;635.4884318123908;635.8537536366662;635.8664680417118;636.3541005879036;14729.55811325662;14784.556463306119;14806.629834254121;14850.554483365517;14908.478203262943;13727.039107262495;13802.447902084004;13838.654033649138;13860.306984650828;13890.93054534731;12227.633171004883;12272.877271227351;12492.500299987989;12548.18436801605;12598.874011259863;4407.801648925774;4456.064226512436;4460.598546130864;4489.842855500075;4630.120277147331;56855.14717279244;57078.42921570773;57693.711531442495;57911.13133303004;58450.70774646142;11071.833922491169;11122.888771112186;11192;11251.493682784294;11398.772024559628;20303.28938487146;20682.896585517276;20685.17259309641;20766.273180438613;20881.89559052195;8189.8771518427275;8415.705450309331;8582;8654.956725216334;8658;4486.663500237495;4695.154872122982;4697.271922851964;4739.289106634033;4840.467548569654;3404.982975085158;3486.89539313821;3501.649835016529;3505.018594793462;3512.1921957949303;629.9307076221612;631.6746875359194;633.6863252689948;650.6616559389091;653.128074021187;668.6422763821332;744.0313150529457;749.2133260076897;758.2266088589607;764.1594246329105;9370.484623345645;9981.80036399268;10004.849927251096;10101.19190464761;10198.592056317813;732.1470486882829;740.1303468424579;743.1008479739468;748.4536288509419;763.267263427105;4421.5578442156175;4547.15877562652;4617;4686.343911852344;4758.95241047592;70302.2969770306;72045.27954720386;72123.27876721165;72175.63912180405;72267;63588.36411635917;63603.04595431072;65590.67204663946;65894;66259;57800.132998005065;57943;58974;59815.401845981854;60240.5783479489;9323.627054917728;9575.521223938915;9583.71637555449;9588.424694518351;9635.084666956627;36323.45514817333;36568.45147322739;36655.81672091623;37659.43510847285;37797;49353.25970110523;50456.24315635342;50646.493535064175;51297.487025129274;51816.48183518116;11950.46222919988;12063.21589096706;12227.510899563918;12350.938245308716;12695.746085078063;13256.536021239595;13305.46778128864;13575.932120339336;14211.786823197453;14238;12436.440360183633;12592.433340499516;12651.746965060831;13068.673283167996;13368.598942031755;12585.811212831997;12754.107212495408;12904.483820647069;13138.540151094665;13269;12079.27524348542;12457.875421246034;12464.376781160725;12593.93703031479;13005.934970325088;10190.490475476345;10287.22845786554;10514.579416823548;10715.24993250465;10834.783304334027;10296.433696146783;10381.792364152825;10788.136949044214;10888.836667449836;10993.340399576051 19:19:20 INFO - PROCESS | 12830 | __end_tp_report 19:19:20 INFO - PROCESS | 12830 | __start_cc_report 19:19:20 INFO - PROCESS | 12830 | _x_x_mozilla_cycle_collect,886 19:19:20 INFO - PROCESS | 12830 | __end_cc_report 19:19:20 INFO - PROCESS | 12830 | __startTimestamp1463797160728__endTimestamp 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | ------- Summary: start ------- 19:19:20 INFO - PROCESS | 12830 | Number of tests: 6 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | [#0] dojo.html Cycles:165 Average:5439.49 Median:5600.92 stddev:623.07 (11.1%) stddev-sans-first:598.01 19:19:20 INFO - PROCESS | 12830 | Values: 3127.9 3140.1 3175.4 3177.1 3179.5 5344.5 5408.3 5422.6 5465.1 5491.6 5481.7 5486.7 5488.0 5527.5 5547.5 4926.3 4937.2 5086.7 5152.9 5163.9 5076.8 5082.5 5133.2 5152.2 5157.0 4841.9 4934.7 4959.8 5003.8 5008.8 5005.1 5015.8 5016.2 5030.7 5061.3 4899.3 4963.7 4986.9 4992.0 5049.4 5425.3 5487.9 5498.7 5575.3 5582.3 5939.4 5974.7 6003.6 6009.0 6011.1 5786.6 5804.2 6047.9 6051.7 6052.3 5462.1 5514.7 5548.9 5553.2 5560.0 6042.8 6043.4 6048.1 6052.7 6052.8 5596.2 5606.2 5615.9 5797.9 5803.1 5756.4 5760.3 5803.9 5812.0 5815.0 5679.1 5726.6 5727.7 5729.8 5735.0 5682.8 5687.6 5816.4 5830.4 5892.3 4455.3 4467.8 4469.8 4470.6 4503.5 3951.7 3974.3 3976.1 4013.9 4029.8 5351.5 5387.9 5478.8 5534.3 5543.6 5908.9 5943.8 5949.3 5954.1 6011.2 5548.0 5560.3 5567.0 5568.4 5573.7 4738.6 4798.6 4808.2 4813.2 4819.6 5975.2 5976.1 5976.9 5982.4 5983.9 5691.2 5785.1 5807.9 5816.4 5818.7 5981.6 6022.6 6054.9 6108.8 6110.7 5802.1 5808.4 5813.2 5817.2 5817.8 5838.2 5859.2 5872.5 5931.7 5940.6 5724.1 5780.1 5781.8 5786.7 5834.5 5785.2 5793.7 5793.8 5799.1 5807.3 5514.9 5537.6 5553.1 5696.3 5708.1 5725.5 5727.5 5736.0 5760.9 5782.3 5579.9 5588.7 5588.9 5600.9 5658.3 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | [#1] ext.html Cycles:180 Average:59036.14 Median:42045.08 stddev:111568.58 (265.4%) stddev-sans-first:111795.32 19:19:20 INFO - PROCESS | 12830 | Values: 610.0 632.6 652.8 654.3 666.9 33300.5 43228.0 44519.3 44561.0 47312.6 45255.5 45761.3 45791.5 45820.3 45835.0 8555.8 8658.9 8698.7 8712.4 8726.0 8718.6 8871.6 8890.6 8898.4 8900.2 8902.7 8970.1 8976.4 8982.6 8996.6 8659.2 8694.7 8709.2 8718.8 8725.3 8714.7 8828.5 8854.3 8866.5 8870.6 41584.6 41711.6 41732.2 41845.4 41860.8 3313.9 3380.7 3457.4 3563.8 4063.8 30711.7 31518.1 33335.7 33347.2 33399.7 4247.0 4388.5 4403.6 4430.8 4439.3 339699.0 345296.0 347263.0 347322.0 347681.3 65565.7 65787.3 66455.3 66482.3 66519.0 68800.0 68872.3 69181.7 69433.7 69469.3 17384.0 17573.8 17587.7 17606.6 17608.2 13182.9 13274.0 13281.7 13293.3 13328.0 19345.5 20828.0 20943.3 20986.3 21004.7 6727.8 7679.4 7835.4 7878.8 7913.2 961.6 982.7 1044.1 1074.3 1092.6 31571.8 32038.0 32068.7 32148.7 32175.4 1131.9 1180.9 1204.2 1244.4 1246.0 16413.2 16688.7 16742.0 16779.2 16826.4 501628.0 504632.0 506579.0 508610.0 508616.0 59695.1 61256.7 61437.4 61519.4 61528.0 1270.4 1302.6 1308.3 1408.1 1413.0 27913.4 33364.0 33698.8 33705.3 33894.0 50024.7 51534.0 51580.5 51588.2 51714.0 390850.0 399237.0 399290.0 400228.0 404275.0 25807.4 28220.2 29005.9 29174.7 29396.4 44840.3 47547.0 49041.8 50100.0 50255.2 39837.4 42027.6 42062.6 42499.8 43107.0 41662.4 41710.0 42469.8 42626.0 42788.6 40617.8 41220.4 41833.2 42167.2 44313.6 34398.3 37280.4 37308.3 37919.4 38139.6 27996.6 28420.1 28657.0 28838.6 28970.7 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | [#2] jquery.html Cycles:180 Average:28644.09 Median:19372.39 stddev:82081.19 (423.7%) stddev-sans-first:82284.01 19:19:20 INFO - PROCESS | 12830 | Values: 382.5 384.8 397.5 397.9 401.9 18022.5 18888.6 20540.2 20562.0 20587.3 11848.1 12155.9 12367.6 12519.7 12549.9 5088.5 5256.7 5263.3 5271.1 5286.5 3807.4 5528.6 5819.3 5828.7 5914.3 5706.6 5840.9 5853.2 5890.5 5906.7 5677.3 5740.7 5817.3 5829.1 5829.3 3723.9 5318.5 5633.2 5645.5 5697.3 13924.0 13934.2 16124.8 23115.3 23127.0 2160.1 2166.6 2191.6 2310.4 2460.1 9466.6 10233.5 10612.5 10655.0 11438.1 1933.6 1985.1 2038.8 2053.3 2184.5 78120.6 81672.6 82035.6 83008.0 83419.6 10909.7 10932.5 11220.4 11275.7 11384.1 15479.6 15488.1 15554.3 15556.5 15599.5 7881.8 7906.8 8012.0 8071.7 8107.7 7401.3 7512.6 7599.1 7841.1 8184.7 4790.4 4793.8 4800.4 4813.1 4899.3 1820.1 1824.6 1986.6 1997.3 2028.8 918.2 982.7 1006.7 1032.9 1088.7 18620.3 19030.5 19457.5 19528.1 19658.8 956.6 965.6 966.8 973.1 996.5 10920.5 10981.6 10992.5 11044.6 11121.2 486273.0 491667.0 494857.0 508242.0 510021.0 85947.0 87750.1 88225.6 88753.6 88959.8 942.6 964.1 969.7 973.9 983.9 16811.7 16927.4 17287.8 17377.7 17447.7 38360.0 38677.0 38695.8 39919.4 40081.6 54530.7 56763.0 56963.4 57063.4 57080.7 7360.1 7674.2 8357.6 8624.7 8723.5 12847.2 13017.3 13254.4 13324.1 13732.7 11320.9 11630.7 11638.5 11694.6 11876.7 11254.4 11456.6 11500.3 11774.6 12022.5 11343.5 11500.8 11502.3 11713.0 12151.4 7668.6 7706.0 7727.6 7816.5 7833.4 11330.7 11425.5 11652.3 11745.4 11745.6 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | [#3] mootools.html Cycles:180 Average:5251.05 Median:4921.67 stddev:2942.79 (59.8%) stddev-sans-first:2936.70 19:19:20 INFO - PROCESS | 12830 | Values: 1384.3 1474.8 1525.2 1643.3 1746.2 7472.9 7822.2 7864.4 8049.4 8050.2 7831.8 8004.1 8018.3 8019.6 8021.4 1943.3 2084.7 2117.4 2128.6 2213.3 2156.6 2171.4 2174.4 2178.4 2182.0 2186.3 2223.6 2228.1 2228.5 2232.4 1899.7 2101.4 2166.0 2172.1 2185.8 2062.3 2079.3 2081.8 2084.8 2087.1 7703.4 7747.9 7761.0 7766.5 7770.6 4790.4 4898.8 4905.6 4914.7 4928.7 3039.7 3142.4 3146.4 3166.6 3194.9 995.2 1013.5 1026.3 1034.0 1057.8 10441.2 10757.5 10773.9 11081.4 11094.5 4103.0 4139.2 4187.5 4224.5 4227.1 8794.3 8812.7 8819.4 8834.5 8834.7 4385.7 4841.9 4842.4 4861.4 4861.8 4460.8 4531.0 4564.1 4566.3 4584.9 3672.5 3691.2 3772.5 3794.7 3808.9 1093.8 1105.4 1107.1 1113.0 1122.4 869.6 894.4 912.3 922.2 933.5 5204.9 5287.0 5297.8 5356.1 5356.9 419.9 427.8 429.6 431.7 440.6 3550.8 3636.8 3638.5 3639.5 3659.2 9606.1 9827.4 9911.5 9923.8 9948.4 9570.4 9627.3 9666.7 9671.1 9684.1 7498.7 7580.3 7614.8 7671.5 7700.8 5318.4 5357.7 5405.2 5482.6 5550.7 8976.5 9073.2 9113.8 9132.6 9145.5 8928.0 9200.2 9352.5 9453.7 9504.3 6230.4 6697.2 6870.2 6914.7 6967.6 7644.3 7646.6 7721.8 7727.8 7741.5 6992.5 7229.5 7246.9 7338.2 7364.6 7078.5 7085.8 7112.6 7170.6 7198.2 6296.0 6504.5 6514.7 6590.8 6662.5 4822.6 4854.8 4904.0 4971.8 4989.3 4763.2 4773.1 4816.4 4818.2 4911.9 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | [#4] prototype.html Cycles:180 Average:4616.52 Median:5265.88 stddev:1349.69 (25.6%) stddev-sans-first:1314.37 19:19:20 INFO - PROCESS | 12830 | Values: 319.1 321.8 324.8 332.2 332.7 5256.7 5287.6 5296.3 5301.0 5336.8 5197.2 5200.7 5214.5 5233.9 5254.5 3929.9 4002.1 4073.6 4097.3 4124.6 4080.0 4093.0 4094.2 4116.6 4123.2 3946.2 4094.9 4100.5 4103.2 4126.1 3999.2 4076.4 4084.9 4114.4 4134.3 3635.3 3636.9 3637.1 3661.4 3674.3 5031.3 5041.4 5059.3 5097.8 5104.4 4494.3 4628.6 4650.0 4665.3 4678.9 5378.8 5386.6 5394.2 5398.2 5408.5 4000.5 4016.1 4022.8 4024.2 4027.2 5386.2 5667.5 5677.8 5689.9 5701.9 3939.9 3941.8 3954.6 3961.9 3970.3 4013.7 4015.4 4022.0 4022.8 4043.6 4440.0 4572.9 4579.5 4644.3 4657.0 5412.5 5419.3 5445.9 5453.5 5493.1 1403.5 1422.2 1427.7 1431.2 1442.9 932.6 943.3 979.5 1003.1 1005.4 5183.7 5198.9 5237.4 5275.1 5289.5 5521.8 5549.2 5572.5 5595.8 5617.6 5021.8 5216.3 5244.0 5303.0 5307.3 2974.4 2986.8 2998.6 3010.7 3013.9 5470.8 5710.7 5732.7 5765.8 5786.3 5635.2 5673.4 5675.4 5692.7 5699.2 5781.8 5800.5 5801.1 5814.2 5820.4 5342.9 5587.3 5634.9 5659.2 5698.4 5526.9 5565.7 5569.2 5570.6 5622.6 5745.3 5845.2 5882.7 5904.6 5912.3 3940.1 3986.0 3996.9 3999.3 4005.2 5670.3 5697.2 5713.9 5715.3 5715.5 5444.5 5497.0 5562.7 5572.6 5578.7 5449.2 5501.8 5513.1 5518.4 5538.4 5209.0 5248.7 5429.4 5462.9 5472.7 5496.2 5522.3 5546.9 5550.6 5558.3 5420.7 5427.4 5438.8 5451.7 5477.6 19:19:20 INFO - PROCESS | 12830 | 19:19:20 INFO - PROCESS | 12830 | [#5] yui.html Cycles:180 Average:16157.12 Median:4623.56 stddev:19549.53 (422.8%) stddev-sans-first:19570.37 19:19:20 INFO - PROCESS | 12830 | Values: 804.1 815.5 826.1 833.4 846.6 14834.5 15534.0 15762.4 15777.7 15811.3 14493.5 14539.1 14549.6 14555.9 14563.2 691.4 810.8 826.5 829.2 839.3 818.6 824.5 830.0 830.2 832.5 872.7 874.9 883.1 883.8 883.9 727.5 850.7 862.8 871.0 876.0 634.4 635.5 635.9 635.9 636.4 14729.6 14784.6 14806.6 14850.6 14908.5 13727.0 13802.4 13838.7 13860.3 13890.9 12227.6 12272.9 12492.5 12548.2 12598.9 4407.8 4456.1 4460.6 4489.8 4630.1 56855.1 57078.4 57693.7 57911.1 58450.7 11071.8 11122.9 11192.0 11251.5 11398.8 20303.3 20682.9 20685.2 20766.3 20881.9 8189.9 8415.7 8582.0 8655.0 8658.0 4486.7 4695.2 4697.3 4739.3 4840.5 3405.0 3486.9 3501.6 3505.0 3512.2 629.9 631.7 633.7 650.7 653.1 668.6 744.0 749.2 758.2 764.2 9370.5 9981.8 10004.8 10101.2 10198.6 732.1 740.1 743.1 748.5 763.3 4421.6 4547.2 4617.0 4686.3 4759.0 70302.3 72045.3 72123.3 72175.6 72267.0 63588.4 63603.0 65590.7 65894.0 66259.0 57800.1 57943.0 58974.0 59815.4 60240.6 9323.6 9575.5 9583.7 9588.4 9635.1 36323.5 36568.5 36655.8 37659.4 37797.0 49353.3 50456.2 50646.5 51297.5 51816.5 11950.5 12063.2 12227.5 12350.9 12695.7 13256.5 13305.5 13575.9 14211.8 14238.0 12436.4 12592.4 12651.7 13068.7 13368.6 12585.8 12754.1 12904.5 13138.5 13269.0 12079.3 12457.9 12464.4 12593.9 13005.9 10190.5 10287.2 10514.6 10715.2 10834.8 10296.4 10381.8 10788.1 10888.8 10993.3 19:19:20 INFO - PROCESS | 12830 | -------- Summary: end -------- 19:19:20 INFO - PROCESS | 12830 | 19:19:21 INFO - TEST-INFO | 12830: exit 0 19:19:21 INFO - TEST-OK | dromaeo_css | took 1111756ms 19:19:21 INFO - TEST-START | kraken 19:19:21 INFO - operating with platform_type : linux_ 19:19:21 INFO - Initialising browser for kraken test... 19:19:21 INFO - TEST-INFO | started process 13015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFT5Hyd/profile http://localhost:52562/getInfo.html) 19:19:25 INFO - PROCESS | 13015 | __metrics Screen width/height:1600/1200 19:19:25 INFO - PROCESS | 13015 | colorDepth:24 19:19:25 INFO - PROCESS | 13015 | Browser inner width/height: 1024/697 19:19:25 INFO - PROCESS | 13015 | __metrics 19:19:26 ERROR - PROCESS | 13015 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:19:26 INFO - TEST-INFO | 13015: exit 0 19:19:26 INFO - Browser initialized. 19:19:26 INFO - Running cycle 1/1 for kraken test... 19:19:26 INFO - TEST-INFO | started process 13098 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFT5Hyd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:38 INFO - PROCESS | 13098 | RSS: Main: 152793088 19:19:38 INFO - PROCESS | 13098 | 19:19:41 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52562/tests/kraken/driver.html?testName=audio-beat-detection) 19:19:42 INFO - PROCESS | 13098 | RSS: Main: 209866752 19:19:42 INFO - PROCESS | 13098 | 19:19:44 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52562/tests/kraken/driver.html?testName=audio-dft) 19:19:45 INFO - PROCESS | 13098 | RSS: Main: 198144000 19:19:45 INFO - PROCESS | 13098 | 19:19:48 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52562/tests/kraken/driver.html?testName=audio-fft) 19:19:49 INFO - PROCESS | 13098 | RSS: Main: 266502144 19:19:49 INFO - PROCESS | 13098 | 19:19:51 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52562/tests/kraken/driver.html?testName=audio-oscillator) 19:19:52 INFO - PROCESS | 13098 | RSS: Main: 175505408 19:19:52 INFO - PROCESS | 13098 | 19:19:54 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52562/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:19:54 INFO - PROCESS | 13098 | RSS: Main: 179793920 19:19:54 INFO - PROCESS | 13098 | 19:19:58 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52562/tests/kraken/driver.html?testName=imaging-darkroom) 19:19:58 INFO - PROCESS | 13098 | RSS: Main: 215732224 19:19:58 INFO - PROCESS | 13098 | 19:20:02 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52562/tests/kraken/driver.html?testName=imaging-desaturate) 19:20:02 INFO - PROCESS | 13098 | RSS: Main: 213065728 19:20:02 INFO - PROCESS | 13098 | 19:20:05 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52562/tests/kraken/driver.html?testName=json-parse-financial) 19:20:06 INFO - PROCESS | 13098 | RSS: Main: 214417408 19:20:06 INFO - PROCESS | 13098 | 19:20:08 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52562/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:20:08 INFO - PROCESS | 13098 | RSS: Main: 202149888 19:20:08 INFO - PROCESS | 13098 | 19:20:10 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:20:11 INFO - PROCESS | 13098 | RSS: Main: 205238272 19:20:11 INFO - PROCESS | 13098 | 19:20:13 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:20:13 INFO - PROCESS | 13098 | RSS: Main: 215076864 19:20:13 INFO - PROCESS | 13098 | 19:20:16 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:20:17 INFO - PROCESS | 13098 | RSS: Main: 225132544 19:20:17 INFO - PROCESS | 13098 | 19:20:19 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:20:20 INFO - PROCESS | 13098 | RSS: Main: 221126656 19:20:20 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | Cycle 1(1): loaded http://localhost:52562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52562/tests/kraken/driver.html?testName=ai-astar) 19:20:22 INFO - PROCESS | 13098 | RSS: Main: 267497472 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | __start_tp_report 19:20:22 INFO - PROCESS | 13098 | _x_x_mozilla_page_load 19:20:22 INFO - PROCESS | 13098 | _x_x_mozilla_page_load_details 19:20:22 INFO - PROCESS | 13098 | |i|pagename|runs| 19:20:22 INFO - PROCESS | 13098 | |0;ai-astar;111;106;110;110;111;109;110;109;110;109 19:20:22 INFO - PROCESS | 13098 | |1;audio-beat-detection;152;126;126;208;124;129;156;124;194;123 19:20:22 INFO - PROCESS | 13098 | |2;audio-dft;161;160;160;160;160;161;161;162;161;161 19:20:22 INFO - PROCESS | 13098 | |3;audio-fft;75;76;75;131;74;76;73;75;118;76 19:20:22 INFO - PROCESS | 13098 | |4;audio-oscillator;92;91;155;92;92;92;130;92;93;138 19:20:22 INFO - PROCESS | 13098 | |5;imaging-gaussian-blur;116;116;116;117;117;116;116;117;116;117 19:20:22 INFO - PROCESS | 13098 | |6;imaging-darkroom;146;146;145;146;145;145;146;147;146;146 19:20:22 INFO - PROCESS | 13098 | |7;imaging-desaturate;95;95;94;95;95;96;95;94;94;95 19:20:22 INFO - PROCESS | 13098 | |8;json-parse-financial;55;54;53;55;55;54;54;110;53;52 19:20:22 INFO - PROCESS | 13098 | |9;json-stringify-tinderbox;61;61;60;61;63;60;61;60;61;61 19:20:22 INFO - PROCESS | 13098 | |10;stanford-crypto-aes;83;80;79;78;85;82;81;85;86;86 19:20:22 INFO - PROCESS | 13098 | |11;stanford-crypto-ccm;120;120;134;122;125;117;118;119;192;122 19:20:22 INFO - PROCESS | 13098 | |12;stanford-crypto-pbkdf2;162;163;159;165;156;158;158;163;165;159 19:20:22 INFO - PROCESS | 13098 | |13;stanford-crypto-sha256-iterative;64;61;63;67;61;60;64;62;60;61 19:20:22 INFO - PROCESS | 13098 | __end_tp_report 19:20:22 INFO - PROCESS | 13098 | __start_cc_report 19:20:22 INFO - PROCESS | 13098 | _x_x_mozilla_cycle_collect,1873 19:20:22 INFO - PROCESS | 13098 | __end_cc_report 19:20:22 INFO - PROCESS | 13098 | __startTimestamp1463797222538__endTimestamp 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | ------- Summary: start ------- 19:20:22 INFO - PROCESS | 13098 | Number of tests: 14 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.43 (1.3%) stddev-sans-first:1.41 19:20:22 INFO - PROCESS | 13098 | Values: 111.0 106.0 110.0 110.0 111.0 109.0 110.0 109.0 110.0 109.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#1] audio-beat-detection Cycles:10 Average:146.20 Median:140.50 stddev:31.39 (22.3%) stddev-sans-first:33.23 19:20:22 INFO - PROCESS | 13098 | Values: 152.0 126.0 126.0 208.0 124.0 129.0 156.0 124.0 194.0 123.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 19:20:22 INFO - PROCESS | 13098 | Values: 161.0 160.0 160.0 160.0 160.0 161.0 161.0 162.0 161.0 161.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#3] audio-fft Cycles:10 Average:84.90 Median:75.00 stddev:21.12 (28.2%) stddev-sans-first:22.09 19:20:22 INFO - PROCESS | 13098 | Values: 75.0 76.0 75.0 131.0 74.0 76.0 73.0 75.0 118.0 76.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#4] audio-oscillator Cycles:10 Average:106.70 Median:92.00 stddev:24.43 (26.6%) stddev-sans-first:25.32 19:20:22 INFO - PROCESS | 13098 | Values: 92.0 91.0 155.0 92.0 92.0 92.0 130.0 92.0 93.0 138.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:20:22 INFO - PROCESS | 13098 | Values: 116.0 116.0 116.0 117.0 117.0 116.0 116.0 117.0 116.0 117.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 19:20:22 INFO - PROCESS | 13098 | Values: 146.0 146.0 145.0 146.0 145.0 145.0 146.0 147.0 146.0 146.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 19:20:22 INFO - PROCESS | 13098 | Values: 95.0 95.0 94.0 95.0 95.0 96.0 95.0 94.0 94.0 95.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:54.00 stddev:17.77 (32.9%) stddev-sans-first:18.77 19:20:22 INFO - PROCESS | 13098 | Values: 55.0 54.0 53.0 55.0 55.0 54.0 54.0 110.0 53.0 52.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.88 (1.4%) stddev-sans-first:0.93 19:20:22 INFO - PROCESS | 13098 | Values: 61.0 61.0 60.0 61.0 63.0 60.0 61.0 60.0 61.0 61.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:84.00 stddev:2.95 (3.5%) stddev-sans-first:3.13 19:20:22 INFO - PROCESS | 13098 | Values: 83.0 80.0 79.0 78.0 85.0 82.0 81.0 85.0 86.0 86.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#11] stanford-crypto-ccm Cycles:10 Average:128.90 Median:122.00 stddev:22.69 (18.6%) stddev-sans-first:23.84 19:20:22 INFO - PROCESS | 13098 | Values: 120.0 120.0 134.0 122.0 125.0 117.0 118.0 119.0 192.0 122.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:162.50 stddev:3.19 (2.0%) stddev-sans-first:3.35 19:20:22 INFO - PROCESS | 13098 | Values: 162.0 163.0 159.0 165.0 156.0 158.0 158.0 163.0 165.0 159.0 19:20:22 INFO - PROCESS | 13098 | 19:20:22 INFO - PROCESS | 13098 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:2.21 (3.5%) stddev-sans-first:2.26 19:20:22 INFO - PROCESS | 13098 | Values: 64.0 61.0 63.0 67.0 61.0 60.0 64.0 62.0 60.0 61.0 19:20:22 INFO - PROCESS | 13098 | -------- Summary: end -------- 19:20:22 INFO - PROCESS | 13098 | 19:20:23 INFO - TEST-INFO | 13098: exit 0 19:20:23 INFO - TEST-OK | kraken | took 62003ms 19:20:23 INFO - SUITE-END | took 1173s 19:20:23 INFO - Completed test suite (00:19:33) 19:20:23 INFO - javascript benchmark 19:20:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.937441251174, 3140.120766185468, 3175.380800743856, 3177.062766483887, 3179.491281394976, 5344.545713614348, 5408.296921400222, 5422.566194704424, 5465.098258787295, 5491.615586908918, 5481.698506582136, 5486.698231597261, 5488.039593071207, 5527.474889885464, 5547.472990065948, 4926.334944782453, 4937.185364414867, 5086.745662716868, 5152.9484705152845, 5163.922541161885, 5076.796928122871, 5082.466341034194, 5133.204353325222, 5152.175651895697, 5157.0, 4841.903161936759, 4934.679245849026, 4959.752012399365, 5003.849884503471, 5008.824691135793, 5005.099082165245, 5015.824446144404, 5016.172331565287, 5030.723310217936, 5061.316722242487, 4899.2896030075435, 4963.70217786934, 4986.90026199474, 4991.950080499221, 5049.419316778581, 5425.294711687495, 5487.917681234785, 5498.697571633558, 5575.275214222166, 5582.27430434041, 5939.4357536034, 5974.701264936779, 6003.6097653652805, 6009.0, 6011.068284415923, 5786.623869448473, 5804.187413762077, 6047.939520604782, 6051.697415129226, 6052.303985041733, 5462.0987537056335, 5514.724263786795, 5548.86127846803, 5553.222548843165, 5559.972200138974, 6042.818715438456, 6043.365446628129, 6048.092786082123, 6052.697365131726, 6052.818415447544, 5596.2165296858475, 5606.243157173811, 5615.943840561624, 5797.942020579824, 5803.129530570448, 5756.395578464201, 5760.337561180434, 5803.854903627359, 5811.953848307261, 5815.04051831443, 5679.091345384719, 5726.599138060295, 5727.684977326204, 5729.799457019068, 5735.0, 5682.829515114553, 5687.630304030225, 5816.447437493432, 5830.416958304136, 5892.322382925932, 4455.331700244989, 4467.8212871484775, 4469.798859051285, 4470.642348612103, 4503.482099558527, 3951.7036222283446, 3974.28462876685, 3976.0855003349393, 4013.8996525086523, 4029.81865816039, 5351.545118664879, 5387.9461205388225, 5478.808241711601, 5534.252875861787, 5543.6396634218645, 5908.940910590925, 5943.821685349447, 5949.25634295713, 5954.07711804671, 6011.158437818709, 5548.029094908375, 5560.277163968739, 5567.025770490148, 5568.443155684399, 5573.721313934287, 4738.620910327166, 4798.616110711135, 4808.206645903387, 4813.229883218668, 4819.614430845524, 5975.223220981245, 5976.103584462278, 5976.850578735566, 5982.431668991526, 5983.910241346383, 5691.23168372281, 5785.1322301654245, 5807.8548036299435, 5816.418358164319, 5818.679972601378, 5981.611195272293, 6022.608530445507, 6054.878902422015, 6108.786192483241, 6110.694465276807, 5802.100674395476, 5808.361080281081, 5813.15709222152, 5817.185594016756, 5817.767289308549, 5838.241028666246, 5859.179714839841, 5872.471477567039, 5931.703414829328, 5940.55445841555, 5724.084146536701, 5780.132980053026, 5781.82654520365, 5786.710664466676, 5834.504067154339, 5785.190073389813, 5793.739281732248, 5793.768249270151, 5799.130130480462, 5807.332156802022, 5514.91727624094, 5537.5569954402745, 5553.111502159635, 5696.316442026983, 5708.143778433268, 5725.456081672234, 5727.513161381329, 5735.97132014354, 5760.913586296293, 5782.335031471266, 5579.944200558105, 5588.692621905834, 5588.944110558842, 5600.915986260291, 5658.264425624642], "value": 5397.033340685533, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.9725512351944, 632.6457183976974, 652.7584793626359, 654.2606854254692, 666.8932970724682, 33300.50049249263, 43228.0, 44519.33221001687, 44561.0, 47312.634368281535, 45255.547444525546, 45761.31358029632, 45791.542084579145, 45820.3126953096, 45835.0, 8555.828883422328, 8658.913410865905, 8698.739037828875, 8712.390132690714, 8725.956370218171, 8718.564071796385, 8871.600777964975, 8890.555472226362, 8898.37711360205, 8900.154485323885, 8902.732918012438, 8970.058143894896, 8976.416532925372, 8982.550872456384, 8996.550172491381, 8659.177378149066, 8694.652213911437, 8709.172628600336, 8718.782030449225, 8725.301975841949, 8714.69498567547, 8828.514431706253, 8854.335924805666, 8866.512341821197, 8870.55647217643, 41584.58415415837, 41711.582884171075, 41732.165356692996, 41845.37231941523, 41860.79069604663, 3313.867445302185, 3380.678835510623, 3457.4122399192197, 3563.750537462379, 4063.8374465021366, 30711.692883071108, 31518.054458366285, 33335.6666433335, 33347.16632084193, 33399.6660033399, 4247.0, 4388.517263101056, 4403.559644035603, 4430.756308403036, 4439.2675208590545, 339699.0, 345296.0, 347262.9999999975, 347322.0, 347681.26159369294, 65565.67217163884, 65787.34212657908, 66455.33544664588, 66482.33517664859, 66519.0, 68800.0, 68872.31127688658, 69181.65409173022, 69433.65283173551, 69469.3053069473, 17384.0, 17573.824261757476, 17587.73618395725, 17606.559836003948, 17608.207630656652, 13182.868171318356, 13274.0, 13281.667958300926, 13293.26887021223, 13328.0, 19345.51636209106, 20828.0, 20943.266985655733, 20986.2654807081, 21004.684929726067, 6727.764528241488, 7679.385649148055, 7835.412344074217, 7878.842423151505, 7913.169117242722, 961.6393852305386, 982.6855406270068, 1044.1490185498947, 1074.333912973961, 1092.5848177692426, 31571.842140789147, 32038.0, 32068.679313207034, 32148.678513214567, 32175.35649287048, 1131.8924702153283, 1180.8701042885275, 1204.2292932523192, 1244.3902487780922, 1245.975080498385, 16413.1793410329, 16688.6662266754, 16742.0, 16779.244933978, 16826.411075612294, 501628.0, 504632.0, 506579.0, 508610.0, 508616.0, 59695.10457343143, 61256.69371653203, 61437.38562614406, 61519.38480615226, 61528.0, 1270.4283072617175, 1302.615728360136, 1308.2542950518305, 1408.0777091005202, 1412.9788053179004, 27913.441731164858, 33364.0, 33698.80957737739, 33705.325893482484, 33894.0, 50024.74987625039, 51534.0, 51580.48419515907, 51588.22617660663, 51714.0, 390850.0, 399237.0, 399290.0, 400228.0, 404275.0, 25807.354816128995, 28220.15339539817, 29005.854970725013, 29174.7082529172, 29396.41207175802, 44840.32739508845, 47547.0, 49041.754791225816, 50100.0, 50255.246171308194, 39837.40243896286, 42027.57972420359, 42062.57937420587, 42499.787501062296, 43107.0, 41662.37506437345, 41710.0, 42469.787651061546, 42626.0, 42788.572114279705, 40617.796911015255, 41220.38169427401, 41833.163336733705, 42167.15665686731, 44313.556864430946, 34398.312033759685, 37280.44079338758, 37308.25383492369, 37919.43120853134, 38139.61860381471, 27996.600169991827, 28420.147395578166, 28657.0, 28838.567421489115, 28970.7102928968], "value": 20472.54902949361, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.4970164234032, 384.7825978322246, 397.5309135220442, 397.8726807421625, 401.90555219523407, 18022.5494362641, 18888.62222755544, 20540.178392864305, 20562.0, 20587.27944521942, 11848.052155827534, 12155.878441215586, 12367.567135150271, 12519.749605007893, 12549.937250313758, 5088.465711100327, 5256.737163141846, 5263.34208223972, 5271.130263506536, 5286.524212820846, 3807.3527500325013, 5528.55771538278, 5819.30168379795, 5828.679422631753, 5914.260717410324, 5706.572007099463, 5840.883182336351, 5853.209816674737, 5890.499307558841, 5906.704664766766, 5677.290338707661, 5740.712964351807, 5817.331006934214, 5829.12563115534, 5829.329627092875, 3723.94414083789, 5318.547923426513, 5633.211350410946, 5645.491905728504, 5697.344805347397, 13924.0, 13934.233617151154, 16124.838751612568, 23115.306540803802, 23127.0, 2160.114353115215, 2166.599179151862, 2191.561687662474, 2310.3761984264156, 2460.1020627470843, 9466.57400416976, 10233.539490722935, 10612.522436490377, 10655.041046305869, 11438.142139339583, 1933.622943526013, 1985.0967809646577, 2038.7655419626785, 2053.301877361689, 2184.530325979906, 78120.60939695266, 81672.59163704203, 82035.5898220511, 83008.0, 83419.5829020857, 10909.727256818484, 10932.508037138348, 11220.382878941737, 11275.718107047389, 11384.146189035855, 15479.613009674624, 15488.14815185153, 15554.300056497485, 15556.455524056604, 15599.454019109276, 7881.763547093597, 7906.802329941797, 8012.0, 8071.717489887826, 8107.675692972333, 7401.296876796696, 7512.586807725518, 7599.050118735158, 7841.137474877759, 8184.6726130955285, 4790.4251489821445, 4793.832215872428, 4800.439857046404, 4813.085513752376, 4899.265110233494, 1820.1051923913546, 1824.616830465617, 1986.632472992501, 1997.270996086447, 2028.7768345481986, 918.2470374293149, 982.6904525074578, 1006.6929586476195, 1032.9018743219383, 1088.6951653537021, 18620.348287809862, 19030.524236894187, 19457.513562160777, 19528.12123454448, 19658.803411965982, 956.636478138303, 965.6089283840056, 966.7824739433571, 973.0804389851522, 996.5415908682088, 10920.453977301104, 10981.61564345244, 10992.505337259843, 11044.558217671363, 11121.220604703209, 486273.0, 491667.0, 494857.0, 508242.0, 510021.0, 85947.0, 87750.12249877419, 88225.55887220651, 88753.55623221843, 88959.77600560038, 942.5805516545208, 964.0841200859212, 969.699393188114, 973.8782652168479, 983.8524221366853, 16811.747823782407, 16927.40754073651, 17287.82712172862, 17377.739333909747, 17447.738283926006, 38360.0, 38677.0, 38695.80652096721, 39919.40120898247, 40081.599184007784, 54530.72734636301, 56763.0, 56963.42704363588, 57063.42936570581, 57080.714596426755, 7360.116785985716, 7674.155842857393, 8357.623906924082, 8624.741257762276, 8723.476591404537, 12847.164934279243, 13017.284049377375, 13254.403551839998, 13324.133931294822, 13732.65668358299, 11320.886791131983, 11630.6510804676, 11638.5344586219, 11694.649160525198, 11876.703082422662, 11254.38100904458, 11456.599019034295, 11500.309981401142, 11774.646760597196, 12022.519099235933, 11343.546258149581, 11500.769984600094, 11502.252353596989, 11713.0, 12151.392430378623, 7668.6165691716305, 7706.0, 7727.613619319124, 7816.531008139303, 7833.373330133461, 11330.716732081764, 11425.485853136463, 11652.30086194831, 11745.412729363668, 11745.647630571097], "value": 8929.07933279314, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.2663388404148, 1474.771410431383, 1525.153539785419, 1643.276958138419, 1746.2142036083758, 7472.925270747291, 7822.178671239516, 7864.449488535804, 8049.436539442241, 8050.154733752945, 7831.765047048598, 8004.119546849842, 8018.318442932343, 8019.639116239769, 8021.438499305051, 1943.2518480385083, 2084.6560317547624, 2117.364790562825, 2128.5636444528914, 2213.2806837777707, 2156.6010288096754, 2171.4245724882917, 2174.3803016140405, 2178.4227179797367, 2181.9890900545474, 2186.267600353883, 2223.6108680980847, 2228.0864845413385, 2228.4874478869788, 2232.352617740853, 1899.7150427435927, 2101.443117573844, 2165.956680866374, 2172.0985790896757, 2185.7595664476894, 2062.267894897305, 2079.334612923865, 2081.7814129516332, 2084.8123668869875, 2087.14427084896, 7703.422243331773, 7747.922520774777, 7761.0, 7766.495177813424, 7770.611469426506, 4790.42514898211, 4898.804047838083, 4905.6320775941595, 4914.680545764514, 4928.654994150411, 3039.7416219621136, 3142.402943440739, 3146.4179126861377, 3166.62000559932, 3194.8881789137267, 995.2187532786728, 1013.5236438873718, 1026.2508368890676, 1033.9896601034043, 1057.836035414512, 10441.164706823436, 10757.462126893624, 10773.892261077446, 11081.390523521202, 11094.500747466383, 4102.979485102615, 4139.19285739284, 4187.5184353799095, 4224.4508213931995, 4227.091175397304, 8794.252488538546, 8812.691555795516, 8819.382643214913, 8834.514101724339, 8834.734957951272, 4385.736855788599, 4841.8547443576745, 4842.394700662418, 4861.440934292531, 4861.829835955725, 4460.77696115193, 4531.048479819209, 4564.132814765184, 4566.337881007235, 4584.931226031612, 3672.5225720656144, 3691.206390626028, 3772.5095737553943, 3794.715396345285, 3808.9428658570146, 1093.8413929980109, 1105.4251789069638, 1107.0534692837507, 1113.04834366617, 1122.4275619433984, 869.599984007351, 894.4320356573622, 912.2610685344891, 922.2207234886511, 933.4819175357605, 5204.9219261711105, 5287.0, 5297.788088476418, 5356.0894647909945, 5356.8660783479945, 419.88939255668583, 427.8160391031824, 429.60806985367185, 431.7323259579018, 440.6474820143872, 3550.840212190406, 3636.7636103653176, 3638.5451818522683, 3639.47227651989, 3659.1949771050326, 9606.135447809591, 9827.4103553787, 9911.504424778732, 9923.801523969625, 9948.35335703177, 9570.425774453555, 9627.326087173831, 9666.661666841768, 9671.081247281498, 9684.08001239881, 7498.6625601848045, 7580.3177714004905, 7614.809629759189, 7671.501352412187, 7700.768976930701, 5318.4149743527405, 5357.651752636066, 5405.24326594285, 5482.616216864782, 5550.69471179089, 8976.506292153734, 9073.228775554151, 9113.77215569616, 9132.634694612141, 9145.451272923647, 8928.0, 9200.21798147165, 9352.485613291063, 9453.716388508356, 9504.334696571173, 6230.43926046658, 6697.196336439659, 6870.175578930561, 6914.688839002157, 6967.581945083311, 7644.312011918954, 7646.617669116633, 7721.7683469496005, 7727.845443091219, 7741.496802707806, 6992.545484543489, 7229.49393542468, 7246.927530724625, 7338.192798792022, 7364.5581265124265, 7078.504504684623, 7085.82285442868, 7112.644367781693, 7170.605616690923, 7198.244184360567, 6296.0, 6504.512161587994, 6514.706838192198, 6590.7693230736695, 6662.467002639681, 4822.614190864792, 4854.80580776765, 4903.950960490492, 4971.751412429292, 4989.326440930428, 4763.2378819390115, 4773.11697335994, 4816.373871396696, 4818.204996175592, 4911.877203069952], "value": 4159.345510400667, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.0762741862309, 321.78118879162173, 324.8294645311211, 332.18945772315533, 332.67730301607446, 5256.684598924057, 5287.629865909388, 5296.311479507659, 5300.973495132529, 5336.759845806938, 5197.220416937461, 5200.68795872247, 5214.478552144784, 5233.921491177626, 5254.527092561669, 3929.8821035368983, 4002.1395399988996, 4073.5926407359175, 4097.262492751303, 4124.608162224584, 4079.9592004080023, 4092.9590704092875, 4094.2016306820183, 4116.600355879001, 4123.237201117788, 3946.1910308386714, 4094.877153685394, 4100.487439070116, 4103.2409004334295, 4126.09225970286, 3999.2401443725744, 4076.4496792932873, 4084.8570300039355, 4114.423980642712, 4134.276501612236, 3635.345637785224, 3636.945445818315, 3637.108908317453, 3661.4141737322166, 3674.2835147146316, 5031.295618613397, 5041.445441001487, 5059.316992206079, 5097.7960881564695, 5104.438511763703, 4494.303382975611, 4628.629709623222, 4649.977005058881, 4665.323528088408, 4678.883027924295, 5378.757955891994, 5386.596005299579, 5394.217838413408, 5398.217258497535, 5408.4591540845995, 4000.5199376074765, 4016.136530645925, 4022.839086436539, 4024.215278020787, 4027.154297597479, 5386.218998245272, 5667.546596272288, 5677.785663591203, 5689.914651280193, 5701.857453563653, 3939.901502462404, 3941.7832019239218, 3954.584768599313, 3961.900952476211, 3970.3448930926074, 4013.695548946604, 4015.437838702584, 4021.959780402217, 4022.7586344819406, 4043.555208927016, 4440.000999775025, 4572.88567785808, 4579.542045795461, 4644.2801365788355, 4656.953430465652, 5412.458754124556, 5419.268398766116, 5445.863853403617, 5453.536449401845, 5493.148561972901, 1403.4877269796655, 1422.1680317014661, 1427.7001829615897, 1431.1842249917447, 1442.9422823087168, 932.6176267730198, 943.2548286853446, 979.5445118020154, 1003.1473247739364, 1005.4269066632096, 5183.688978661293, 5198.948010519922, 5237.423883372826, 5275.076861549213, 5289.497497737709, 5521.834344969658, 5549.19536667181, 5572.498475137257, 5595.804146854922, 5617.578681598897, 5021.799128034911, 5216.295800067019, 5244.0, 5302.969742995274, 5307.275135580951, 2974.3605124897817, 2986.805857619248, 2998.6401631804324, 3010.6838781928022, 3013.9246518837203, 5470.781168753285, 5710.685912274865, 5732.6847023413275, 5765.798197063083, 5786.276715410574, 5635.154726790932, 5673.404292549223, 5675.432456754292, 5692.686902220335, 5699.173619825185, 5781.82654520365, 5800.535957123504, 5801.128441421523, 5814.215080964101, 5820.388859169726, 5342.9465705342445, 5587.3015873015875, 5634.943650563442, 5659.179418984142, 5698.430156984434, 5526.889462210814, 5565.749541270571, 5569.248151499658, 5570.58220633446, 5622.634528755618, 5745.2818397700285, 5845.18167456548, 5882.735276912635, 5904.61619994699, 5912.320083190488, 3940.133170702441, 3986.0, 3996.9200615988098, 3999.280129576704, 4005.2189822984237, 5670.347909990239, 5697.208367899782, 5713.9428605715075, 5715.342735585461, 5715.514181294637, 5444.482774136451, 5497.038018346853, 5562.694051827027, 5572.60991730575, 5578.693171875586, 5449.182622606482, 5501.752421141138, 5513.145462453326, 5518.42056584069, 5538.418466061005, 5209.0, 5248.685078895279, 5429.448159365092, 5462.863428414163, 5472.699001554953, 5496.203050557567, 5522.2544956430365, 5546.944530554642, 5550.639208451437, 5558.276444542226, 5420.701861397503, 5427.402985671652, 5438.809641662523, 5451.700156491431, 5477.616566840442], "value": 4189.219164017406, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.1395706593944, 815.5025434484963, 826.0500424511811, 833.4291010657698, 846.5809424334955, 14834.48079317224, 15534.0, 15762.448314308987, 15777.684446311066, 15811.288492017859, 14493.49272775453, 14539.054961427526, 14549.563513094598, 14555.854441455582, 14563.1990240537, 691.3501308769766, 810.7648781853255, 826.4917075998278, 829.2163905109674, 839.3369238301734, 818.602977555888, 824.5217773691274, 830.0371568979984, 830.161536847782, 832.488019867783, 872.650939624149, 874.9125087491232, 883.1301168349171, 883.8320719063389, 883.8983516895576, 727.487121579288, 850.706506255344, 862.8360611483861, 870.9738707838743, 875.9562021898879, 634.3688030409726, 635.4884318123908, 635.8537536366662, 635.8664680417118, 636.3541005879036, 14729.55811325662, 14784.556463306119, 14806.629834254121, 14850.554483365517, 14908.478203262943, 13727.039107262495, 13802.447902084004, 13838.654033649138, 13860.306984650828, 13890.93054534731, 12227.633171004883, 12272.877271227351, 12492.500299987989, 12548.18436801605, 12598.874011259863, 4407.801648925774, 4456.064226512436, 4460.598546130864, 4489.842855500075, 4630.120277147331, 56855.14717279244, 57078.42921570773, 57693.711531442495, 57911.13133303004, 58450.70774646142, 11071.833922491169, 11122.888771112186, 11192.0, 11251.493682784294, 11398.772024559628, 20303.28938487146, 20682.896585517276, 20685.17259309641, 20766.273180438613, 20881.89559052195, 8189.8771518427275, 8415.705450309331, 8582.0, 8654.956725216334, 8658.0, 4486.663500237495, 4695.154872122982, 4697.271922851964, 4739.289106634033, 4840.467548569654, 3404.982975085158, 3486.89539313821, 3501.649835016529, 3505.018594793462, 3512.1921957949303, 629.9307076221612, 631.6746875359194, 633.6863252689948, 650.6616559389091, 653.128074021187, 668.6422763821332, 744.0313150529457, 749.2133260076897, 758.2266088589607, 764.1594246329105, 9370.484623345645, 9981.80036399268, 10004.849927251096, 10101.19190464761, 10198.592056317813, 732.1470486882829, 740.1303468424579, 743.1008479739468, 748.4536288509419, 763.267263427105, 4421.5578442156175, 4547.15877562652, 4617.0, 4686.343911852344, 4758.95241047592, 70302.2969770306, 72045.27954720386, 72123.27876721165, 72175.63912180405, 72267.0, 63588.36411635917, 63603.04595431072, 65590.67204663946, 65894.0, 66259.0, 57800.132998005065, 57943.0, 58974.0, 59815.401845981854, 60240.5783479489, 9323.627054917728, 9575.521223938915, 9583.71637555449, 9588.424694518351, 9635.084666956627, 36323.45514817333, 36568.45147322739, 36655.81672091623, 37659.43510847285, 37797.0, 49353.25970110523, 50456.24315635342, 50646.493535064175, 51297.487025129274, 51816.48183518116, 11950.46222919988, 12063.21589096706, 12227.510899563918, 12350.938245308716, 12695.746085078063, 13256.536021239595, 13305.46778128864, 13575.932120339336, 14211.786823197453, 14238.0, 12436.440360183633, 12592.433340499516, 12651.746965060831, 13068.673283167996, 13368.598942031755, 12585.811212831997, 12754.107212495408, 12904.483820647069, 13138.540151094665, 13269.0, 12079.27524348542, 12457.875421246034, 12464.376781160725, 12593.93703031479, 13005.934970325088, 10190.490475476345, 10287.22845786554, 10514.579416823548, 10715.24993250465, 10834.783304334027, 10296.433696146783, 10381.792364152825, 10788.136949044214, 10888.836667449836, 10993.340399576051], "value": 7071.445572179582, "unit": "score"}], "name": "dromaeo_css", "value": 7038.415943815623, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 110.0, 110.0, 111.0, 109.0, 110.0, 109.0, 110.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 126.0, 126.0, 208.0, 124.0, 129.0, 156.0, 124.0, 194.0, 123.0], "value": 146.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 162.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 131.0, 74.0, 76.0, 73.0, 75.0, 118.0, 76.0], "value": 84.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 155.0, 92.0, 92.0, 92.0, 130.0, 92.0, 93.0, 138.0], "value": 106.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 147.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 55.0, 55.0, 54.0, 54.0, 110.0, 53.0, 52.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 61.0, 63.0, 60.0, 61.0, 60.0, 61.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 79.0, 78.0, 85.0, 82.0, 81.0, 85.0, 86.0, 86.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 120.0, 134.0, 122.0, 125.0, 117.0, 118.0, 119.0, 192.0, 122.0], "value": 128.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 159.0, 165.0, 156.0, 158.0, 158.0, 163.0, 165.0, 159.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 63.0, 67.0, 61.0, 60.0, 64.0, 62.0, 60.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1519.9, "alertThreshold": 2.0}]} 19:20:23 INFO - Return code: 0 19:20:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:20:23 ERROR - # TBPL SUCCESS # 19:20:23 INFO - Running post-action listener: _resource_record_post_action 19:20:23 INFO - ##### 19:20:23 INFO - ##### Finished run-tests step (success) 19:20:23 INFO - ##### 19:20:23 INFO - Running post-run listener: _resource_record_post_run 19:20:23 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 546308096; Read time: 0; Write time: 516428 19:20:23 INFO - TinderboxPrint: CPU usage
13.1% 19:20:23 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:20:23 INFO - TinderboxPrint: I/O write bytes / time
546,308,096 / 516,428 19:20:23 INFO - TinderboxPrint: CPU idle
8,266.7 (86.9%) 19:20:23 INFO - TinderboxPrint: CPU user
1,205.5 (12.7%) 19:20:23 INFO - TinderboxPrint: Swap in / out
0 / 0 19:20:23 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254033920; Read time: 0; Write time: 310752 19:20:23 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 292274176; Read time: 0; Write time: 205676 19:20:24 INFO - Running post-run listener: _upload_blobber_files 19:20:24 INFO - Blob upload gear active. 19:20:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:20:24 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 19:20:24 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'] 19:20:24 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 19:20:24 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:20:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:25 INFO - (blobuploader) - INFO - Done attempting. 19:20:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:20:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:20:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:29 INFO - (blobuploader) - INFO - Done attempting. 19:20:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:20:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:20:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:30 INFO - (blobuploader) - INFO - Done attempting. 19:20:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:30 INFO - Return code: 0 19:20:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/030f7a426fee75b2a001408ebe8c74cf827da6fb63c910689af08eb99f2109c04bf7c2b7f701e048f8c765922b4bb81a5a35940b694e13fd4242bfa9615aeb89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a165b0020cc0cd33101a7091038628b60a94ad8386d237b116f7c80c42a79c1862aceef47a6ecba2a34533cc39a4e47c6627e940d8918c9ce25006d44e46a2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee7d47ce0d3579aea2b1cc642065b851f4bcc95d2a520cac5976f821a2a20d6b850fadc8265477368d53658e416943bad50515c1920da48d69913109757510b7"} 19:20:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:30 INFO - Contents: 19:20:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/030f7a426fee75b2a001408ebe8c74cf827da6fb63c910689af08eb99f2109c04bf7c2b7f701e048f8c765922b4bb81a5a35940b694e13fd4242bfa9615aeb89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a165b0020cc0cd33101a7091038628b60a94ad8386d237b116f7c80c42a79c1862aceef47a6ecba2a34533cc39a4e47c6627e940d8918c9ce25006d44e46a2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee7d47ce0d3579aea2b1cc642065b851f4bcc95d2a520cac5976f821a2a20d6b850fadc8265477368d53658e416943bad50515c1920da48d69913109757510b7"} 19:20:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:30 INFO - Copying logs to upload dir... 19:20:30 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.888255 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-05-20 19:20:29.371317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 19:20:29.375100) ========= 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=826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532 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/030f7a426fee75b2a001408ebe8c74cf827da6fb63c910689af08eb99f2109c04bf7c2b7f701e048f8c765922b4bb81a5a35940b694e13fd4242bfa9615aeb89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a165b0020cc0cd33101a7091038628b60a94ad8386d237b116f7c80c42a79c1862aceef47a6ecba2a34533cc39a4e47c6627e940d8918c9ce25006d44e46a2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee7d47ce0d3579aea2b1cc642065b851f4bcc95d2a520cac5976f821a2a20d6b850fadc8265477368d53658e416943bad50515c1920da48d69913109757510b7"} build_url:https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/Xk6iiWLDRp6f0lKA01jyKg/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/030f7a426fee75b2a001408ebe8c74cf827da6fb63c910689af08eb99f2109c04bf7c2b7f701e048f8c765922b4bb81a5a35940b694e13fd4242bfa9615aeb89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a165b0020cc0cd33101a7091038628b60a94ad8386d237b116f7c80c42a79c1862aceef47a6ecba2a34533cc39a4e47c6627e940d8918c9ce25006d44e46a2d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee7d47ce0d3579aea2b1cc642065b851f4bcc95d2a520cac5976f821a2a20d6b850fadc8265477368d53658e416943bad50515c1920da48d69913109757510b7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 19:20:29.400013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 19:20:29.400379) ========= 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=826874ccc97bd7bf541b4875000001d5-1463794979.118021-788290532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004392 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 19:20:29.484907) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 19:20:29.485255) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 19:20:29.485574) ========= ========= Total master_lag: 0.17 =========