builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1463159297.14 results: success (0) buildid: 20160513073006 builduid: 0b08fb9e946a4437bc83648ad8bc902c revision: 1f1a8b96d5167153d1f750439ba6a1063155a4bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.140455) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.140944) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.141217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.157965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.158363) ========= 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=523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 10:08:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 142M=0s 2016-05-13 10:08:17 (142 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141525 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.313155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.313480) ========= 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=523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015473 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.362455) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:17.362839) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1f1a8b96d5167153d1f750439ba6a1063155a4bc --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 1f1a8b96d5167153d1f750439ba6a1063155a4bc --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=523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 10:08:17,511 truncating revision to first 12 chars 2016-05-13 10:08:17,511 Setting DEBUG logging. 2016-05-13 10:08:17,511 attempt 1/10 2016-05-13 10:08:17,511 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1f1a8b96d516?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 10:08:18,080 unpacking tar archive at: mozilla-central-1f1a8b96d516/testing/mozharness/ program finished with exit code 0 elapsedTime=0.857652 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:18.234210) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:18.234549) ========= script_repo_revision: 1f1a8b96d5167153d1f750439ba6a1063155a4bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:18.234957) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:18.235220) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 10:08:18.246415) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-13 10:08:18.246837) ========= /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=523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:08:18 INFO - MultiFileLogger online at 20160513 10:08:18 in /builds/slave/test 10:08:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:08:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:08:18 INFO - {'append_to_log': False, 10:08:18 INFO - 'base_work_dir': '/builds/slave/test', 10:08:18 INFO - 'blob_upload_branch': 'Firefox', 10:08:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:08:18 INFO - 'branch': 'Firefox', 10:08:18 INFO - 'buildbot_json_path': 'buildprops.json', 10:08:18 INFO - 'config_files': ('talos/linux_config.py',), 10:08:18 INFO - 'default_actions': ('clobber', 10:08:18 INFO - 'read-buildbot-config', 10:08:18 INFO - 'download-and-extract', 10:08:18 INFO - 'populate-webroot', 10:08:18 INFO - 'create-virtualenv', 10:08:18 INFO - 'install', 10:08:18 INFO - 'run-tests'), 10:08:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:08:18 INFO - 'download_minidump_stackwalk': True, 10:08:18 INFO - 'download_symbols': 'ondemand', 10:08:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:08:18 INFO - 'tooltool.py': '/tools/tooltool.py', 10:08:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:08:18 INFO - '/tools/misc-python/virtualenv.py')}, 10:08:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:08:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:08:18 INFO - 'installer_path': 'installer.exe', 10:08:18 INFO - 'log_level': 'info', 10:08:18 INFO - 'log_name': 'talos', 10:08:18 INFO - 'log_to_console': True, 10:08:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:08:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:08:18 INFO - 'opt_config_files': (), 10:08:18 INFO - 'pip_index': False, 10:08:18 INFO - 'sps_profile': False, 10:08:18 INFO - 'sps_profile_interval': 0, 10:08:18 INFO - 'suite': 'dromaeojs', 10:08:18 INFO - 'system_bits': '32', 10:08:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:08:18 INFO - 'title': 'talos-linux64-ix-065', 10:08:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:08:18 INFO - 'use_talos_json': True, 10:08:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:08:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:08:18 INFO - 'work_dir': 'build'} 10:08:18 INFO - ##### 10:08:18 INFO - ##### Running clobber step. 10:08:18 INFO - ##### 10:08:18 INFO - Running pre-action listener: _resource_record_pre_action 10:08:18 INFO - Running main action method: clobber 10:08:18 INFO - rmtree: /builds/slave/test/build 10:08:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:08:18 INFO - Running post-action listener: _resource_record_post_action 10:08:18 INFO - ##### 10:08:18 INFO - ##### Finished clobber step (success) 10:08:18 INFO - ##### 10:08:18 INFO - ##### 10:08:18 INFO - ##### Running read-buildbot-config step. 10:08:18 INFO - ##### 10:08:18 INFO - Running pre-action listener: _resource_record_pre_action 10:08:18 INFO - Running main action method: read_buildbot_config 10:08:18 INFO - Using buildbot properties: 10:08:18 INFO - { 10:08:18 INFO - "project": "", 10:08:18 INFO - "product": "firefox", 10:08:18 INFO - "script_repo_revision": "production", 10:08:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:08:18 INFO - "repository": "", 10:08:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 10:08:18 INFO - "buildid": "20160513073006", 10:08:18 INFO - "pgo_build": "True", 10:08:18 INFO - "basedir": "/builds/slave/test", 10:08:18 INFO - "buildnumber": 770, 10:08:18 INFO - "slavename": "talos-linux64-ix-065", 10:08:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:08:18 INFO - "platform": "ubuntu64_hw", 10:08:18 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 10:08:18 INFO - "branch": "mozilla-central", 10:08:18 INFO - "repo_path": "mozilla-central", 10:08:18 INFO - "revision": "1f1a8b96d5167153d1f750439ba6a1063155a4bc", 10:08:18 INFO - "stage_platform": "linux64-pgo", 10:08:18 INFO - "builduid": "0b08fb9e946a4437bc83648ad8bc902c", 10:08:18 INFO - "slavebuilddir": "test" 10:08:18 INFO - } 10:08:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:08:18 INFO - Running post-action listener: _resource_record_post_action 10:08:18 INFO - ##### 10:08:18 INFO - ##### Finished read-buildbot-config step (success) 10:08:18 INFO - ##### 10:08:18 INFO - ##### 10:08:18 INFO - ##### Running download-and-extract step. 10:08:18 INFO - ##### 10:08:18 INFO - Running pre-action listener: _resource_record_pre_action 10:08:18 INFO - Running main action method: download_and_extract 10:08:18 INFO - mkdir: /builds/slave/test/build/tests 10:08: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')]} 10:08:18 INFO - https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:08:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:08:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:08:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:08:19 INFO - Downloaded 1361 bytes. 10:08:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:08:19 INFO - Using the following test package requirements: 10:08:19 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:08:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:08:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:08:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'jsshell-linux-x86_64.zip'], 10:08:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:08:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:08:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:08:19 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:08:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:08:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:08:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:19 INFO - https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:08:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:08:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:08:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:08:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:08:20 INFO - Downloaded 23909824 bytes. 10:08:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:08:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:08:21 INFO - Return code: 0 10:08:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:08: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')]} 10:08:21 INFO - https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:08:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:08:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:08:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:08:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:08:23 INFO - Downloaded 23909824 bytes. 10:08:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:08:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:08:24 INFO - Return code: 0 10:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:24 INFO - https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:08:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:08:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:08:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:08:25 INFO - Downloaded 10840239 bytes. 10:08:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:08:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 10:08:25 INFO - Return code: 0 10:08: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')]} 10:08:25 INFO - https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:08:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:08:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:08:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:08:27 INFO - Downloaded 58400844 bytes. 10:08:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:08:27 INFO - mkdir: /builds/slave/test/properties 10:08:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:08:27 INFO - Writing to file /builds/slave/test/properties/build_url 10:08:27 INFO - Contents: 10:08:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:08:27 INFO - Running post-action listener: _resource_record_post_action 10:08:27 INFO - Running post-action listener: set_extra_try_arguments 10:08:27 INFO - ##### 10:08:27 INFO - ##### Finished download-and-extract step (success) 10:08:27 INFO - ##### 10:08:27 INFO - ##### 10:08:27 INFO - ##### Running populate-webroot step. 10:08:27 INFO - ##### 10:08:27 INFO - Running pre-action listener: _resource_record_pre_action 10:08:27 INFO - Running main action method: populate_webroot 10:08:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:08:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:08:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:08:27 INFO - '--tppagecycles', 10:08:27 INFO - '7'], 10:08:27 INFO - 'tests': ['tsvgm']}}, 10:08:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:08:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:08:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:08:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:08:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:08:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:08:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['damp', 'tps']}, 10:08:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'tests': ['damp', 'tps']}, 10:08:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['dromaeo_dom']}, 10:08:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:08:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['a11yr', 10:08:27 INFO - 'ts_paint', 10:08:27 INFO - 'tpaint', 10:08:27 INFO - 'sessionrestore', 10:08:27 INFO - 'sessionrestore_no_auto_restore', 10:08:27 INFO - 'tabpaint']}, 10:08:27 INFO - 'other-e10s': {'tests': ['a11yr', 10:08:27 INFO - 'ts_paint', 10:08:27 INFO - 'tpaint', 10:08:27 INFO - 'sessionrestore', 10:08:27 INFO - 'sessionrestore_no_auto_restore', 10:08:27 INFO - 'tabpaint']}, 10:08:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:08:27 INFO - 'ts_paint', 10:08:27 INFO - 'tpaint', 10:08:27 INFO - 'sessionrestore', 10:08:27 INFO - 'sessionrestore_no_auto_restore', 10:08:27 INFO - 'tabpaint']}, 10:08:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:08:27 INFO - 'ts_paint', 10:08:27 INFO - 'tpaint', 10:08:27 INFO - 'sessionrestore', 10:08:27 INFO - 'sessionrestore_no_auto_restore', 10:08:27 INFO - 'tabpaint']}, 10:08:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['a11yr', 10:08:27 INFO - 'ts_paint', 10:08:27 INFO - 'tpaint', 10:08:27 INFO - 'sessionrestore', 10:08:27 INFO - 'sessionrestore_no_auto_restore', 10:08:27 INFO - 'tabpaint']}, 10:08:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['a11yr', 10:08:27 INFO - 'ts_paint', 10:08:27 INFO - 'tpaint', 10:08:27 INFO - 'sessionrestore', 10:08:27 INFO - 'sessionrestore_no_auto_restore', 10:08:27 INFO - 'tabpaint']}, 10:08:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['tsvgx', 10:08:27 INFO - 'tsvgr_opacity', 10:08:27 INFO - 'tart', 10:08:27 INFO - 'tscrollx', 10:08:27 INFO - 'cart']}, 10:08:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:08:27 INFO - 'tsvgr_opacity', 10:08:27 INFO - 'tart', 10:08:27 INFO - 'tscrollx', 10:08:27 INFO - 'cart']}, 10:08:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'talos_options': ['--disable-e10s'], 10:08:27 INFO - 'tests': ['tp5o']}, 10:08:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'tests': ['tp5o']}, 10:08:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'talos_options': ['--disable-e10s', 10:08:27 INFO - '--xperf_path', 10:08:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:08:27 INFO - 'tests': ['tp5n']}, 10:08:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:27 INFO - 'talos_options': ['--xperf_path', 10:08:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:08:27 INFO - 'tests': ['tp5n']}}, 10:08:27 INFO - 'talos.zip': {'path': '', 10:08:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:08:27 INFO - Running post-action listener: _resource_record_post_action 10:08:27 INFO - ##### 10:08:27 INFO - ##### Finished populate-webroot step (success) 10:08:27 INFO - ##### 10:08:27 INFO - ##### 10:08:27 INFO - ##### Running create-virtualenv step. 10:08:27 INFO - ##### 10:08:27 INFO - Running pre-action listener: _resource_record_pre_action 10:08:27 INFO - Running main action method: create_virtualenv 10:08:27 INFO - Creating virtualenv /builds/slave/test/build/venv 10:08:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:08:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:08:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:08:27 INFO - Using real prefix '/usr' 10:08:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:08:28 INFO - Installing distribute.............................................................................................................................................................................................done. 10:08:30 INFO - Installing pip.................done. 10:08:30 INFO - Return code: 0 10:08:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:08:30 INFO - Using env: {'DISPLAY': ':0', 10:08:30 INFO - 'HOME': '/home/cltbld', 10:08:30 INFO - 'LANG': 'en_US.UTF-8', 10:08:30 INFO - 'LANGUAGE': 'en_US:en', 10:08:30 INFO - 'LOGNAME': 'cltbld', 10:08:30 INFO - 'MAIL': '/var/mail/cltbld', 10:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:30 INFO - 'MOZ_NO_REMOTE': '1', 10:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:30 INFO - 'NO_EM_RESTART': '1', 10:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:30 INFO - 'PWD': '/builds/slave/test', 10:08:30 INFO - 'SHELL': '/bin/bash', 10:08:30 INFO - 'SHLVL': '1', 10:08:30 INFO - 'TERM': 'linux', 10:08:30 INFO - 'TMOUT': '86400', 10:08:30 INFO - 'USER': 'cltbld', 10:08:30 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:30 INFO - '_': '/tools/buildbot/bin/python'} 10:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:08:30 INFO - Cleaning up... 10:08:30 INFO - Return code: 0 10:08:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:08:30 INFO - Using env: {'DISPLAY': ':0', 10:08:30 INFO - 'HOME': '/home/cltbld', 10:08:30 INFO - 'LANG': 'en_US.UTF-8', 10:08:30 INFO - 'LANGUAGE': 'en_US:en', 10:08:30 INFO - 'LOGNAME': 'cltbld', 10:08:30 INFO - 'MAIL': '/var/mail/cltbld', 10:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:30 INFO - 'MOZ_NO_REMOTE': '1', 10:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:30 INFO - 'NO_EM_RESTART': '1', 10:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:30 INFO - 'PWD': '/builds/slave/test', 10:08:30 INFO - 'SHELL': '/bin/bash', 10:08:30 INFO - 'SHLVL': '1', 10:08:30 INFO - 'TERM': 'linux', 10:08:30 INFO - 'TMOUT': '86400', 10:08:30 INFO - 'USER': 'cltbld', 10:08:30 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:30 INFO - '_': '/tools/buildbot/bin/python'} 10:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:30 INFO - Downloading/unpacking psutil>=3.1.1 10:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:08:32 INFO - Installing collected packages: psutil 10:08:32 INFO - Running setup.py install for psutil 10:08:32 INFO - building 'psutil._psutil_linux' extension 10:08:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:08:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:08:33 INFO - building 'psutil._psutil_posix' extension 10:08:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:08:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:08:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:08:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:08:33 INFO - Successfully installed psutil 10:08:33 INFO - Cleaning up... 10:08:33 INFO - Return code: 0 10:08:33 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 10:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:33 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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:33 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.1'] in /builds/slave/test/build 10:08:33 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.1 10:08:33 INFO - Using env: {'DISPLAY': ':0', 10:08:33 INFO - 'HOME': '/home/cltbld', 10:08:33 INFO - 'LANG': 'en_US.UTF-8', 10:08:33 INFO - 'LANGUAGE': 'en_US:en', 10:08:33 INFO - 'LOGNAME': 'cltbld', 10:08:33 INFO - 'MAIL': '/var/mail/cltbld', 10:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:33 INFO - 'MOZ_NO_REMOTE': '1', 10:08:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:33 INFO - 'NO_EM_RESTART': '1', 10:08:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:33 INFO - 'PWD': '/builds/slave/test', 10:08:33 INFO - 'SHELL': '/bin/bash', 10:08:33 INFO - 'SHLVL': '1', 10:08:33 INFO - 'TERM': 'linux', 10:08:33 INFO - 'TMOUT': '86400', 10:08:33 INFO - 'USER': 'cltbld', 10:08:33 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:33 INFO - '_': '/tools/buildbot/bin/python'} 10:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:33 INFO - Downloading/unpacking mozsystemmonitor==0.1 10:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:35 INFO - Downloading mozsystemmonitor-0.1.tar.gz 10:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 10:08:35 INFO - Installing collected packages: mozsystemmonitor 10:08:35 INFO - Running setup.py install for mozsystemmonitor 10:08:35 INFO - Successfully installed mozsystemmonitor 10:08:35 INFO - Cleaning up... 10:08:35 INFO - Return code: 0 10:08:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:35 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 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:08:35 INFO - Using env: {'DISPLAY': ':0', 10:08:35 INFO - 'HOME': '/home/cltbld', 10:08:35 INFO - 'LANG': 'en_US.UTF-8', 10:08:35 INFO - 'LANGUAGE': 'en_US:en', 10:08:35 INFO - 'LOGNAME': 'cltbld', 10:08:35 INFO - 'MAIL': '/var/mail/cltbld', 10:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:35 INFO - 'MOZ_NO_REMOTE': '1', 10:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:35 INFO - 'NO_EM_RESTART': '1', 10:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:35 INFO - 'PWD': '/builds/slave/test', 10:08:35 INFO - 'SHELL': '/bin/bash', 10:08:35 INFO - 'SHLVL': '1', 10:08:35 INFO - 'TERM': 'linux', 10:08:35 INFO - 'TMOUT': '86400', 10:08:35 INFO - 'USER': 'cltbld', 10:08:35 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:35 INFO - '_': '/tools/buildbot/bin/python'} 10:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:35 INFO - Downloading/unpacking blobuploader==1.2.4 10:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:37 INFO - Downloading blobuploader-1.2.4.tar.gz 10:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:08:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:08:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:38 INFO - Downloading docopt-0.6.1.tar.gz 10:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:08:38 INFO - Installing collected packages: blobuploader, requests, docopt 10:08:38 INFO - Running setup.py install for blobuploader 10:08:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:08:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:08:38 INFO - Running setup.py install for requests 10:08:39 INFO - Running setup.py install for docopt 10:08:39 INFO - Successfully installed blobuploader requests docopt 10:08:39 INFO - Cleaning up... 10:08:39 INFO - Return code: 0 10:08:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:39 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 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:08:39 INFO - Using env: {'DISPLAY': ':0', 10:08:39 INFO - 'HOME': '/home/cltbld', 10:08:39 INFO - 'LANG': 'en_US.UTF-8', 10:08:39 INFO - 'LANGUAGE': 'en_US:en', 10:08:39 INFO - 'LOGNAME': 'cltbld', 10:08:39 INFO - 'MAIL': '/var/mail/cltbld', 10:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:39 INFO - 'MOZ_NO_REMOTE': '1', 10:08:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:39 INFO - 'NO_EM_RESTART': '1', 10:08:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:39 INFO - 'PWD': '/builds/slave/test', 10:08:39 INFO - 'SHELL': '/bin/bash', 10:08:39 INFO - 'SHLVL': '1', 10:08:39 INFO - 'TERM': 'linux', 10:08:39 INFO - 'TMOUT': '86400', 10:08:39 INFO - 'USER': 'cltbld', 10:08:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:39 INFO - '_': '/tools/buildbot/bin/python'} 10:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:08:39 INFO - Running setup.py (path:/tmp/pip-IKbssP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:08:39 INFO - Running setup.py (path:/tmp/pip-CTQyN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:08:39 INFO - Running setup.py (path:/tmp/pip-aU5_Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:08:39 INFO - Running setup.py (path:/tmp/pip-wnaNKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:08:39 INFO - Running setup.py (path:/tmp/pip-8cpcpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:08:39 INFO - Running setup.py (path:/tmp/pip-PU2Rwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:08:39 INFO - Running setup.py (path:/tmp/pip-oFYTQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:08:39 INFO - Running setup.py (path:/tmp/pip-jm_BYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:08:40 INFO - Running setup.py (path:/tmp/pip-0dD8Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:08:40 INFO - Running setup.py (path:/tmp/pip-oez_bU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:08:40 INFO - Running setup.py (path:/tmp/pip-sDpWeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:08:40 INFO - Running setup.py (path:/tmp/pip-miIsgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:08:40 INFO - Running setup.py (path:/tmp/pip-qvX4vW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:08:40 INFO - Running setup.py (path:/tmp/pip-dSWS6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:08:40 INFO - Running setup.py (path:/tmp/pip-4o4rQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:08:40 INFO - Running setup.py (path:/tmp/pip-Jbln4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:08:40 INFO - Running setup.py (path:/tmp/pip-GcvJnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:08:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:08:40 INFO - Running setup.py install for manifestparser 10:08:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:08:40 INFO - Running setup.py install for mozcrash 10:08:40 INFO - Running setup.py install for mozdebug 10:08:41 INFO - Running setup.py install for mozdevice 10:08:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Running setup.py install for mozfile 10:08:41 INFO - Running setup.py install for mozhttpd 10:08:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Running setup.py install for mozinfo 10:08:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Running setup.py install for mozInstall 10:08:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Running setup.py install for mozleak 10:08:41 INFO - Running setup.py install for mozlog 10:08:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Running setup.py install for moznetwork 10:08:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:08:41 INFO - Running setup.py install for mozprocess 10:08:41 INFO - Running setup.py install for mozprofile 10:08:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:08:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:08:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:08:42 INFO - Running setup.py install for mozrunner 10:08:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:08:42 INFO - Running setup.py install for mozscreenshot 10:08:42 INFO - Running setup.py install for moztest 10:08:42 INFO - Running setup.py install for mozversion 10:08:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:08:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:08:42 INFO - Cleaning up... 10:08:42 INFO - Return code: 0 10:08:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:42 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 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:08:42 INFO - Using env: {'DISPLAY': ':0', 10:08:42 INFO - 'HOME': '/home/cltbld', 10:08:42 INFO - 'LANG': 'en_US.UTF-8', 10:08:42 INFO - 'LANGUAGE': 'en_US:en', 10:08:42 INFO - 'LOGNAME': 'cltbld', 10:08:42 INFO - 'MAIL': '/var/mail/cltbld', 10:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:42 INFO - 'MOZ_NO_REMOTE': '1', 10:08:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:42 INFO - 'NO_EM_RESTART': '1', 10:08:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:42 INFO - 'PWD': '/builds/slave/test', 10:08:42 INFO - 'SHELL': '/bin/bash', 10:08:42 INFO - 'SHLVL': '1', 10:08:42 INFO - 'TERM': 'linux', 10:08:42 INFO - 'TMOUT': '86400', 10:08:42 INFO - 'USER': 'cltbld', 10:08:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:42 INFO - '_': '/tools/buildbot/bin/python'} 10:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:08:42 INFO - Running setup.py (path:/tmp/pip-d_hbn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:08:42 INFO - Running setup.py (path:/tmp/pip-JbZm8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:08:42 INFO - Running setup.py (path:/tmp/pip-dh4fxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:08:42 INFO - Running setup.py (path:/tmp/pip-56nC4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:08:42 INFO - Running setup.py (path:/tmp/pip-7ed27g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:08:43 INFO - Running setup.py (path:/tmp/pip-YGMVYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:08:43 INFO - Running setup.py (path:/tmp/pip-B45HaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:08:43 INFO - Running setup.py (path:/tmp/pip-hYVmMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:08:43 INFO - Running setup.py (path:/tmp/pip-PDpW6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:08:43 INFO - Running setup.py (path:/tmp/pip-TevOkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:08:43 INFO - Running setup.py (path:/tmp/pip-HcpYc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:08:43 INFO - Running setup.py (path:/tmp/pip-S5XhtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:08:43 INFO - Running setup.py (path:/tmp/pip-08AaBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:08:43 INFO - Running setup.py (path:/tmp/pip-rXnE59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:08:43 INFO - Running setup.py (path:/tmp/pip-RmLZyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:08:43 INFO - Running setup.py (path:/tmp/pip-VkdBTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:08:43 INFO - Running setup.py (path:/tmp/pip-CzFVsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:08:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:45 INFO - Downloading blessings-1.6.tar.gz 10:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:08:45 INFO - Installing collected packages: blessings 10:08:45 INFO - Running setup.py install for blessings 10:08:45 INFO - Successfully installed blessings 10:08:45 INFO - Cleaning up... 10:08:46 INFO - Return code: 0 10:08:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:08:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:08:46 INFO - Reading from file tmpfile_stdout 10:08:46 INFO - Current package versions: 10:08:46 INFO - argparse == 1.2.1 10:08:46 INFO - blessings == 1.6 10:08:46 INFO - blobuploader == 1.2.4 10:08:46 INFO - docopt == 0.6.1 10:08:46 INFO - manifestparser == 1.1 10:08:46 INFO - mozInstall == 1.12 10:08:46 INFO - mozcrash == 0.17 10:08:46 INFO - mozdebug == 0.1 10:08:46 INFO - mozdevice == 0.48 10:08:46 INFO - mozfile == 1.2 10:08:46 INFO - mozhttpd == 0.7 10:08:46 INFO - mozinfo == 0.9 10:08:46 INFO - mozleak == 0.1 10:08:46 INFO - mozlog == 3.2 10:08:46 INFO - moznetwork == 0.27 10:08:46 INFO - mozprocess == 0.22 10:08:46 INFO - mozprofile == 0.28 10:08:46 INFO - mozrunner == 6.11 10:08:46 INFO - mozscreenshot == 0.1 10:08:46 INFO - mozsystemmonitor == 0.1 10:08:46 INFO - moztest == 0.7 10:08:46 INFO - mozversion == 1.4 10:08:46 INFO - psutil == 3.1.1 10:08:46 INFO - requests == 1.2.3 10:08:46 INFO - wsgiref == 0.1.2 10:08:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:46 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 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:08:46 INFO - Using env: {'DISPLAY': ':0', 10:08:46 INFO - 'HOME': '/home/cltbld', 10:08:46 INFO - 'LANG': 'en_US.UTF-8', 10:08:46 INFO - 'LANGUAGE': 'en_US:en', 10:08:46 INFO - 'LOGNAME': 'cltbld', 10:08:46 INFO - 'MAIL': '/var/mail/cltbld', 10:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:46 INFO - 'MOZ_NO_REMOTE': '1', 10:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:46 INFO - 'NO_EM_RESTART': '1', 10:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:46 INFO - 'PWD': '/builds/slave/test', 10:08:46 INFO - 'SHELL': '/bin/bash', 10:08:46 INFO - 'SHLVL': '1', 10:08:46 INFO - 'TERM': 'linux', 10:08:46 INFO - 'TMOUT': '86400', 10:08:46 INFO - 'USER': 'cltbld', 10:08:46 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:46 INFO - '_': '/tools/buildbot/bin/python'} 10:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:08:46 INFO - Cleaning up... 10:08:46 INFO - Return code: 0 10:08:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:46 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 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:08:46 INFO - Using env: {'DISPLAY': ':0', 10:08:46 INFO - 'HOME': '/home/cltbld', 10:08:46 INFO - 'LANG': 'en_US.UTF-8', 10:08:46 INFO - 'LANGUAGE': 'en_US:en', 10:08:46 INFO - 'LOGNAME': 'cltbld', 10:08:46 INFO - 'MAIL': '/var/mail/cltbld', 10:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:46 INFO - 'MOZ_NO_REMOTE': '1', 10:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:46 INFO - 'NO_EM_RESTART': '1', 10:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:46 INFO - 'PWD': '/builds/slave/test', 10:08:46 INFO - 'SHELL': '/bin/bash', 10:08:46 INFO - 'SHLVL': '1', 10:08:46 INFO - 'TERM': 'linux', 10:08:46 INFO - 'TMOUT': '86400', 10:08:46 INFO - 'USER': 'cltbld', 10:08:46 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:46 INFO - '_': '/tools/buildbot/bin/python'} 10:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:46 INFO - Downloading/unpacking jsonschema 10:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:08:49 INFO - Downloading/unpacking functools32 (from jsonschema) 10:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:49 INFO - Downloading functools32-3.2.3-2.tar.gz 10:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:08:49 INFO - warning: no files found matching '*.txt' 10:08:49 INFO - no previously-included directories found matching 'build' 10:08:49 INFO - no previously-included directories found matching 'dist' 10:08:49 INFO - no previously-included directories found matching '.git*' 10:08:49 INFO - Installing collected packages: jsonschema, functools32 10:08:49 INFO - Running setup.py install for functools32 10:08:50 INFO - warning: no files found matching '*.txt' 10:08:50 INFO - no previously-included directories found matching 'build' 10:08:50 INFO - no previously-included directories found matching 'dist' 10:08:50 INFO - no previously-included directories found matching '.git*' 10:08:50 INFO - Successfully installed jsonschema functools32 10:08:50 INFO - Cleaning up... 10:08:50 INFO - Return code: 0 10:08:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94db6dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x216c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2237770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, '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': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:08:50 INFO - Using env: {'DISPLAY': ':0', 10:08:50 INFO - 'HOME': '/home/cltbld', 10:08:50 INFO - 'LANG': 'en_US.UTF-8', 10:08:50 INFO - 'LANGUAGE': 'en_US:en', 10:08:50 INFO - 'LOGNAME': 'cltbld', 10:08:50 INFO - 'MAIL': '/var/mail/cltbld', 10:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:50 INFO - 'MOZ_NO_REMOTE': '1', 10:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:50 INFO - 'NO_EM_RESTART': '1', 10:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:50 INFO - 'PWD': '/builds/slave/test', 10:08:50 INFO - 'SHELL': '/bin/bash', 10:08:50 INFO - 'SHLVL': '1', 10:08:50 INFO - 'TERM': 'linux', 10:08:50 INFO - 'TMOUT': '86400', 10:08:50 INFO - 'USER': 'cltbld', 10:08:50 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:50 INFO - '_': '/tools/buildbot/bin/python'} 10:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:50 INFO - Downloading/unpacking flake8 10:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:08:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:08:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:08:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:08:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:08:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:08:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:08:52 INFO - no previously-included directories found matching 'docs/_build' 10:08:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:08:52 INFO - Running setup.py install for pep8 10:08:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:08:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:08:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:08:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:08:53 INFO - no previously-included directories found matching 'docs/_build' 10:08:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:08:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:08:53 INFO - Cleaning up... 10:08:53 INFO - Return code: 0 10:08:53 INFO - Running post-action listener: _resource_record_post_action 10:08:53 INFO - Running post-action listener: _start_resource_monitoring 10:08:53 INFO - Starting resource monitoring. 10:08:53 INFO - ##### 10:08:53 INFO - ##### Finished create-virtualenv step (success) 10:08:53 INFO - ##### 10:08:53 INFO - ##### 10:08:53 INFO - ##### Running install step. 10:08:53 INFO - ##### 10:08:53 INFO - Running pre-action listener: _resource_record_pre_action 10:08:53 INFO - Running main action method: install 10:08:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:08:53 INFO - Reading from file tmpfile_stdout 10:08:53 INFO - Detecting whether we're running mozinstall >=1.0... 10:08:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:08:53 INFO - Reading from file tmpfile_stdout 10:08:53 INFO - Output received: 10:08:53 INFO - Usage: mozinstall [options] installer 10:08:53 INFO - Options: 10:08:53 INFO - -h, --help show this help message and exit 10:08:53 INFO - -d DEST, --destination=DEST 10:08:53 INFO - Directory to install application into. [default: 10:08:53 INFO - "/builds/slave/test"] 10:08:53 INFO - --app=APP Application being installed. [default: firefox] 10:08:53 INFO - mkdir: /builds/slave/test/build/application 10:08:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:09:10 INFO - Reading from file tmpfile_stdout 10:09:10 INFO - Output received: 10:09:10 INFO - /builds/slave/test/build/application/firefox/firefox 10:09:10 INFO - Running post-action listener: _resource_record_post_action 10:09:10 INFO - ##### 10:09:10 INFO - ##### Finished install step (success) 10:09:10 INFO - ##### 10:09:10 INFO - ##### 10:09:10 INFO - ##### Running run-tests step. 10:09:10 INFO - ##### 10:09:10 INFO - Running pre-action listener: _resource_record_pre_action 10:09:10 INFO - Running main action method: run_tests 10:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:09:10 INFO - Python 2.7.3 10:09:10 INFO - Return code: 0 10:09:10 INFO - grabbing minidump binary from tooltool 10:09: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')]} 10:09:10 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 0x22c69c0>, '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 0x22c78c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22c7d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:09:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:09:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:09:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:09:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:09:10 INFO - Return code: 0 10:09:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:09:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:09:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:09:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:09:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:09:11 INFO - Return code: 0 10:09:11 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/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:09:11 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/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:09:11 INFO - Using env: {'DISPLAY': ':0', 10:09:11 INFO - 'HOME': '/home/cltbld', 10:09:11 INFO - 'LANG': 'en_US.UTF-8', 10:09:11 INFO - 'LANGUAGE': 'en_US:en', 10:09:11 INFO - 'LOGNAME': 'cltbld', 10:09:11 INFO - 'MAIL': '/var/mail/cltbld', 10:09:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:09:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:11 INFO - 'MOZ_NO_REMOTE': '1', 10:09:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:11 INFO - 'NO_EM_RESTART': '1', 10:09:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:11 INFO - 'PWD': '/builds/slave/test', 10:09:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:09:11 INFO - 'SHELL': '/bin/bash', 10:09:11 INFO - 'SHLVL': '1', 10:09:11 INFO - 'TERM': 'linux', 10:09:11 INFO - 'TMOUT': '86400', 10:09:11 INFO - 'USER': 'cltbld', 10:09:11 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614', 10:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:11 INFO - '_': '/tools/buildbot/bin/python'} 10:09:11 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/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:09:11 INFO - mozversion application_buildid: 20160513073006 10:09:11 INFO - mozversion application_changeset: 1f1a8b96d5167153d1f750439ba6a1063155a4bc 10:09:11 INFO - mozversion application_display_name: Nightly 10:09:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:09:11 INFO - mozversion application_name: Firefox 10:09:11 INFO - mozversion application_remotingname: firefox 10:09:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:09:11 INFO - mozversion application_vendor: Mozilla 10:09:11 INFO - mozversion application_version: 49.0a1 10:09:11 INFO - mozversion platform_buildid: 20160513073006 10:09:11 INFO - mozversion platform_changeset: 1f1a8b96d5167153d1f750439ba6a1063155a4bc 10:09:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:09:11 INFO - mozversion platform_version: 49.0a1 10:09:11 INFO - using testdate: 1463159351 10:09:11 INFO - actual date: 1463159351 10:09:11 INFO - starting webserver on 'localhost:53129' 10:09:11 INFO - SUITE-START | Running 2 tests 10:09:11 INFO - TEST-START | dromaeo_css 10:09:11 INFO - operating with platform_type : linux_ 10:09:11 INFO - Initialising browser for dromaeo_css test... 10:09:11 INFO - TEST-INFO | started process 7778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu_dSf1/profile http://localhost:53129/getInfo.html) 10:09:15 INFO - PROCESS | 7778 | __metrics Screen width/height:1600/1200 10:09:15 INFO - PROCESS | 7778 | colorDepth:24 10:09:15 INFO - PROCESS | 7778 | Browser inner width/height: 1024/697 10:09:15 INFO - PROCESS | 7778 | __metrics 10:09:16 ERROR - PROCESS | 7778 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:09:17 INFO - TEST-INFO | 7778: exit 0 10:09:17 INFO - Browser initialized. 10:09:17 INFO - Running cycle 1/1 for dromaeo_css test... 10:09:17 INFO - TEST-INFO | started process 7859 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu_dSf1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:09:29 INFO - PROCESS | 7859 | RSS: Main: 152190976 10:09:29 INFO - PROCESS | 7859 | 10:10:20 INFO - PROCESS | 7859 | 1463159420179 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:12:27 INFO - PROCESS | 7859 | Cycle 1(1): loaded http://localhost:53129/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53129/tests/dromaeo/cssquery-ext.html) 10:12:28 INFO - PROCESS | 7859 | RSS: Main: 251064320 10:12:28 INFO - PROCESS | 7859 | 10:15:31 INFO - PROCESS | 7859 | Cycle 1(1): loaded http://localhost:53129/tests/dromaeo/cssquery-ext.html (next: http://localhost:53129/tests/dromaeo/cssquery-jquery.html) 10:15:31 INFO - PROCESS | 7859 | RSS: Main: 239915008 10:15:31 INFO - PROCESS | 7859 | 10:18:33 INFO - PROCESS | 7859 | Cycle 1(1): loaded http://localhost:53129/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53129/tests/dromaeo/cssquery-mootools.html) 10:18:34 INFO - PROCESS | 7859 | RSS: Main: 238260224 10:18:34 INFO - PROCESS | 7859 | 10:21:36 INFO - PROCESS | 7859 | Cycle 1(1): loaded http://localhost:53129/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53129/tests/dromaeo/cssquery-prototype.html) 10:21:37 INFO - PROCESS | 7859 | RSS: Main: 233594880 10:21:37 INFO - PROCESS | 7859 | 10:24:39 INFO - PROCESS | 7859 | Cycle 1(1): loaded http://localhost:53129/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53129/tests/dromaeo/cssquery-yui.html) 10:24:40 INFO - PROCESS | 7859 | RSS: Main: 204075008 10:24:40 INFO - PROCESS | 7859 | 10:27:42 INFO - PROCESS | 7859 | Cycle 1(1): loaded http://localhost:53129/tests/dromaeo/cssquery-yui.html (next: http://localhost:53129/tests/dromaeo/cssquery-dojo.html) 10:27:43 INFO - PROCESS | 7859 | RSS: Main: 260747264 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | __start_tp_report 10:27:43 INFO - PROCESS | 7859 | _x_x_mozilla_page_load 10:27:43 INFO - PROCESS | 7859 | _x_x_mozilla_page_load_details 10:27:43 INFO - PROCESS | 7859 | |i|pagename|runs| 10:27:43 INFO - PROCESS | 7859 | |0;dojo.html;3170.6036745406823;3187.7927934684258;3211.566438530798;3222.30720395115;3241;5203.817866374678;5315.468453154683;5328.227407025979;5428.240046393503;5429.131338985758;5344.198370244456;5382.138857782746;5384.3807962084375;5388.299521062257;5431.674099554029;4843.370361852954;4858.246971719389;4968.478309777475;4971.776270067838;5012.949870501285;4936.06214819185;4963.081829861487;4999.4000719913665;5012.924806127893;5017.799288028475;4769.90460190796;4803.423589169304;4827;4867.586255168298;4875.293082503017;4849.927251091236;4877.536634019798;4882.511748825124;4883.658143929926;4930.408350997904;4717.669763116583;4726.196546587088;4809.206480930642;4821.204501257288;4881.096997055521;5250.028744682246;5288.629795914288;5296.947030529684;5356.517913387814;5388.461153884619;5704.572157088235;5772.797952071657;5776.768929242825;5777.24895763548;5783.277090363705;5582.49757521825;5626.521745651625;5783.797567085132;5821.388754180834;5824.504917082053;5311.760970756325;5371.086915224421;5382.138857782736;5389.703566303851;5393.757280922328;5865.794697185578;5871.618344807659;5876.500497457764;5881.235439392852;5881.529477641779;5446.91829622556;5554.972225138848;5555.388907220203;5635.971820140873;5637.126245431965;5694.686792226385;5703.429657034347;5704.600677952587;5717.056685646905;5742.827715168552;5655.151727240865;5694.032014557535;5708.885822283614;5712.657240565496;5717.942820571824;5474.945250547444;5622.746976386073;5658.490735833795;5672.858178545569;5694.572907031989;4329.675274354436;4330.35044743291;4330.783460826946;4352.869413917588;4360.215161270939;3885.1646895917515;3890.3466921119525;3902.551206611219;3939.7242193046786;3940.2513522430645;5368.4631536846;5419.837404877781;5458.099413596712;5484.067708489566;5484.533814625722;5794.362620111784;5812.854678633068;5818.709064546756;5825.097109947965;5826.475617194388;5509.889802203934;5520.806771762969;5524.779008839601;5526.861828454321;5531.3362396512675;4531.320301954733;4623.768811559408;4662.463816661059;4676.947686770517;4737.431508219036;6069.332373438918;6073.878522429527;6076.210092687922;6083.117947897618;6086.695665216722;5774.133879918046;5794.85512862165;5797.391273916347;5801.622894511843;5804.332501762351;5781.94218057814;5907.17299578068;6001.489873360639;6001.939980600138;6035.185249991195;5622.775088996558;5625.96445816128;5664.291963504562;5670.659760414388;5674.77300907959;5637.267155269788;5741.311042674905;5751.712414379181;5760;5774.191613174243;5544.417836127149;5569.164625306236;5613.354464236665;5623.297087864017;5625.774969001358;5525.585581081355;5561.4716601922755;5586.692731899622;5588.441155884541;5594.076977298863;5396.595255355943;5449.945500544944;5492.066348720807;5511.917321240265;5547.667139971614;5614.270144881302;5721.427857214246;5731.398203188605;5732.283464566929;5733.197352370755;5479.808206712746;5531.391546929754;5536.363318218457;5552.305961754781;5559.388467268678 10:27:43 INFO - PROCESS | 7859 | |1;ext.html;609.497164839008;631.9620822750633;643.47235267081;651.3584119642154;661.2065521374352;33190;43243.78378108113;44422.33366499505;44465.55534444655;44477.332840007424;45958.310625340644;46543;46567.76716116415;46630.76684616572;46644;7913.20867913207;8842.29261659069;8989.77525561863;8995.730128096167;10304.227182961271;10099.34354266974;10520.105791007736;10527;10539.84190237147;10544.947275263614;10617.044465998059;10664.520096595672;10666.360018398882;10671.573137074507;10675.306105103182;8066.5966701664975;10320.948395257976;10520.473976301231;10539.473026348729;10547.05076543111;10539.525721342558;10611.734706632318;10615.52230149645;10621.150307975344;10621.468926553718;42059.7897010516;42307.576924230976;42397.57602423967;42515.57484425178;42519;3590;3720.609336019706;4217.219814334328;4302.9569704303185;4348.782560871975;32515.349693006006;32562.837185814155;32918.506222406686;32966.6703332966;33444.6655533444;4224.366345048267;4228.813643645329;4259.5740425957465;4318.093200427914;4458.598726114666;370997;371766;372188.139059303;372562;372991.94528191385;52599.73700131475;56948;58244.708776455846;58374.12438813421;64193;64055;64294.678526607066;64315.71368572603;64324;64329.356706433275;15808.36766529349;15838.208089595475;15856.207189640472;15963.920180399024;15988.440404585785;12077.698057548632;12318.568850090203;12320.137590368768;12334.321612311229;12359.443825027894;16896.915515422592;16928.83071169297;17020.148992550323;17162.485125446256;17163.31346746116;5200.165909579288;5805.970970145122;6063.754333036979;6063.848403789941;6073.635581865102;1024.538957469142;1074.3446497636435;1111.4887151910211;1141.1840534018218;1151.476078384333;31697.84151079276;33127.503087453704;33219.833900830345;33325;33397.499037514455;1135.5868778844954;1146.5471138900255;1161.384466232881;1166.6733314671906;1208.7062753995467;16881.57796055084;17100.486985390457;17101;17130.70649189874;17258.654826903392;522738;522740;524151;524749;525426;59834;60787.08819367713;60844.39155608387;60911.69544152251;61024.389756102755;1293.0366876676749;1407.5355132806149;1424.992875035639;1426.8216472940883;1440.7478691228994;31915.202119947186;33894.66105338915;33994.83002584971;34269.255599145654;34538.82730586331;46582.53417465782;46665.53334466612;47012.294815578476;47071.52928470672;47073.529264706915;397654;397792;401285;402225;402357;36708.81645591862;40370.59629403786;42196.57803421927;42684.35973460463;45333;49472;49714;49820;50106.74733131701;51064.48935510597;41544.792276038424;42784.78607607067;42978.14043719171;43278;43421.78289108661;40002.1999560013;41855.953601158995;42064.57935420729;42564.36153457763;42916.78541607272;41970;42342.78828605961;42939;43247.56752432561;43257.13485730331;35300.042884640236;36681.266374672894;37382.62617373792;37781.622183777814;37802.62197377991;28317;28673.28316792097;28911.42177156403;29065.418691626473;29145 10:27:43 INFO - PROCESS | 7859 | |2;jquery.html;408.5526348648231;410.36393589935597;411.89290784396087;415.59687103509606;426.9103488267464;14008.64978375541;17984.370547030856;19399.224031038742;19579.804201957995;19581;14570.562883113496;18939.62120757584;18975.810241897576;19043.143058562364;19569.902150489263;5520.254765606642;5665.8583535411535;5701.059325211335;5708.1437784332265;5719.428057194289;5645.887082258352;5660.66036037837;5688;5688.857778555528;5701.743421546041;4020.336644453662;4207.26372884744;4885.853424397274;6186.907196392058;6196.75212991482;6123.601965872204;6199.535034872358;6246.531510136712;6272.40412160844;6295.8740825183695;5504.036793561111;5538.390777014525;5546.750396232139;5547.861303467405;5553.527950124244;22380.888095559418;22802.65796013061;22809.42976425586;23562;23585.41036474085;2367.9526409471714;2447.473793134485;2561.0780119157275;2562.2441379792817;2972.925676858096;13656.795148072786;14392.85607143936;14444.422223111063;14539.636509087251;15168;1818.4544636608964;1878.201764250202;1881.9153138108682;1903.1340739963284;1947.0107279199;79186.20813791845;79593.60203199004;79616;79742;80639.19360806375;10112.14046806015;10242;10494.632687855888;10695.465226738788;10755.193360497993;14480;14500.129992200502;14669.046511976901;14758.262086895613;14814.333354999051;7623.771286861403;7833.529988200612;7855.607219638995;7968.282854543119;8057.395695322874;7939.126696063428;8008.879866802003;8132.105468398471;8137.75586732399;8176.6320515576945;5906.2912450505355;6103.6032657877095;6552.213734351908;6558.901616475757;6662.367075127855;1839.1745170046272;1894.9526261843564;1931.159945423745;1943.465546974565;1953.062529985615;991.5438898106952;1026.461107918349;1036.5750042482546;1086.6468397770757;1090.9672709818717;19385;19393.127309271116;19485.805141948684;19724.211031558865;20311.18755249803;1024.3239461955075;1042.83314669654;1094.852194953671;1104.2987702808775;1112.9888701113048;10793.514291856885;11314.321140731417;11430.942845285721;11441.485133169028;11451.942740286411;492904;508111;519426;521229;521733;80176.59911700404;80553;80673;80719.59640201878;80961.59519202367;1075.8171110911007;1080.1412876762993;1089.6622047165404;1096.2983690438125;1096.3038470571244;17094.632429405865;17231.483055508354;17615.911920440314;17675.734863977308;17835.286588536834;36428;36462.63537364592;36714.265714685025;37236;37751.62248377481;47760;49874.25188622101;51278;51322.48677513177;51448.22827657513;8257.38069644791;8258.463199891989;8623.568821559022;8642.919635045619;8672.306215502871;12354.38228088574;12519.624411267676;12631.810522842348;12710.809337860124;12763.680907977376;10682.62610808618;11435.485403156712;11457.828132577852;11508.654740357802;11518.07855371585;9259.657349684123;10717.35695858251;11296.548138074386;11298.887011130113;11518;11270.154738394489;11295;11523.711907202387;11535.653930382103;11644.825327619923;8183.181681831769;8353.33173346143;8391.118932511998;8420.536870471939;8613.138686131337;11168.21822472419;11253.324800511995;11492;11556.42217889119;11760.411979400824 10:27:43 INFO - PROCESS | 7859 | |3;mootools.html;1349.4264937401601;1550.3566020101655;1563.1012168003397;1758.4285107340118;1880.2291060665123;8432.36757243207;8799.34004949629;8812.339074569401;8827.24968377688;9015.098490150982;8446.577671116434;8728.51993140377;8959.193672569467;8962;8965.551722413886;2059.7116403703494;2278.293728944033;2333.9299821005357;2339.836211465198;2377.476955069882;2366.881655917206;2378.0844374915696;2378.82158838087;2379.5478859016816;2380.880955952204;2309.3533810532995;2357.0453966143655;2357.516709074644;2360.775726305998;2363.231949616373;2043.3359158273547;2265.807406370452;2332.8716920569364;2332.9066837326486;2347.3779448446176;2294.4493321602863;2313.051648823991;2320.153144102407;2322;2324.5467133908896;8917;8961.327900407496;8964.103589641049;8974.102589741038;8976.3267754918;5301.204819277101;5418.566514678816;5423.620346575742;5429.511343979061;5432.728363581805;3107.751379889626;3192.898450034734;3207.7754557181006;3283.162793487669;3363.125587347283;1043.7181960870523;1055.8363453664617;1073.940933248671;1076.1390887290136;1087.613897066538;12859.228446293253;13028.869711302861;13029.218246905217;13030.478780848849;13047.86952130476;4652.860414187512;4695.81216751333;4782.234842425195;4787.688800227974;4794.880127996828;10948.397838118979;10952.178586606036;10956.835647465294;10959.452027398598;10963.616273430393;4980.452150263468;5327.121025030904;5473.124300111963;5500;5502.312210973629;4927.827526036644;5079.974600126976;5091.5672167865405;5113.1052065888325;5122.667026643331;4125.968507873032;4156.877643036424;4206.663466922639;4259.212045771542;4273.97863010683;1304.3673818198167;1326.602019394178;1332.4936524120772;1334.3928512526777;1351.351351351357;910.267234875918;974.7563109222694;976.8227502612912;991.8512223166583;992.7270000749852;5760.481556659921;5783.190353350535;5798;5868.9706551467825;6034.219905127998;438.3796927347767;450.84220522816753;457.13145024453803;458.23474797088943;459.89192539753134;3927.430522574268;4024.8188831502653;4026.174634199982;4038.414429907647;4081.551029386765;11116.833247501294;11484.598039068593;11546.307221566565;11552.6534203974;11565.074794016458;10750.677479675622;11249.268797528135;11263.04264137541;11311.151663625262;11372.31766094037;8413.45312554682;8421.789455263544;8426.410151289474;8522.829543409098;8592.05487396386;6283.59156654816;6339.873202536016;6397.96801015992;6416.230052393743;6464.709088091141;10277.229207809294;10573.682789516326;10584.682459526226;10615.575376984834;10707.839382409426;10723.142148628247;10859.674209773719;10871.456427178768;10886.945565272123;10892.346459212473;7427.2201418850245;7856.921430785619;8025.077116131486;8028.558429286445;8102.878456823035;9001.144891235323;9013.63945442175;9066.365354425126;9068.047854975133;9085.636574536944;7899.84200316002;8023;8043.959780201061;8103.351731861319;8146.429749917449;7885;7955.5226686399;8064.677412903418;8082.030156381036;8209.179082091743;6990.196127445205;7035.718571257093;7060.117485314336;7085.14978202619;7119;5030.295758593873;5138.486151384832;5153.227015947488;5181.326427564543;5223.895522089613;5147.382314122179;5267.71027593486;5293.258943747801;5338.599605029716;5364.785408583769 10:27:43 INFO - PROCESS | 7859 | |4;prototype.html;319.669142437577;332.33367098966585;332.5410932912581;334.2296007702245;339.74519110667;5215.6349055566125;5285.762140703668;5392.784288628455;5415.512603865652;5453.781848726051;5324.57731732062;5334.813281535137;5362.6514276572;5375.543078838294;5393.597664607201;4193.161367726451;4194.748315101104;4197.769122698266;4209.7474151551005;4230.153969206156;3908.433277174808;3949.9210015799667;4134.896627584304;4186.5604111568155;4209.705320627558;4101.26177288088;4108.863391036659;4117.938230926538;4133.21468920904;4139.937900931488;4101.876943691694;4132.690048246393;4160.5007399112;4164.895877603084;4190.811413486401;3481.910853487887;3675.852965881388;3677.9080522986874;3681.4845921571;3692.298463291966;5103.719295438749;5125.718085505296;5145.76844042019;5151.8454446366295;5171.2501687255135;4714.030160998584;4725.740084295362;4732.929006064877;4733.881652958669;4746.691465054761;5447.155690867922;5569.888602227973;5570.554355651539;5590.552755779528;5631.465010824006;3970.4242884781834;4001.339778936472;4009.5766003069075;4017.517897852247;4029.194161167778;5799.710014499258;5811.442965095387;5820.3888591698105;5837.241158649348;5891.204687367236;3929.7052721046034;4096.549379568245;4107.096438783463;4122.56713045132;4133.689973252018;4076.1860971867236;4093.9590604094174;4097.180563887235;4104.302268614283;4119.958800412018;4694.859154225379;4695.929561056587;4710.740909249956;4732.858014259577;4734.384530011076;5474.096774032319;5578.944210557843;5580.381689310055;5610.6517448977365;5664.943350566524;1411.574309946961;1415.7451658701327;1418.163283662644;1434.8493408192198;1435.3110506956718;892.3575025981283;903.0608167505852;908.7909780750465;919.7010971434177;928.2944961828923;4895.877603059952;4906.460289368167;4906.55840974314;4907.754612269372;4917.680350777188;5142.742862856842;5157.793688252503;5161.458046905096;5195.948040519622;5211.609129315317;4721.686373676874;4883.511648835088;4913.213885778258;4933.925991110136;4959.677620954626;2687.5565531687444;2695.9326016849736;2719;2724.4959682458807;2746.780257579389;5203.895922081537;5278.261043453919;5315.6279060465395;5323.041852466516;5400.540954018874;4833.806647734122;5164.9741751291;5184.222366645034;5191.273221748958;5223.075077956357;5218.66078704883;5258.027264955996;5343.465653434625;5388.410418926348;5392.815909556557;5197.740112994259;5199.558037566698;5200.7778172128765;5203.661761985459;5204.583633309402;5187.29971453864;5401.513863752281;5804.187413762161;5805.738741756547;5812.854678633068;6078.264780848547;6088.878222435438;6090.9390906090375;6094.8171554853425;6100.633961962297;3886.7862267575556;4019.517657881073;4099.651529620016;4104.630583247522;4198.979005104955;5658.915116273177;5705.800296989585;5751;5764.250647415809;5783.334916484489;5602.663840169603;5603.971980140236;5631.352394474524;5650.660960342392;5657.2928383952;5355.973220133875;5581.748821303132;5615.803446879503;5632.451075954145;5640.85897852557;5436.592255580768;5461.617686761889;5534.169874518854;5536.529395001309;5549.750261238173;5392.757325920422;5600.4959553640365;5620.494155526022;5640.830775076754;5707.172459993195;5516.862078448071;5564.693941833076;5566.220729097849;5579.609427340048;5613.46672066153 10:27:43 INFO - PROCESS | 7859 | |5;yui.html;753.0398741604457;761.0486891385768;774.5468900693094;787.4920676163873;820.4257020085939;15102.395904163834;15347.769783453257;15617.609559761011;15882.364705411785;16044.197790110506;14726.705465890676;14822.777658335133;14833.703325933502;14854.554363369089;14868.479603213862;702.0276916470699;828.4904783558129;846.8729690546437;850.2475309351238;858.7638399440142;859.4327743689166;860.384824850232;861.0399404663787;861.9482831030158;864.1358641358642;884.7434244069212;888.0630934364243;888.1695614600369;888.2627419242014;889.2130464538898;730.9817254568625;750.5309181761372;867.371155911965;881.0132651430407;882.496976723268;647.3752828520453;647.8056583025074;647.9319671434479;648.3160265919453;648.6659370424235;14721.04313219648;14735.115893046344;14749.852501475063;14756.852431475763;14761.852381476156;13353;14089;14352.425902963869;14358.56924292273;14579.562613121623;11965.581204657883;12525.686857828536;12768.80846787299;12992.090553661248;13097.148685335424;4536.705114167568;4540.614047805941;4571.977140114312;4573.382593349888;4624.606908412756;55045.44954550443;56196.43803561953;56305.71847140779;58737.41262587405;61826;11597.6520704379;11839.17125801203;11886.940565297118;11956.043516518657;12212.32832194238;20305.695414568792;20321.695174572393;20388.694169587467;20402.795972040385;20440.897795510926;8563.914360856435;8579.742607721653;8619.784505387415;8757.649694012296;8903.109689031045;4790.329353890458;4819.566239038503;4838.322634831126;4850.902981940341;4892.143874821892;3445.0870519312402;3600.8199590020395;3605.6394360563736;3616.150204701893;3618.3848745713294;672.7174586673609;682.74738346811;730.6200775596766;732.7398773435353;734.0494060192065;731.4331393170229;772.1166886093052;793.7856778669727;813.2152472863714;815.0871024452532;9440.95279523598;9980.900190998142;9997.400155990665;10006.19950403966;10110.595576176871;617.478230894893;645.8191706322237;719.5790462579449;756.5460723565817;765.8468306338755;4277.550857159953;4546.818127274938;4673.275642275453;4700.613319070882;4795.808167673254;75647;76957.23042769612;77199;77490.6125469369;77501.6124919383;54986.45013549893;55691.16463253054;55739;65514.344856551776;67588.3241167582;58414.415855840896;58436.70781646064;58593.99999999915;59077.40922590805;60342;9660.227181825578;9789.140063387933;9880.50597470138;9917.107460328605;10199.592016319264;37318;37956;38267.42598861075;38948.41577376285;39057.609423906535;45032.324515131644;46126.30810537777;46239.07521849612;47556.2866556995;49436.75281623713;12012.879871201176;12492.687682808002;12652.746945060862;12707.046971476992;12890.35548222604;14084.859151408764;14400.42398304098;14715.632109197355;14879.553613391616;15001.624959376102;13368.331583420984;13653.59039228785;13736.244506552235;13983.091099078934;14175.36210870534;13184.208947463183;13473.124246923919;13885.652858678613;13998.860011399756;14013.159210447406;12630.557930472389;12811.359432028175;13100.868991309964;13182.670433239246;13483;9911.603535858774;9967.750806229902;10500.267455869696;10563.52464139131;10705.197110216921;10572.947135264274;10759.731006724895;10848.566057357617;10849.783004340026;10878.292910960763 10:27:43 INFO - PROCESS | 7859 | __end_tp_report 10:27:43 INFO - PROCESS | 7859 | __start_cc_report 10:27:43 INFO - PROCESS | 7859 | _x_x_mozilla_cycle_collect,801 10:27:43 INFO - PROCESS | 7859 | __end_cc_report 10:27:43 INFO - PROCESS | 7859 | __startTimestamp1463160463219__endTimestamp 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | ------- Summary: start ------- 10:27:43 INFO - PROCESS | 7859 | Number of tests: 6 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | [#0] dojo.html Cycles:165 Average:5326.51 Median:5531.34 stddev:609.07 (11.0%) stddev-sans-first:586.98 10:27:43 INFO - PROCESS | 7859 | Values: 3170.6 3187.8 3211.6 3222.3 3241.0 5203.8 5315.5 5328.2 5428.2 5429.1 5344.2 5382.1 5384.4 5388.3 5431.7 4843.4 4858.2 4968.5 4971.8 5012.9 4936.1 4963.1 4999.4 5012.9 5017.8 4769.9 4803.4 4827.0 4867.6 4875.3 4849.9 4877.5 4882.5 4883.7 4930.4 4717.7 4726.2 4809.2 4821.2 4881.1 5250.0 5288.6 5296.9 5356.5 5388.5 5704.6 5772.8 5776.8 5777.2 5783.3 5582.5 5626.5 5783.8 5821.4 5824.5 5311.8 5371.1 5382.1 5389.7 5393.8 5865.8 5871.6 5876.5 5881.2 5881.5 5446.9 5555.0 5555.4 5636.0 5637.1 5694.7 5703.4 5704.6 5717.1 5742.8 5655.2 5694.0 5708.9 5712.7 5717.9 5474.9 5622.7 5658.5 5672.9 5694.6 4329.7 4330.4 4330.8 4352.9 4360.2 3885.2 3890.3 3902.6 3939.7 3940.3 5368.5 5419.8 5458.1 5484.1 5484.5 5794.4 5812.9 5818.7 5825.1 5826.5 5509.9 5520.8 5524.8 5526.9 5531.3 4531.3 4623.8 4662.5 4676.9 4737.4 6069.3 6073.9 6076.2 6083.1 6086.7 5774.1 5794.9 5797.4 5801.6 5804.3 5781.9 5907.2 6001.5 6001.9 6035.2 5622.8 5626.0 5664.3 5670.7 5674.8 5637.3 5741.3 5751.7 5760.0 5774.2 5544.4 5569.2 5613.4 5623.3 5625.8 5525.6 5561.5 5586.7 5588.4 5594.1 5396.6 5449.9 5492.1 5511.9 5547.7 5614.3 5721.4 5731.4 5732.3 5733.2 5479.8 5531.4 5536.4 5552.3 5559.4 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | [#1] ext.html Cycles:180 Average:60355.61 Median:218897.10 stddev:115542.08 (52.8%) stddev-sans-first:115779.13 10:27:43 INFO - PROCESS | 7859 | Values: 609.5 632.0 643.5 651.4 661.2 33190.0 43243.8 44422.3 44465.6 44477.3 45958.3 46543.0 46567.8 46630.8 46644.0 7913.2 8842.3 8989.8 8995.7 10304.2 10099.3 10520.1 10527.0 10539.8 10544.9 10617.0 10664.5 10666.4 10671.6 10675.3 8066.6 10320.9 10520.5 10539.5 10547.1 10539.5 10611.7 10615.5 10621.2 10621.5 42059.8 42307.6 42397.6 42515.6 42519.0 3590.0 3720.6 4217.2 4303.0 4348.8 32515.3 32562.8 32918.5 32966.7 33444.7 4224.4 4228.8 4259.6 4318.1 4458.6 370997.0 371766.0 372188.1 372562.0 372991.9 52599.7 56948.0 58244.7 58374.1 64193.0 64055.0 64294.7 64315.7 64324.0 64329.4 15808.4 15838.2 15856.2 15963.9 15988.4 12077.7 12318.6 12320.1 12334.3 12359.4 16896.9 16928.8 17020.1 17162.5 17163.3 5200.2 5806.0 6063.8 6063.8 6073.6 1024.5 1074.3 1111.5 1141.2 1151.5 31697.8 33127.5 33219.8 33325.0 33397.5 1135.6 1146.5 1161.4 1166.7 1208.7 16881.6 17100.5 17101.0 17130.7 17258.7 522738.0 522740.0 524151.0 524749.0 525426.0 59834.0 60787.1 60844.4 60911.7 61024.4 1293.0 1407.5 1425.0 1426.8 1440.7 31915.2 33894.7 33994.8 34269.3 34538.8 46582.5 46665.5 47012.3 47071.5 47073.5 397654.0 397792.0 401285.0 402225.0 402357.0 36708.8 40370.6 42196.6 42684.4 45333.0 49472.0 49714.0 49820.0 50106.7 51064.5 41544.8 42784.8 42978.1 43278.0 43421.8 40002.2 41856.0 42064.6 42564.4 42916.8 41970.0 42342.8 42939.0 43247.6 43257.1 35300.0 36681.3 37382.6 37781.6 37802.6 28317.0 28673.3 28911.4 29065.4 29145.0 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | [#2] jquery.html Cycles:180 Average:28963.31 Median:23185.71 stddev:84103.94 (362.7%) stddev-sans-first:84312.54 10:27:43 INFO - PROCESS | 7859 | Values: 408.6 410.4 411.9 415.6 426.9 14008.6 17984.4 19399.2 19579.8 19581.0 14570.6 18939.6 18975.8 19043.1 19569.9 5520.3 5665.9 5701.1 5708.1 5719.4 5645.9 5660.7 5688.0 5688.9 5701.7 4020.3 4207.3 4885.9 6186.9 6196.8 6123.6 6199.5 6246.5 6272.4 6295.9 5504.0 5538.4 5546.8 5547.9 5553.5 22380.9 22802.7 22809.4 23562.0 23585.4 2368.0 2447.5 2561.1 2562.2 2972.9 13656.8 14392.9 14444.4 14539.6 15168.0 1818.5 1878.2 1881.9 1903.1 1947.0 79186.2 79593.6 79616.0 79742.0 80639.2 10112.1 10242.0 10494.6 10695.5 10755.2 14480.0 14500.1 14669.0 14758.3 14814.3 7623.8 7833.5 7855.6 7968.3 8057.4 7939.1 8008.9 8132.1 8137.8 8176.6 5906.3 6103.6 6552.2 6558.9 6662.4 1839.2 1895.0 1931.2 1943.5 1953.1 991.5 1026.5 1036.6 1086.6 1091.0 19385.0 19393.1 19485.8 19724.2 20311.2 1024.3 1042.8 1094.9 1104.3 1113.0 10793.5 11314.3 11430.9 11441.5 11451.9 492904.0 508111.0 519426.0 521229.0 521733.0 80176.6 80553.0 80673.0 80719.6 80961.6 1075.8 1080.1 1089.7 1096.3 1096.3 17094.6 17231.5 17615.9 17675.7 17835.3 36428.0 36462.6 36714.3 37236.0 37751.6 47760.0 49874.3 51278.0 51322.5 51448.2 8257.4 8258.5 8623.6 8642.9 8672.3 12354.4 12519.6 12631.8 12710.8 12763.7 10682.6 11435.5 11457.8 11508.7 11518.1 9259.7 10717.4 11296.5 11298.9 11518.0 11270.2 11295.0 11523.7 11535.7 11644.8 8183.2 8353.3 8391.1 8420.5 8613.1 11168.2 11253.3 11492.0 11556.4 11760.4 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | [#3] mootools.html Cycles:180 Average:5966.63 Median:4674.34 stddev:3491.03 (74.7%) stddev-sans-first:3483.58 10:27:43 INFO - PROCESS | 7859 | Values: 1349.4 1550.4 1563.1 1758.4 1880.2 8432.4 8799.3 8812.3 8827.2 9015.1 8446.6 8728.5 8959.2 8962.0 8965.6 2059.7 2278.3 2333.9 2339.8 2377.5 2366.9 2378.1 2378.8 2379.5 2380.9 2309.4 2357.0 2357.5 2360.8 2363.2 2043.3 2265.8 2332.9 2332.9 2347.4 2294.4 2313.1 2320.2 2322.0 2324.5 8917.0 8961.3 8964.1 8974.1 8976.3 5301.2 5418.6 5423.6 5429.5 5432.7 3107.8 3192.9 3207.8 3283.2 3363.1 1043.7 1055.8 1073.9 1076.1 1087.6 12859.2 13028.9 13029.2 13030.5 13047.9 4652.9 4695.8 4782.2 4787.7 4794.9 10948.4 10952.2 10956.8 10959.5 10963.6 4980.5 5327.1 5473.1 5500.0 5502.3 4927.8 5080.0 5091.6 5113.1 5122.7 4126.0 4156.9 4206.7 4259.2 4274.0 1304.4 1326.6 1332.5 1334.4 1351.4 910.3 974.8 976.8 991.9 992.7 5760.5 5783.2 5798.0 5869.0 6034.2 438.4 450.8 457.1 458.2 459.9 3927.4 4024.8 4026.2 4038.4 4081.6 11116.8 11484.6 11546.3 11552.7 11565.1 10750.7 11249.3 11263.0 11311.2 11372.3 8413.5 8421.8 8426.4 8522.8 8592.1 6283.6 6339.9 6398.0 6416.2 6464.7 10277.2 10573.7 10584.7 10615.6 10707.8 10723.1 10859.7 10871.5 10886.9 10892.3 7427.2 7856.9 8025.1 8028.6 8102.9 9001.1 9013.6 9066.4 9068.0 9085.6 7899.8 8023.0 8044.0 8103.4 8146.4 7885.0 7955.5 8064.7 8082.0 8209.2 6990.2 7035.7 7060.1 7085.1 7119.0 5030.3 5138.5 5153.2 5181.3 5223.9 5147.4 5267.7 5293.3 5338.6 5364.8 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | [#4] prototype.html Cycles:180 Average:4591.19 Median:5185.76 stddev:1346.14 (26.0%) stddev-sans-first:1311.18 10:27:43 INFO - PROCESS | 7859 | Values: 319.7 332.3 332.5 334.2 339.7 5215.6 5285.8 5392.8 5415.5 5453.8 5324.6 5334.8 5362.7 5375.5 5393.6 4193.2 4194.7 4197.8 4209.7 4230.2 3908.4 3949.9 4134.9 4186.6 4209.7 4101.3 4108.9 4117.9 4133.2 4139.9 4101.9 4132.7 4160.5 4164.9 4190.8 3481.9 3675.9 3677.9 3681.5 3692.3 5103.7 5125.7 5145.8 5151.8 5171.3 4714.0 4725.7 4732.9 4733.9 4746.7 5447.2 5569.9 5570.6 5590.6 5631.5 3970.4 4001.3 4009.6 4017.5 4029.2 5799.7 5811.4 5820.4 5837.2 5891.2 3929.7 4096.5 4107.1 4122.6 4133.7 4076.2 4094.0 4097.2 4104.3 4120.0 4694.9 4695.9 4710.7 4732.9 4734.4 5474.1 5578.9 5580.4 5610.7 5664.9 1411.6 1415.7 1418.2 1434.8 1435.3 892.4 903.1 908.8 919.7 928.3 4895.9 4906.5 4906.6 4907.8 4917.7 5142.7 5157.8 5161.5 5195.9 5211.6 4721.7 4883.5 4913.2 4933.9 4959.7 2687.6 2695.9 2719.0 2724.5 2746.8 5203.9 5278.3 5315.6 5323.0 5400.5 4833.8 5165.0 5184.2 5191.3 5223.1 5218.7 5258.0 5343.5 5388.4 5392.8 5197.7 5199.6 5200.8 5203.7 5204.6 5187.3 5401.5 5804.2 5805.7 5812.9 6078.3 6088.9 6090.9 6094.8 6100.6 3886.8 4019.5 4099.7 4104.6 4199.0 5658.9 5705.8 5751.0 5764.3 5783.3 5602.7 5604.0 5631.4 5650.7 5657.3 5356.0 5581.7 5615.8 5632.5 5640.9 5436.6 5461.6 5534.2 5536.5 5549.8 5392.8 5600.5 5620.5 5640.8 5707.2 5516.9 5564.7 5566.2 5579.6 5613.5 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - PROCESS | 7859 | [#5] yui.html Cycles:180 Average:16244.18 Median:4648.94 stddev:19498.42 (419.4%) stddev-sans-first:19518.42 10:27:43 INFO - PROCESS | 7859 | Values: 753.0 761.0 774.5 787.5 820.4 15102.4 15347.8 15617.6 15882.4 16044.2 14726.7 14822.8 14833.7 14854.6 14868.5 702.0 828.5 846.9 850.2 858.8 859.4 860.4 861.0 861.9 864.1 884.7 888.1 888.2 888.3 889.2 731.0 750.5 867.4 881.0 882.5 647.4 647.8 647.9 648.3 648.7 14721.0 14735.1 14749.9 14756.9 14761.9 13353.0 14089.0 14352.4 14358.6 14579.6 11965.6 12525.7 12768.8 12992.1 13097.1 4536.7 4540.6 4572.0 4573.4 4624.6 55045.4 56196.4 56305.7 58737.4 61826.0 11597.7 11839.2 11886.9 11956.0 12212.3 20305.7 20321.7 20388.7 20402.8 20440.9 8563.9 8579.7 8619.8 8757.6 8903.1 4790.3 4819.6 4838.3 4850.9 4892.1 3445.1 3600.8 3605.6 3616.2 3618.4 672.7 682.7 730.6 732.7 734.0 731.4 772.1 793.8 813.2 815.1 9441.0 9980.9 9997.4 10006.2 10110.6 617.5 645.8 719.6 756.5 765.8 4277.6 4546.8 4673.3 4700.6 4795.8 75647.0 76957.2 77199.0 77490.6 77501.6 54986.5 55691.2 55739.0 65514.3 67588.3 58414.4 58436.7 58594.0 59077.4 60342.0 9660.2 9789.1 9880.5 9917.1 10199.6 37318.0 37956.0 38267.4 38948.4 39057.6 45032.3 46126.3 46239.1 47556.3 49436.8 12012.9 12492.7 12652.7 12707.0 12890.4 14084.9 14400.4 14715.6 14879.6 15001.6 13368.3 13653.6 13736.2 13983.1 14175.4 13184.2 13473.1 13885.7 13998.9 14013.2 12630.6 12811.4 13100.9 13182.7 13483.0 9911.6 9967.8 10500.3 10563.5 10705.2 10572.9 10759.7 10848.6 10849.8 10878.3 10:27:43 INFO - PROCESS | 7859 | -------- Summary: end -------- 10:27:43 INFO - PROCESS | 7859 | 10:27:43 INFO - TEST-INFO | 7859: exit 0 10:27:43 INFO - TEST-OK | dromaeo_css | took 1112716ms 10:27:43 INFO - TEST-START | kraken 10:27:43 INFO - operating with platform_type : linux_ 10:27:43 INFO - Initialising browser for kraken test... 10:27:43 INFO - TEST-INFO | started process 8043 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt5PES3/profile http://localhost:53129/getInfo.html) 10:27:47 INFO - PROCESS | 8043 | __metrics Screen width/height:1600/1200 10:27:47 INFO - PROCESS | 8043 | colorDepth:24 10:27:47 INFO - PROCESS | 8043 | Browser inner width/height: 1024/697 10:27:47 INFO - PROCESS | 8043 | __metrics 10:27:48 ERROR - PROCESS | 8043 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:27:49 INFO - TEST-INFO | 8043: exit 0 10:27:49 INFO - Browser initialized. 10:27:49 INFO - Running cycle 1/1 for kraken test... 10:27:49 INFO - TEST-INFO | started process 8126 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt5PES3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:28:01 INFO - PROCESS | 8126 | RSS: Main: 152215552 10:28:01 INFO - PROCESS | 8126 | 10:28:04 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53129/tests/kraken/driver.html?testName=audio-beat-detection) 10:28:05 INFO - PROCESS | 8126 | RSS: Main: 208678912 10:28:05 INFO - PROCESS | 8126 | 10:28:07 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53129/tests/kraken/driver.html?testName=audio-dft) 10:28:08 INFO - PROCESS | 8126 | RSS: Main: 198033408 10:28:08 INFO - PROCESS | 8126 | 10:28:11 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53129/tests/kraken/driver.html?testName=audio-fft) 10:28:12 INFO - PROCESS | 8126 | RSS: Main: 265261056 10:28:12 INFO - PROCESS | 8126 | 10:28:14 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53129/tests/kraken/driver.html?testName=audio-oscillator) 10:28:14 INFO - PROCESS | 8126 | RSS: Main: 174411776 10:28:14 INFO - PROCESS | 8126 | 10:28:17 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53129/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:28:17 INFO - PROCESS | 8126 | RSS: Main: 179019776 10:28:17 INFO - PROCESS | 8126 | 10:28:21 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53129/tests/kraken/driver.html?testName=imaging-darkroom) 10:28:21 INFO - PROCESS | 8126 | RSS: Main: 214675456 10:28:21 INFO - PROCESS | 8126 | 10:28:25 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53129/tests/kraken/driver.html?testName=imaging-desaturate) 10:28:25 INFO - PROCESS | 8126 | RSS: Main: 212893696 10:28:25 INFO - PROCESS | 8126 | 10:28:28 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53129/tests/kraken/driver.html?testName=json-parse-financial) 10:28:29 INFO - PROCESS | 8126 | RSS: Main: 214114304 10:28:29 INFO - PROCESS | 8126 | 10:28:30 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53129/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:28:31 INFO - PROCESS | 8126 | RSS: Main: 201883648 10:28:31 INFO - PROCESS | 8126 | 10:28:33 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:28:33 INFO - PROCESS | 8126 | RSS: Main: 206254080 10:28:33 INFO - PROCESS | 8126 | 10:28:35 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:28:36 INFO - PROCESS | 8126 | RSS: Main: 216416256 10:28:36 INFO - PROCESS | 8126 | 10:28:39 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:28:39 INFO - PROCESS | 8126 | RSS: Main: 223584256 10:28:39 INFO - PROCESS | 8126 | 10:28:42 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:28:43 INFO - PROCESS | 8126 | RSS: Main: 220454912 10:28:43 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | Cycle 1(1): loaded http://localhost:53129/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53129/tests/kraken/driver.html?testName=ai-astar) 10:28:45 INFO - PROCESS | 8126 | RSS: Main: 266010624 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | __start_tp_report 10:28:45 INFO - PROCESS | 8126 | _x_x_mozilla_page_load 10:28:45 INFO - PROCESS | 8126 | _x_x_mozilla_page_load_details 10:28:45 INFO - PROCESS | 8126 | |i|pagename|runs| 10:28:45 INFO - PROCESS | 8126 | |0;ai-astar;114;106;110;111;112;110;110;112;110;112 10:28:45 INFO - PROCESS | 8126 | |1;audio-beat-detection;154;132;153;216;127;153;153;125;190;126 10:28:45 INFO - PROCESS | 8126 | |2;audio-dft;162;160;160;161;160;161;161;161;162;161 10:28:45 INFO - PROCESS | 8126 | |3;audio-fft;75;76;75;152;75;73;76;75;130;76 10:28:45 INFO - PROCESS | 8126 | |4;audio-oscillator;92;92;147;93;91;90;129;92;91;128 10:28:45 INFO - PROCESS | 8126 | |5;imaging-gaussian-blur;117;116;116;116;117;117;117;117;117;118 10:28:45 INFO - PROCESS | 8126 | |6;imaging-darkroom;145;145;145;146;146;146;145;146;145;145 10:28:45 INFO - PROCESS | 8126 | |7;imaging-desaturate;95;95;95;95;95;96;95;96;94;96 10:28:45 INFO - PROCESS | 8126 | |8;json-parse-financial;54;54;56;54;55;54;57;107;53;52 10:28:45 INFO - PROCESS | 8126 | |9;json-stringify-tinderbox;67;60;59;59;59;59;59;66;59;59 10:28:45 INFO - PROCESS | 8126 | |10;stanford-crypto-aes;79;79;78;77;85;82;78;80;79;79 10:28:45 INFO - PROCESS | 8126 | |11;stanford-crypto-ccm;130;137;129;118;120;119;118;117;199;122 10:28:45 INFO - PROCESS | 8126 | |12;stanford-crypto-pbkdf2;162;161;165;163;164;162;163;169;163;169 10:28:45 INFO - PROCESS | 8126 | |13;stanford-crypto-sha256-iterative;65;64;65;66;65;63;63;62;62;61 10:28:45 INFO - PROCESS | 8126 | __end_tp_report 10:28:45 INFO - PROCESS | 8126 | __start_cc_report 10:28:45 INFO - PROCESS | 8126 | _x_x_mozilla_cycle_collect,1834 10:28:45 INFO - PROCESS | 8126 | __end_cc_report 10:28:45 INFO - PROCESS | 8126 | __startTimestamp1463160525339__endTimestamp 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | ------- Summary: start ------- 10:28:45 INFO - PROCESS | 8126 | Number of tests: 14 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.50 stddev:2.11 (1.9%) stddev-sans-first:1.87 10:28:45 INFO - PROCESS | 8126 | Values: 114.0 106.0 110.0 111.0 112.0 110.0 110.0 112.0 110.0 112.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#1] audio-beat-detection Cycles:10 Average:152.90 Median:153.00 stddev:29.76 (19.4%) stddev-sans-first:31.56 10:28:45 INFO - PROCESS | 8126 | Values: 154.0 132.0 153.0 216.0 127.0 153.0 153.0 125.0 190.0 126.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 10:28:45 INFO - PROCESS | 8126 | Values: 162.0 160.0 160.0 161.0 160.0 161.0 161.0 161.0 162.0 161.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#3] audio-fft Cycles:10 Average:88.30 Median:75.00 stddev:28.27 (37.7%) stddev-sans-first:29.57 10:28:45 INFO - PROCESS | 8126 | Values: 75.0 76.0 75.0 152.0 75.0 73.0 76.0 75.0 130.0 76.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:91.50 stddev:21.43 (23.4%) stddev-sans-first:22.25 10:28:45 INFO - PROCESS | 8126 | Values: 92.0 92.0 147.0 93.0 91.0 90.0 129.0 92.0 91.0 128.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 10:28:45 INFO - PROCESS | 8126 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:28:45 INFO - PROCESS | 8126 | Values: 145.0 145.0 145.0 146.0 146.0 146.0 145.0 146.0 145.0 145.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 10:28:45 INFO - PROCESS | 8126 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 94.0 96.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:54.00 stddev:16.71 (31.0%) stddev-sans-first:17.61 10:28:45 INFO - PROCESS | 8126 | Values: 54.0 54.0 56.0 54.0 55.0 54.0 57.0 107.0 53.0 52.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:59.00 stddev:3.13 (5.3%) stddev-sans-first:2.32 10:28:45 INFO - PROCESS | 8126 | Values: 67.0 60.0 59.0 59.0 59.0 59.0 59.0 66.0 59.0 59.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:2.32 (2.9%) stddev-sans-first:2.45 10:28:45 INFO - PROCESS | 8126 | Values: 79.0 79.0 78.0 77.0 85.0 82.0 78.0 80.0 79.0 79.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#11] stanford-crypto-ccm Cycles:10 Average:130.90 Median:125.50 stddev:24.82 (19.8%) stddev-sans-first:26.32 10:28:45 INFO - PROCESS | 8126 | Values: 130.0 137.0 129.0 118.0 120.0 119.0 118.0 117.0 199.0 122.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:163.50 stddev:2.81 (1.7%) stddev-sans-first:2.87 10:28:45 INFO - PROCESS | 8126 | Values: 162.0 161.0 165.0 163.0 164.0 162.0 163.0 169.0 163.0 169.0 10:28:45 INFO - PROCESS | 8126 | 10:28:45 INFO - PROCESS | 8126 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.50 stddev:1.65 (2.6%) stddev-sans-first:1.67 10:28:45 INFO - PROCESS | 8126 | Values: 65.0 64.0 65.0 66.0 65.0 63.0 63.0 62.0 62.0 61.0 10:28:45 INFO - PROCESS | 8126 | -------- Summary: end -------- 10:28:45 INFO - PROCESS | 8126 | 10:28:46 INFO - TEST-INFO | 8126: exit 0 10:28:46 INFO - TEST-OK | kraken | took 62420ms 10:28:46 INFO - SUITE-END | took 1175s 10:28:46 INFO - Completed test suite (00:19:35) 10:28:46 INFO - javascript benchmark 10:28:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3170.6036745406823, 3187.7927934684258, 3211.566438530798, 3222.30720395115, 3241.0, 5203.817866374678, 5315.468453154683, 5328.227407025979, 5428.240046393503, 5429.131338985758, 5344.198370244456, 5382.138857782746, 5384.3807962084375, 5388.299521062257, 5431.674099554029, 4843.370361852954, 4858.246971719389, 4968.478309777475, 4971.776270067838, 5012.949870501285, 4936.06214819185, 4963.081829861487, 4999.4000719913665, 5012.924806127893, 5017.799288028475, 4769.90460190796, 4803.423589169304, 4827.0, 4867.586255168298, 4875.293082503017, 4849.927251091236, 4877.536634019798, 4882.511748825124, 4883.658143929926, 4930.408350997904, 4717.669763116583, 4726.196546587088, 4809.206480930642, 4821.204501257288, 4881.096997055521, 5250.028744682246, 5288.629795914288, 5296.947030529684, 5356.517913387814, 5388.461153884619, 5704.572157088235, 5772.797952071657, 5776.768929242825, 5777.24895763548, 5783.277090363705, 5582.49757521825, 5626.521745651625, 5783.797567085132, 5821.388754180834, 5824.504917082053, 5311.760970756325, 5371.086915224421, 5382.138857782736, 5389.703566303851, 5393.757280922328, 5865.794697185578, 5871.618344807659, 5876.500497457764, 5881.235439392852, 5881.529477641779, 5446.91829622556, 5554.972225138848, 5555.388907220203, 5635.971820140873, 5637.126245431965, 5694.686792226385, 5703.429657034347, 5704.600677952587, 5717.056685646905, 5742.827715168552, 5655.151727240865, 5694.032014557535, 5708.885822283614, 5712.657240565496, 5717.942820571824, 5474.945250547444, 5622.746976386073, 5658.490735833795, 5672.858178545569, 5694.572907031989, 4329.675274354436, 4330.35044743291, 4330.783460826946, 4352.869413917588, 4360.215161270939, 3885.1646895917515, 3890.3466921119525, 3902.551206611219, 3939.7242193046786, 3940.2513522430645, 5368.4631536846, 5419.837404877781, 5458.099413596712, 5484.067708489566, 5484.533814625722, 5794.362620111784, 5812.854678633068, 5818.709064546756, 5825.097109947965, 5826.475617194388, 5509.889802203934, 5520.806771762969, 5524.779008839601, 5526.861828454321, 5531.3362396512675, 4531.320301954733, 4623.768811559408, 4662.463816661059, 4676.947686770517, 4737.431508219036, 6069.332373438918, 6073.878522429527, 6076.210092687922, 6083.117947897618, 6086.695665216722, 5774.133879918046, 5794.85512862165, 5797.391273916347, 5801.622894511843, 5804.332501762351, 5781.94218057814, 5907.17299578068, 6001.489873360639, 6001.939980600138, 6035.185249991195, 5622.775088996558, 5625.96445816128, 5664.291963504562, 5670.659760414388, 5674.77300907959, 5637.267155269788, 5741.311042674905, 5751.712414379181, 5760.0, 5774.191613174243, 5544.417836127149, 5569.164625306236, 5613.354464236665, 5623.297087864017, 5625.774969001358, 5525.585581081355, 5561.4716601922755, 5586.692731899622, 5588.441155884541, 5594.076977298863, 5396.595255355943, 5449.945500544944, 5492.066348720807, 5511.917321240265, 5547.667139971614, 5614.270144881302, 5721.427857214246, 5731.398203188605, 5732.283464566929, 5733.197352370755, 5479.808206712746, 5531.391546929754, 5536.363318218457, 5552.305961754781, 5559.388467268678], "value": 5285.936235942478, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.497164839008, 631.9620822750633, 643.47235267081, 651.3584119642154, 661.2065521374352, 33190.0, 43243.78378108113, 44422.33366499505, 44465.55534444655, 44477.332840007424, 45958.310625340644, 46543.0, 46567.76716116415, 46630.76684616572, 46644.0, 7913.20867913207, 8842.29261659069, 8989.77525561863, 8995.730128096167, 10304.227182961271, 10099.34354266974, 10520.105791007736, 10527.0, 10539.84190237147, 10544.947275263614, 10617.044465998059, 10664.520096595672, 10666.360018398882, 10671.573137074507, 10675.306105103182, 8066.5966701664975, 10320.948395257976, 10520.473976301231, 10539.473026348729, 10547.05076543111, 10539.525721342558, 10611.734706632318, 10615.52230149645, 10621.150307975344, 10621.468926553718, 42059.7897010516, 42307.576924230976, 42397.57602423967, 42515.57484425178, 42519.0, 3590.0, 3720.609336019706, 4217.219814334328, 4302.9569704303185, 4348.782560871975, 32515.349693006006, 32562.837185814155, 32918.506222406686, 32966.6703332966, 33444.6655533444, 4224.366345048267, 4228.813643645329, 4259.5740425957465, 4318.093200427914, 4458.598726114666, 370997.0, 371766.0, 372188.139059303, 372562.0, 372991.94528191385, 52599.73700131475, 56948.0, 58244.708776455846, 58374.12438813421, 64193.0, 64055.0, 64294.678526607066, 64315.71368572603, 64324.0, 64329.356706433275, 15808.36766529349, 15838.208089595475, 15856.207189640472, 15963.920180399024, 15988.440404585785, 12077.698057548632, 12318.568850090203, 12320.137590368768, 12334.321612311229, 12359.443825027894, 16896.915515422592, 16928.83071169297, 17020.148992550323, 17162.485125446256, 17163.31346746116, 5200.165909579288, 5805.970970145122, 6063.754333036979, 6063.848403789941, 6073.635581865102, 1024.538957469142, 1074.3446497636435, 1111.4887151910211, 1141.1840534018218, 1151.476078384333, 31697.84151079276, 33127.503087453704, 33219.833900830345, 33325.0, 33397.499037514455, 1135.5868778844954, 1146.5471138900255, 1161.384466232881, 1166.6733314671906, 1208.7062753995467, 16881.57796055084, 17100.486985390457, 17101.0, 17130.70649189874, 17258.654826903392, 522738.0, 522740.0, 524151.0, 524749.0, 525426.0, 59834.0, 60787.08819367713, 60844.39155608387, 60911.69544152251, 61024.389756102755, 1293.0366876676749, 1407.5355132806149, 1424.992875035639, 1426.8216472940883, 1440.7478691228994, 31915.202119947186, 33894.66105338915, 33994.83002584971, 34269.255599145654, 34538.82730586331, 46582.53417465782, 46665.53334466612, 47012.294815578476, 47071.52928470672, 47073.529264706915, 397654.0, 397792.0, 401285.0, 402225.0, 402357.0, 36708.81645591862, 40370.59629403786, 42196.57803421927, 42684.35973460463, 45333.0, 49472.0, 49714.0, 49820.0, 50106.74733131701, 51064.48935510597, 41544.792276038424, 42784.78607607067, 42978.14043719171, 43278.0, 43421.78289108661, 40002.1999560013, 41855.953601158995, 42064.57935420729, 42564.36153457763, 42916.78541607272, 41970.0, 42342.78828605961, 42939.0, 43247.56752432561, 43257.13485730331, 35300.042884640236, 36681.266374672894, 37382.62617373792, 37781.622183777814, 37802.62197377991, 28317.0, 28673.28316792097, 28911.42177156403, 29065.418691626473, 29145.0], "value": 20832.89791195077, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.5526348648231, 410.36393589935597, 411.89290784396087, 415.59687103509606, 426.9103488267464, 14008.64978375541, 17984.370547030856, 19399.224031038742, 19579.804201957995, 19581.0, 14570.562883113496, 18939.62120757584, 18975.810241897576, 19043.143058562364, 19569.902150489263, 5520.254765606642, 5665.8583535411535, 5701.059325211335, 5708.1437784332265, 5719.428057194289, 5645.887082258352, 5660.66036037837, 5688.0, 5688.857778555528, 5701.743421546041, 4020.336644453662, 4207.26372884744, 4885.853424397274, 6186.907196392058, 6196.75212991482, 6123.601965872204, 6199.535034872358, 6246.531510136712, 6272.40412160844, 6295.8740825183695, 5504.036793561111, 5538.390777014525, 5546.750396232139, 5547.861303467405, 5553.527950124244, 22380.888095559418, 22802.65796013061, 22809.42976425586, 23562.0, 23585.41036474085, 2367.9526409471714, 2447.473793134485, 2561.0780119157275, 2562.2441379792817, 2972.925676858096, 13656.795148072786, 14392.85607143936, 14444.422223111063, 14539.636509087251, 15168.0, 1818.4544636608964, 1878.201764250202, 1881.9153138108682, 1903.1340739963284, 1947.0107279199, 79186.20813791845, 79593.60203199004, 79616.0, 79742.0, 80639.19360806375, 10112.14046806015, 10242.0, 10494.632687855888, 10695.465226738788, 10755.193360497993, 14480.0, 14500.129992200502, 14669.046511976901, 14758.262086895613, 14814.333354999051, 7623.771286861403, 7833.529988200612, 7855.607219638995, 7968.282854543119, 8057.395695322874, 7939.126696063428, 8008.879866802003, 8132.105468398471, 8137.75586732399, 8176.6320515576945, 5906.2912450505355, 6103.6032657877095, 6552.213734351908, 6558.901616475757, 6662.367075127855, 1839.1745170046272, 1894.9526261843564, 1931.159945423745, 1943.465546974565, 1953.062529985615, 991.5438898106952, 1026.461107918349, 1036.5750042482546, 1086.6468397770757, 1090.9672709818717, 19385.0, 19393.127309271116, 19485.805141948684, 19724.211031558865, 20311.18755249803, 1024.3239461955075, 1042.83314669654, 1094.852194953671, 1104.2987702808775, 1112.9888701113048, 10793.514291856885, 11314.321140731417, 11430.942845285721, 11441.485133169028, 11451.942740286411, 492904.0, 508111.0, 519426.0, 521229.0, 521733.0, 80176.59911700404, 80553.0, 80673.0, 80719.59640201878, 80961.59519202367, 1075.8171110911007, 1080.1412876762993, 1089.6622047165404, 1096.2983690438125, 1096.3038470571244, 17094.632429405865, 17231.483055508354, 17615.911920440314, 17675.734863977308, 17835.286588536834, 36428.0, 36462.63537364592, 36714.265714685025, 37236.0, 37751.62248377481, 47760.0, 49874.25188622101, 51278.0, 51322.48677513177, 51448.22827657513, 8257.38069644791, 8258.463199891989, 8623.568821559022, 8642.919635045619, 8672.306215502871, 12354.38228088574, 12519.624411267676, 12631.810522842348, 12710.809337860124, 12763.680907977376, 10682.62610808618, 11435.485403156712, 11457.828132577852, 11508.654740357802, 11518.07855371585, 9259.657349684123, 10717.35695858251, 11296.548138074386, 11298.887011130113, 11518.0, 11270.154738394489, 11295.0, 11523.711907202387, 11535.653930382103, 11644.825327619923, 8183.181681831769, 8353.33173346143, 8391.118932511998, 8420.536870471939, 8613.138686131337, 11168.21822472419, 11253.324800511995, 11492.0, 11556.42217889119, 11760.411979400824], "value": 9258.91508958905, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.4264937401601, 1550.3566020101655, 1563.1012168003397, 1758.4285107340118, 1880.2291060665123, 8432.36757243207, 8799.34004949629, 8812.339074569401, 8827.24968377688, 9015.098490150982, 8446.577671116434, 8728.51993140377, 8959.193672569467, 8962.0, 8965.551722413886, 2059.7116403703494, 2278.293728944033, 2333.9299821005357, 2339.836211465198, 2377.476955069882, 2366.881655917206, 2378.0844374915696, 2378.82158838087, 2379.5478859016816, 2380.880955952204, 2309.3533810532995, 2357.0453966143655, 2357.516709074644, 2360.775726305998, 2363.231949616373, 2043.3359158273547, 2265.807406370452, 2332.8716920569364, 2332.9066837326486, 2347.3779448446176, 2294.4493321602863, 2313.051648823991, 2320.153144102407, 2322.0, 2324.5467133908896, 8917.0, 8961.327900407496, 8964.103589641049, 8974.102589741038, 8976.3267754918, 5301.204819277101, 5418.566514678816, 5423.620346575742, 5429.511343979061, 5432.728363581805, 3107.751379889626, 3192.898450034734, 3207.7754557181006, 3283.162793487669, 3363.125587347283, 1043.7181960870523, 1055.8363453664617, 1073.940933248671, 1076.1390887290136, 1087.613897066538, 12859.228446293253, 13028.869711302861, 13029.218246905217, 13030.478780848849, 13047.86952130476, 4652.860414187512, 4695.81216751333, 4782.234842425195, 4787.688800227974, 4794.880127996828, 10948.397838118979, 10952.178586606036, 10956.835647465294, 10959.452027398598, 10963.616273430393, 4980.452150263468, 5327.121025030904, 5473.124300111963, 5500.0, 5502.312210973629, 4927.827526036644, 5079.974600126976, 5091.5672167865405, 5113.1052065888325, 5122.667026643331, 4125.968507873032, 4156.877643036424, 4206.663466922639, 4259.212045771542, 4273.97863010683, 1304.3673818198167, 1326.602019394178, 1332.4936524120772, 1334.3928512526777, 1351.351351351357, 910.267234875918, 974.7563109222694, 976.8227502612912, 991.8512223166583, 992.7270000749852, 5760.481556659921, 5783.190353350535, 5798.0, 5868.9706551467825, 6034.219905127998, 438.3796927347767, 450.84220522816753, 457.13145024453803, 458.23474797088943, 459.89192539753134, 3927.430522574268, 4024.8188831502653, 4026.174634199982, 4038.414429907647, 4081.551029386765, 11116.833247501294, 11484.598039068593, 11546.307221566565, 11552.6534203974, 11565.074794016458, 10750.677479675622, 11249.268797528135, 11263.04264137541, 11311.151663625262, 11372.31766094037, 8413.45312554682, 8421.789455263544, 8426.410151289474, 8522.829543409098, 8592.05487396386, 6283.59156654816, 6339.873202536016, 6397.96801015992, 6416.230052393743, 6464.709088091141, 10277.229207809294, 10573.682789516326, 10584.682459526226, 10615.575376984834, 10707.839382409426, 10723.142148628247, 10859.674209773719, 10871.456427178768, 10886.945565272123, 10892.346459212473, 7427.2201418850245, 7856.921430785619, 8025.077116131486, 8028.558429286445, 8102.878456823035, 9001.144891235323, 9013.63945442175, 9066.365354425126, 9068.047854975133, 9085.636574536944, 7899.84200316002, 8023.0, 8043.959780201061, 8103.351731861319, 8146.429749917449, 7885.0, 7955.5226686399, 8064.677412903418, 8082.030156381036, 8209.179082091743, 6990.196127445205, 7035.718571257093, 7060.117485314336, 7085.14978202619, 7119.0, 5030.295758593873, 5138.486151384832, 5153.227015947488, 5181.326427564543, 5223.895522089613, 5147.382314122179, 5267.71027593486, 5293.258943747801, 5338.599605029716, 5364.785408583769], "value": 4646.646523675564, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.669142437577, 332.33367098966585, 332.5410932912581, 334.2296007702245, 339.74519110667, 5215.6349055566125, 5285.762140703668, 5392.784288628455, 5415.512603865652, 5453.781848726051, 5324.57731732062, 5334.813281535137, 5362.6514276572, 5375.543078838294, 5393.597664607201, 4193.161367726451, 4194.748315101104, 4197.769122698266, 4209.7474151551005, 4230.153969206156, 3908.433277174808, 3949.9210015799667, 4134.896627584304, 4186.5604111568155, 4209.705320627558, 4101.26177288088, 4108.863391036659, 4117.938230926538, 4133.21468920904, 4139.937900931488, 4101.876943691694, 4132.690048246393, 4160.5007399112, 4164.895877603084, 4190.811413486401, 3481.910853487887, 3675.852965881388, 3677.9080522986874, 3681.4845921571, 3692.298463291966, 5103.719295438749, 5125.718085505296, 5145.76844042019, 5151.8454446366295, 5171.2501687255135, 4714.030160998584, 4725.740084295362, 4732.929006064877, 4733.881652958669, 4746.691465054761, 5447.155690867922, 5569.888602227973, 5570.554355651539, 5590.552755779528, 5631.465010824006, 3970.4242884781834, 4001.339778936472, 4009.5766003069075, 4017.517897852247, 4029.194161167778, 5799.710014499258, 5811.442965095387, 5820.3888591698105, 5837.241158649348, 5891.204687367236, 3929.7052721046034, 4096.549379568245, 4107.096438783463, 4122.56713045132, 4133.689973252018, 4076.1860971867236, 4093.9590604094174, 4097.180563887235, 4104.302268614283, 4119.958800412018, 4694.859154225379, 4695.929561056587, 4710.740909249956, 4732.858014259577, 4734.384530011076, 5474.096774032319, 5578.944210557843, 5580.381689310055, 5610.6517448977365, 5664.943350566524, 1411.574309946961, 1415.7451658701327, 1418.163283662644, 1434.8493408192198, 1435.3110506956718, 892.3575025981283, 903.0608167505852, 908.7909780750465, 919.7010971434177, 928.2944961828923, 4895.877603059952, 4906.460289368167, 4906.55840974314, 4907.754612269372, 4917.680350777188, 5142.742862856842, 5157.793688252503, 5161.458046905096, 5195.948040519622, 5211.609129315317, 4721.686373676874, 4883.511648835088, 4913.213885778258, 4933.925991110136, 4959.677620954626, 2687.5565531687444, 2695.9326016849736, 2719.0, 2724.4959682458807, 2746.780257579389, 5203.895922081537, 5278.261043453919, 5315.6279060465395, 5323.041852466516, 5400.540954018874, 4833.806647734122, 5164.9741751291, 5184.222366645034, 5191.273221748958, 5223.075077956357, 5218.66078704883, 5258.027264955996, 5343.465653434625, 5388.410418926348, 5392.815909556557, 5197.740112994259, 5199.558037566698, 5200.7778172128765, 5203.661761985459, 5204.583633309402, 5187.29971453864, 5401.513863752281, 5804.187413762161, 5805.738741756547, 5812.854678633068, 6078.264780848547, 6088.878222435438, 6090.9390906090375, 6094.8171554853425, 6100.633961962297, 3886.7862267575556, 4019.517657881073, 4099.651529620016, 4104.630583247522, 4198.979005104955, 5658.915116273177, 5705.800296989585, 5751.0, 5764.250647415809, 5783.334916484489, 5602.663840169603, 5603.971980140236, 5631.352394474524, 5650.660960342392, 5657.2928383952, 5355.973220133875, 5581.748821303132, 5615.803446879503, 5632.451075954145, 5640.85897852557, 5436.592255580768, 5461.617686761889, 5534.169874518854, 5536.529395001309, 5549.750261238173, 5392.757325920422, 5600.4959553640365, 5620.494155526022, 5640.830775076754, 5707.172459993195, 5516.862078448071, 5564.693941833076, 5566.220729097849, 5579.609427340048, 5613.46672066153], "value": 4162.394895157222, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.0398741604457, 761.0486891385768, 774.5468900693094, 787.4920676163873, 820.4257020085939, 15102.395904163834, 15347.769783453257, 15617.609559761011, 15882.364705411785, 16044.197790110506, 14726.705465890676, 14822.777658335133, 14833.703325933502, 14854.554363369089, 14868.479603213862, 702.0276916470699, 828.4904783558129, 846.8729690546437, 850.2475309351238, 858.7638399440142, 859.4327743689166, 860.384824850232, 861.0399404663787, 861.9482831030158, 864.1358641358642, 884.7434244069212, 888.0630934364243, 888.1695614600369, 888.2627419242014, 889.2130464538898, 730.9817254568625, 750.5309181761372, 867.371155911965, 881.0132651430407, 882.496976723268, 647.3752828520453, 647.8056583025074, 647.9319671434479, 648.3160265919453, 648.6659370424235, 14721.04313219648, 14735.115893046344, 14749.852501475063, 14756.852431475763, 14761.852381476156, 13353.0, 14089.0, 14352.425902963869, 14358.56924292273, 14579.562613121623, 11965.581204657883, 12525.686857828536, 12768.80846787299, 12992.090553661248, 13097.148685335424, 4536.705114167568, 4540.614047805941, 4571.977140114312, 4573.382593349888, 4624.606908412756, 55045.44954550443, 56196.43803561953, 56305.71847140779, 58737.41262587405, 61826.0, 11597.6520704379, 11839.17125801203, 11886.940565297118, 11956.043516518657, 12212.32832194238, 20305.695414568792, 20321.695174572393, 20388.694169587467, 20402.795972040385, 20440.897795510926, 8563.914360856435, 8579.742607721653, 8619.784505387415, 8757.649694012296, 8903.109689031045, 4790.329353890458, 4819.566239038503, 4838.322634831126, 4850.902981940341, 4892.143874821892, 3445.0870519312402, 3600.8199590020395, 3605.6394360563736, 3616.150204701893, 3618.3848745713294, 672.7174586673609, 682.74738346811, 730.6200775596766, 732.7398773435353, 734.0494060192065, 731.4331393170229, 772.1166886093052, 793.7856778669727, 813.2152472863714, 815.0871024452532, 9440.95279523598, 9980.900190998142, 9997.400155990665, 10006.19950403966, 10110.595576176871, 617.478230894893, 645.8191706322237, 719.5790462579449, 756.5460723565817, 765.8468306338755, 4277.550857159953, 4546.818127274938, 4673.275642275453, 4700.613319070882, 4795.808167673254, 75647.0, 76957.23042769612, 77199.0, 77490.6125469369, 77501.6124919383, 54986.45013549893, 55691.16463253054, 55739.0, 65514.344856551776, 67588.3241167582, 58414.415855840896, 58436.70781646064, 58593.99999999915, 59077.40922590805, 60342.0, 9660.227181825578, 9789.140063387933, 9880.50597470138, 9917.107460328605, 10199.592016319264, 37318.0, 37956.0, 38267.42598861075, 38948.41577376285, 39057.609423906535, 45032.324515131644, 46126.30810537777, 46239.07521849612, 47556.2866556995, 49436.75281623713, 12012.879871201176, 12492.687682808002, 12652.746945060862, 12707.046971476992, 12890.35548222604, 14084.859151408764, 14400.42398304098, 14715.632109197355, 14879.553613391616, 15001.624959376102, 13368.331583420984, 13653.59039228785, 13736.244506552235, 13983.091099078934, 14175.36210870534, 13184.208947463183, 13473.124246923919, 13885.652858678613, 13998.860011399756, 14013.159210447406, 12630.557930472389, 12811.359432028175, 13100.868991309964, 13182.670433239246, 13483.0, 9911.603535858774, 9967.750806229902, 10500.267455869696, 10563.52464139131, 10705.197110216921, 10572.947135264274, 10759.731006724895, 10848.566057357617, 10849.783004340026, 10878.292910960763], "value": 7181.140201189846, "unit": "score"}], "name": "dromaeo_css", "value": 7219.811876988262, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 106.0, 110.0, 111.0, 112.0, 110.0, 110.0, 112.0, 110.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 132.0, 153.0, 216.0, 127.0, 153.0, 153.0, 125.0, 190.0, 126.0], "value": 152.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 161.0, 160.0, 161.0, 161.0, 161.0, 162.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 152.0, 75.0, 73.0, 76.0, 75.0, 130.0, 76.0], "value": 88.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 147.0, 93.0, 91.0, 90.0, 129.0, 92.0, 91.0, 128.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 94.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 54.0, 55.0, 54.0, 57.0, 107.0, 53.0, 52.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 66.0, 59.0, 59.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 77.0, 85.0, 82.0, 78.0, 80.0, 79.0, 79.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 137.0, 129.0, 118.0, 120.0, 119.0, 118.0, 117.0, 199.0, 122.0], "value": 130.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 165.0, 163.0, 164.0, 162.0, 163.0, 169.0, 163.0, 169.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 66.0, 65.0, 63.0, 63.0, 62.0, 62.0, 61.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1533.0999999999997, "alertThreshold": 2.0}]} 10:28:46 INFO - Return code: 0 10:28:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:28:46 ERROR - # TBPL SUCCESS # 10:28:46 INFO - Running post-action listener: _resource_record_post_action 10:28:46 INFO - ##### 10:28:46 INFO - ##### Finished run-tests step (success) 10:28:46 INFO - ##### 10:28:46 INFO - Running post-run listener: _resource_record_post_run 10:28:46 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 77824; Write bytes: 540119040; Read time: 32; Write time: 637428 10:28:46 INFO - TinderboxPrint: CPU usage
13.2% 10:28:46 INFO - TinderboxPrint: I/O read bytes / time
77,824 / 32 10:28:46 INFO - TinderboxPrint: I/O write bytes / time
540,119,040 / 637,428 10:28:46 INFO - TinderboxPrint: CPU idle
8,268.1 (86.8%) 10:28:46 INFO - TinderboxPrint: CPU user
1,205.7 (12.7%) 10:28:46 INFO - TinderboxPrint: Swap in / out
0 / 0 10:28:46 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 250957824; Read time: 0; Write time: 347600 10:28:46 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 77824; Write bytes: 289161216; Read time: 32; Write time: 289828 10:28:47 INFO - Running post-run listener: _upload_blobber_files 10:28:47 INFO - Blob upload gear active. 10:28:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:28:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:28:47 INFO - (blobuploader) - INFO - Open directory for files ... 10:28:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:28:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:28:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:28:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:51 INFO - (blobuploader) - INFO - Done attempting. 10:28:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:28:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:28:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:28:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:52 INFO - (blobuploader) - INFO - Done attempting. 10:28:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:28:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:28:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:28:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:53 INFO - (blobuploader) - INFO - Done attempting. 10:28:53 INFO - (blobuploader) - INFO - Iteration through files over. 10:28:53 INFO - Return code: 0 10:28:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:28:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:28:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a331305940ed0249923cf6aa772505be5090e3dacc6fd4e66ca4995ee545ff784160762e22c3850b5a7d5972cc82fe0611abf1e46de461491c16fbc642abcee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f6d78887a83c18f67ce2c13fa9d42dd7a9064197a514277845094eb38f505ce4818e74a7c6dccffaf26ce140cd06a1212ffb63bc46777d27a02ebe0432a281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00afdfc565f17b422dd154d9f90a4465be3e71f55a27c5efebc9b1f27f37931318965b27a774383f0743ea1d7b82ca1a612a333b6c9d63b15a660ef346239d8d"} 10:28:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:28:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:28:53 INFO - Contents: 10:28:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a331305940ed0249923cf6aa772505be5090e3dacc6fd4e66ca4995ee545ff784160762e22c3850b5a7d5972cc82fe0611abf1e46de461491c16fbc642abcee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f6d78887a83c18f67ce2c13fa9d42dd7a9064197a514277845094eb38f505ce4818e74a7c6dccffaf26ce140cd06a1212ffb63bc46777d27a02ebe0432a281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00afdfc565f17b422dd154d9f90a4465be3e71f55a27c5efebc9b1f27f37931318965b27a774383f0743ea1d7b82ca1a612a333b6c9d63b15a660ef346239d8d"} 10:28:53 INFO - Running post-run listener: copy_logs_to_upload_dir 10:28:53 INFO - Copying logs to upload dir... 10:28:53 INFO - mkdir: /builds/slave/test/build/upload/logs 10:28:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.029842 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-13 10:28:53.278605) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 10:28:53.282185) ========= 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=523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614 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/1a331305940ed0249923cf6aa772505be5090e3dacc6fd4e66ca4995ee545ff784160762e22c3850b5a7d5972cc82fe0611abf1e46de461491c16fbc642abcee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f6d78887a83c18f67ce2c13fa9d42dd7a9064197a514277845094eb38f505ce4818e74a7c6dccffaf26ce140cd06a1212ffb63bc46777d27a02ebe0432a281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00afdfc565f17b422dd154d9f90a4465be3e71f55a27c5efebc9b1f27f37931318965b27a774383f0743ea1d7b82ca1a612a333b6c9d63b15a660ef346239d8d"} build_url:https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/Ckf2ThuMTeaztiV0V6RKvw/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/1a331305940ed0249923cf6aa772505be5090e3dacc6fd4e66ca4995ee545ff784160762e22c3850b5a7d5972cc82fe0611abf1e46de461491c16fbc642abcee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f6d78887a83c18f67ce2c13fa9d42dd7a9064197a514277845094eb38f505ce4818e74a7c6dccffaf26ce140cd06a1212ffb63bc46777d27a02ebe0432a281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00afdfc565f17b422dd154d9f90a4465be3e71f55a27c5efebc9b1f27f37931318965b27a774383f0743ea1d7b82ca1a612a333b6c9d63b15a660ef346239d8d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 10:28:53.310126) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:28:53.310446) ========= 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=523ca41ca1e0623de333ad52000001bf-1463159296.608129-1413012614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 10:28:53.394464) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 10:28:53.394814) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 10:28:53.395097) ========= ========= Total master_lag: 0.16 =========