builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1464412112.81 results: success (0) buildid: 20160527193125 builduid: e7dce2b0ee1e4139afe8cbe093722cb5 revision: ff41a86fc3b395255982059b6e33d7be4e684d84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:32.814616) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:32.815134) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:32.815460) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:32.860091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:32.860388) ========= 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=00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 22:08:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 166M=0s 2016-05-27 22:08:33 (166 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:33.018754) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:33.019172) ========= 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=00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017770 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:33.072579) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 22:08:33.072945) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ff41a86fc3b395255982059b6e33d7be4e684d84 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ff41a86fc3b395255982059b6e33d7be4e684d84 --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=00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 22:08:33,118 truncating revision to first 12 chars 2016-05-27 22:08:33,118 Setting DEBUG logging. 2016-05-27 22:08:33,118 attempt 1/10 2016-05-27 22:08:33,118 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ff41a86fc3b3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 22:08:34,099 unpacking tar archive at: mozilla-inbound-ff41a86fc3b3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.320390 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 22:08:34.410088) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:34.410816) ========= script_repo_revision: ff41a86fc3b395255982059b6e33d7be4e684d84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:34.411428) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:34.411753) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 22:08:34.422631) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-27 22:08:34.423301) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:08:34 INFO - MultiFileLogger online at 20160527 22:08:34 in /builds/slave/test 22:08:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:08:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:08:34 INFO - {'append_to_log': False, 22:08:34 INFO - 'base_work_dir': '/builds/slave/test', 22:08:34 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:08:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:08:34 INFO - 'branch': 'Mozilla-Inbound', 22:08:34 INFO - 'buildbot_json_path': 'buildprops.json', 22:08:34 INFO - 'config_files': ('talos/linux_config.py',), 22:08:34 INFO - 'default_actions': ('clobber', 22:08:34 INFO - 'read-buildbot-config', 22:08:34 INFO - 'download-and-extract', 22:08:34 INFO - 'populate-webroot', 22:08:34 INFO - 'create-virtualenv', 22:08:34 INFO - 'install', 22:08:34 INFO - 'run-tests'), 22:08:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:08:34 INFO - 'download_minidump_stackwalk': True, 22:08:34 INFO - 'download_symbols': 'ondemand', 22:08:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:08:34 INFO - 'tooltool.py': '/tools/tooltool.py', 22:08:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:08:34 INFO - '/tools/misc-python/virtualenv.py')}, 22:08:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:08:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:08:34 INFO - 'installer_path': 'installer.exe', 22:08:34 INFO - 'log_level': 'info', 22:08:34 INFO - 'log_name': 'talos', 22:08:34 INFO - 'log_to_console': True, 22:08:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:08:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:08:34 INFO - 'opt_config_files': (), 22:08:34 INFO - 'pip_index': False, 22:08:34 INFO - 'sps_profile': False, 22:08:34 INFO - 'sps_profile_interval': 0, 22:08:34 INFO - 'suite': 'dromaeojs', 22:08:34 INFO - 'system_bits': '32', 22:08:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:08:34 INFO - 'title': 'talos-linux64-ix-016', 22:08:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:08:34 INFO - 'use_talos_json': True, 22:08:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:08:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:08:34 INFO - 'work_dir': 'build'} 22:08:34 INFO - ##### 22:08:34 INFO - ##### Running clobber step. 22:08:34 INFO - ##### 22:08:34 INFO - Running pre-action listener: _resource_record_pre_action 22:08:34 INFO - Running main action method: clobber 22:08:34 INFO - rmtree: /builds/slave/test/build 22:08:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:08:35 INFO - Running post-action listener: _resource_record_post_action 22:08:35 INFO - ##### 22:08:35 INFO - ##### Finished clobber step (success) 22:08:35 INFO - ##### 22:08:35 INFO - ##### 22:08:35 INFO - ##### Running read-buildbot-config step. 22:08:35 INFO - ##### 22:08:35 INFO - Running pre-action listener: _resource_record_pre_action 22:08:35 INFO - Running main action method: read_buildbot_config 22:08:35 INFO - Using buildbot properties: 22:08:35 INFO - { 22:08:35 INFO - "project": "", 22:08:35 INFO - "product": "firefox", 22:08:35 INFO - "script_repo_revision": "production", 22:08:35 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:08:35 INFO - "repository": "", 22:08:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:08:35 INFO - "buildid": "20160527193125", 22:08:35 INFO - "pgo_build": "True", 22:08:35 INFO - "basedir": "/builds/slave/test", 22:08:35 INFO - "buildnumber": 1075, 22:08:35 INFO - "slavename": "talos-linux64-ix-016", 22:08:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:08:35 INFO - "platform": "ubuntu64_hw", 22:08:35 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:08:35 INFO - "branch": "mozilla-inbound", 22:08:35 INFO - "repo_path": "integration/mozilla-inbound", 22:08:35 INFO - "revision": "ff41a86fc3b395255982059b6e33d7be4e684d84", 22:08:35 INFO - "stage_platform": "linux64-pgo", 22:08:35 INFO - "builduid": "e7dce2b0ee1e4139afe8cbe093722cb5", 22:08:35 INFO - "slavebuilddir": "test" 22:08:35 INFO - } 22:08:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:08:35 INFO - Running post-action listener: _resource_record_post_action 22:08:35 INFO - ##### 22:08:35 INFO - ##### Finished read-buildbot-config step (success) 22:08:35 INFO - ##### 22:08:35 INFO - ##### 22:08:35 INFO - ##### Running download-and-extract step. 22:08:35 INFO - ##### 22:08:35 INFO - Running pre-action listener: _resource_record_pre_action 22:08:35 INFO - Running main action method: download_and_extract 22:08:35 INFO - mkdir: /builds/slave/test/build/tests 22: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')]} 22:08:35 INFO - https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:08:56 INFO - Downloaded 1361 bytes. 22:08:56 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:08:56 INFO - Using the following test package requirements: 22:08:56 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:08:56 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:08:56 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:08:56 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'jsshell-linux-x86_64.zip'], 22:08:56 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:08:56 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:08:56 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:08:56 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:08:56 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:08:56 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:56 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:08:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:56 INFO - https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:08:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:08:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:08:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:08:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:08:58 INFO - Downloaded 24410280 bytes. 22:08:58 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'] 22:08:58 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 22:08:59 INFO - Return code: 0 22:08:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:59 INFO - https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:08:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:08:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:08:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:09:02 INFO - Downloaded 24410280 bytes. 22:09:02 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'] 22:09:02 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 22:09:03 INFO - Return code: 0 22:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:03 INFO - https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:09:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:09:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:09:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:09:05 INFO - Downloaded 10834055 bytes. 22:09:05 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'] 22:09:05 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 22:09:05 INFO - Return code: 0 22:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:05 INFO - https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:09:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:09:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:09:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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 22:09:07 INFO - Downloaded 58903740 bytes. 22:09:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:09:07 INFO - mkdir: /builds/slave/test/properties 22:09:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:07 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:07 INFO - Contents: 22:09:07 INFO - build_url:https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:09:08 INFO - Running post-action listener: _resource_record_post_action 22:09:08 INFO - Running post-action listener: set_extra_try_arguments 22:09:08 INFO - ##### 22:09:08 INFO - ##### Finished download-and-extract step (success) 22:09:08 INFO - ##### 22:09:08 INFO - ##### 22:09:08 INFO - ##### Running populate-webroot step. 22:09:08 INFO - ##### 22:09:08 INFO - Running pre-action listener: _resource_record_pre_action 22:09:08 INFO - Running main action method: populate_webroot 22:09:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:08 INFO - '--tppagecycles', 22:09:08 INFO - '7'], 22:09:08 INFO - 'tests': ['tsvgm']}}, 22:09:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:09:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:09:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['damp', 'tps']}, 22:09:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'tests': ['damp', 'tps']}, 22:09:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['dromaeo_dom']}, 22:09:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['a11yr', 22:09:08 INFO - 'ts_paint', 22:09:08 INFO - 'tpaint', 22:09:08 INFO - 'sessionrestore', 22:09:08 INFO - 'sessionrestore_no_auto_restore', 22:09:08 INFO - 'tabpaint']}, 22:09:08 INFO - 'other-e10s': {'tests': ['a11yr', 22:09:08 INFO - 'ts_paint', 22:09:08 INFO - 'tpaint', 22:09:08 INFO - 'sessionrestore', 22:09:08 INFO - 'sessionrestore_no_auto_restore', 22:09:08 INFO - 'tabpaint']}, 22:09:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:09:08 INFO - 'ts_paint', 22:09:08 INFO - 'tpaint', 22:09:08 INFO - 'sessionrestore', 22:09:08 INFO - 'sessionrestore_no_auto_restore', 22:09:08 INFO - 'tabpaint']}, 22:09:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:09:08 INFO - 'ts_paint', 22:09:08 INFO - 'tpaint', 22:09:08 INFO - 'sessionrestore', 22:09:08 INFO - 'sessionrestore_no_auto_restore', 22:09:08 INFO - 'tabpaint']}, 22:09:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['a11yr', 22:09:08 INFO - 'ts_paint', 22:09:08 INFO - 'tpaint', 22:09:08 INFO - 'sessionrestore', 22:09:08 INFO - 'sessionrestore_no_auto_restore', 22:09:08 INFO - 'tabpaint']}, 22:09:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['a11yr', 22:09:08 INFO - 'ts_paint', 22:09:08 INFO - 'tpaint', 22:09:08 INFO - 'sessionrestore', 22:09:08 INFO - 'sessionrestore_no_auto_restore', 22:09:08 INFO - 'tabpaint']}, 22:09:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['tsvgx', 22:09:08 INFO - 'tsvgr_opacity', 22:09:08 INFO - 'tart', 22:09:08 INFO - 'tscrollx', 22:09:08 INFO - 'cart']}, 22:09:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:09:08 INFO - 'tsvgr_opacity', 22:09:08 INFO - 'tart', 22:09:08 INFO - 'tscrollx', 22:09:08 INFO - 'cart']}, 22:09:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'talos_options': ['--disable-e10s'], 22:09:08 INFO - 'tests': ['tp5o']}, 22:09:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'tests': ['tp5o']}, 22:09:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'talos_options': ['--disable-e10s', 22:09:08 INFO - '--xperf_path', 22:09:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:08 INFO - 'tests': ['tp5n']}, 22:09:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:08 INFO - 'talos_options': ['--xperf_path', 22:09:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:08 INFO - 'tests': ['tp5n']}}, 22:09:08 INFO - 'talos.zip': {'path': '', 22:09:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:08 INFO - Running post-action listener: _resource_record_post_action 22:09:08 INFO - ##### 22:09:08 INFO - ##### Finished populate-webroot step (success) 22:09:08 INFO - ##### 22:09:08 INFO - ##### 22:09:08 INFO - ##### Running create-virtualenv step. 22:09:08 INFO - ##### 22:09:08 INFO - Running pre-action listener: _resource_record_pre_action 22:09:08 INFO - Running main action method: create_virtualenv 22:09:08 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:08 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 22:09:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:08 INFO - Using real prefix '/usr' 22:09:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:08 INFO - Installing distribute.............................................................................................................................................................................................done. 22:09:10 INFO - Installing pip.................done. 22:09:10 INFO - Return code: 0 22:09:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22: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')]} 22:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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')]} 22:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:09:10 INFO - Using env: {'DISPLAY': ':0', 22:09:10 INFO - 'HOME': '/home/cltbld', 22:09:10 INFO - 'LANG': 'en_US.UTF-8', 22:09:10 INFO - 'LANGUAGE': 'en_US:en', 22:09:10 INFO - 'LOGNAME': 'cltbld', 22:09:10 INFO - 'MAIL': '/var/mail/cltbld', 22:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:10 INFO - 'MOZ_NO_REMOTE': '1', 22:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:10 INFO - 'NO_EM_RESTART': '1', 22:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:10 INFO - 'PWD': '/builds/slave/test', 22:09:10 INFO - 'SHELL': '/bin/bash', 22:09:10 INFO - 'SHLVL': '1', 22:09:10 INFO - 'TERM': 'linux', 22:09:10 INFO - 'TMOUT': '86400', 22:09:10 INFO - 'USER': 'cltbld', 22:09:10 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:10 INFO - '_': '/tools/buildbot/bin/python'} 22:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:11 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 22:09:11 INFO - Cleaning up... 22:09:11 INFO - Return code: 0 22:09:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 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 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:11 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 22:09:11 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 22:09:11 INFO - Using env: {'DISPLAY': ':0', 22:09:11 INFO - 'HOME': '/home/cltbld', 22:09:11 INFO - 'LANG': 'en_US.UTF-8', 22:09:11 INFO - 'LANGUAGE': 'en_US:en', 22:09:11 INFO - 'LOGNAME': 'cltbld', 22:09:11 INFO - 'MAIL': '/var/mail/cltbld', 22:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:11 INFO - 'MOZ_NO_REMOTE': '1', 22:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:11 INFO - 'NO_EM_RESTART': '1', 22:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:11 INFO - 'PWD': '/builds/slave/test', 22:09:11 INFO - 'SHELL': '/bin/bash', 22:09:11 INFO - 'SHLVL': '1', 22:09:11 INFO - 'TERM': 'linux', 22:09:11 INFO - 'TMOUT': '86400', 22:09:11 INFO - 'USER': 'cltbld', 22:09:11 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:11 INFO - '_': '/tools/buildbot/bin/python'} 22:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:11 INFO - Downloading/unpacking psutil>=3.1.1 22:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:13 INFO - Installing collected packages: psutil 22:09:13 INFO - Running setup.py install for psutil 22:09:13 INFO - building 'psutil._psutil_linux' extension 22:09:13 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 22:09:13 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 22:09:13 INFO - building 'psutil._psutil_posix' extension 22:09:13 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 22:09:13 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 22:09:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:14 INFO - Successfully installed psutil 22:09:14 INFO - Cleaning up... 22:09:14 INFO - Return code: 0 22:09:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:09:14 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')]} 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:14 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')]} 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:09:14 INFO - Using env: {'DISPLAY': ':0', 22:09:14 INFO - 'HOME': '/home/cltbld', 22:09:14 INFO - 'LANG': 'en_US.UTF-8', 22:09:14 INFO - 'LANGUAGE': 'en_US:en', 22:09:14 INFO - 'LOGNAME': 'cltbld', 22:09:14 INFO - 'MAIL': '/var/mail/cltbld', 22:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:14 INFO - 'MOZ_NO_REMOTE': '1', 22:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:14 INFO - 'NO_EM_RESTART': '1', 22:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:14 INFO - 'PWD': '/builds/slave/test', 22:09:14 INFO - 'SHELL': '/bin/bash', 22:09:14 INFO - 'SHLVL': '1', 22:09:14 INFO - 'TERM': 'linux', 22:09:14 INFO - 'TMOUT': '86400', 22:09:14 INFO - 'USER': 'cltbld', 22:09:14 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:14 INFO - '_': '/tools/buildbot/bin/python'} 22:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:09:16 INFO - Installing collected packages: mozsystemmonitor 22:09:16 INFO - Running setup.py install for mozsystemmonitor 22:09:16 INFO - Successfully installed mozsystemmonitor 22:09:16 INFO - Cleaning up... 22:09:16 INFO - Return code: 0 22:09:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:16 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 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:16 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 22:09:16 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 22:09:16 INFO - Using env: {'DISPLAY': ':0', 22:09:16 INFO - 'HOME': '/home/cltbld', 22:09:16 INFO - 'LANG': 'en_US.UTF-8', 22:09:16 INFO - 'LANGUAGE': 'en_US:en', 22:09:16 INFO - 'LOGNAME': 'cltbld', 22:09:16 INFO - 'MAIL': '/var/mail/cltbld', 22:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:16 INFO - 'MOZ_NO_REMOTE': '1', 22:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:16 INFO - 'NO_EM_RESTART': '1', 22:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:16 INFO - 'PWD': '/builds/slave/test', 22:09:16 INFO - 'SHELL': '/bin/bash', 22:09:16 INFO - 'SHLVL': '1', 22:09:16 INFO - 'TERM': 'linux', 22:09:16 INFO - 'TMOUT': '86400', 22:09:16 INFO - 'USER': 'cltbld', 22:09:16 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:16 INFO - '_': '/tools/buildbot/bin/python'} 22:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:16 INFO - Downloading/unpacking blobuploader==1.2.4 22:09:16 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 22:09:16 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 22:09:16 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 22:09:16 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 22:09:16 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 22:09:16 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 22:09:18 INFO - Downloading blobuploader-1.2.4.tar.gz 22:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:09:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:09:18 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 22:09:18 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 22:09:18 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 22:09:18 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 22:09:18 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 22:09:18 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 22:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09:19 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 22:09:19 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 22:09:19 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 22:09:19 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 22:09:19 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 22:09:19 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 22:09:19 INFO - Downloading docopt-0.6.1.tar.gz 22:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:19 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:19 INFO - Running setup.py install for blobuploader 22:09:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:19 INFO - Running setup.py install for requests 22:09:20 INFO - Running setup.py install for docopt 22:09:20 INFO - Successfully installed blobuploader requests docopt 22:09:20 INFO - Cleaning up... 22:09:20 INFO - Return code: 0 22:09:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:20 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 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:20 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 22:09:20 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 22:09:20 INFO - Using env: {'DISPLAY': ':0', 22:09:20 INFO - 'HOME': '/home/cltbld', 22:09:20 INFO - 'LANG': 'en_US.UTF-8', 22:09:20 INFO - 'LANGUAGE': 'en_US:en', 22:09:20 INFO - 'LOGNAME': 'cltbld', 22:09:20 INFO - 'MAIL': '/var/mail/cltbld', 22:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:20 INFO - 'MOZ_NO_REMOTE': '1', 22:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:20 INFO - 'NO_EM_RESTART': '1', 22:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:20 INFO - 'PWD': '/builds/slave/test', 22:09:20 INFO - 'SHELL': '/bin/bash', 22:09:20 INFO - 'SHLVL': '1', 22:09:20 INFO - 'TERM': 'linux', 22:09:20 INFO - 'TMOUT': '86400', 22:09:20 INFO - 'USER': 'cltbld', 22:09:20 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:20 INFO - '_': '/tools/buildbot/bin/python'} 22:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:20 INFO - Running setup.py (path:/tmp/pip-vnQJo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:20 INFO - Running setup.py (path:/tmp/pip-g_X43f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:20 INFO - Running setup.py (path:/tmp/pip-UWuwMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:20 INFO - Running setup.py (path:/tmp/pip-2noFIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:20 INFO - Running setup.py (path:/tmp/pip-dvfETx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:20 INFO - Running setup.py (path:/tmp/pip-nDJc6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:20 INFO - Running setup.py (path:/tmp/pip-J1yQO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:20 INFO - Running setup.py (path:/tmp/pip-Z1ISwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:20 INFO - Running setup.py (path:/tmp/pip-skCD8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:21 INFO - Running setup.py (path:/tmp/pip-BxWk1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:21 INFO - Running setup.py (path:/tmp/pip-RPaoAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:21 INFO - Running setup.py (path:/tmp/pip-S9hkQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:21 INFO - Running setup.py (path:/tmp/pip-fi5BNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:21 INFO - Running setup.py (path:/tmp/pip-sD2ZCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:21 INFO - Running setup.py (path:/tmp/pip-URM7WW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:21 INFO - Running setup.py (path:/tmp/pip-ql6aPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:21 INFO - Running setup.py (path:/tmp/pip-Pkmna4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:09:21 INFO - Running setup.py install for manifestparser 22:09:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:09:21 INFO - Running setup.py install for mozcrash 22:09:21 INFO - Running setup.py install for mozdebug 22:09:21 INFO - Running setup.py install for mozdevice 22:09:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Running setup.py install for mozfile 22:09:22 INFO - Running setup.py install for mozhttpd 22:09:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Running setup.py install for mozinfo 22:09:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Running setup.py install for mozInstall 22:09:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Running setup.py install for mozleak 22:09:22 INFO - Running setup.py install for mozlog 22:09:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Running setup.py install for moznetwork 22:09:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Running setup.py install for mozprocess 22:09:22 INFO - Running setup.py install for mozprofile 22:09:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:09:22 INFO - Running setup.py install for mozrunner 22:09:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:09:23 INFO - Running setup.py install for mozscreenshot 22:09:23 INFO - Running setup.py install for moztest 22:09:23 INFO - Running setup.py install for mozversion 22:09:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:09:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:09:23 INFO - Cleaning up... 22:09:23 INFO - Return code: 0 22:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:23 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 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:23 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 22:09:23 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 22:09:23 INFO - Using env: {'DISPLAY': ':0', 22:09:23 INFO - 'HOME': '/home/cltbld', 22:09:23 INFO - 'LANG': 'en_US.UTF-8', 22:09:23 INFO - 'LANGUAGE': 'en_US:en', 22:09:23 INFO - 'LOGNAME': 'cltbld', 22:09:23 INFO - 'MAIL': '/var/mail/cltbld', 22:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:23 INFO - 'MOZ_NO_REMOTE': '1', 22:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:23 INFO - 'NO_EM_RESTART': '1', 22:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:23 INFO - 'PWD': '/builds/slave/test', 22:09:23 INFO - 'SHELL': '/bin/bash', 22:09:23 INFO - 'SHLVL': '1', 22:09:23 INFO - 'TERM': 'linux', 22:09:23 INFO - 'TMOUT': '86400', 22:09:23 INFO - 'USER': 'cltbld', 22:09:23 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:23 INFO - '_': '/tools/buildbot/bin/python'} 22:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:23 INFO - Running setup.py (path:/tmp/pip-7ADd12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:23 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)) 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:23 INFO - Running setup.py (path:/tmp/pip-fP9pJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:23 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)) 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:23 INFO - Running setup.py (path:/tmp/pip-Bm12Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:23 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)) 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:23 INFO - Running setup.py (path:/tmp/pip-5APa2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:23 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)) 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:23 INFO - Running setup.py (path:/tmp/pip-MJFpWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:23 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)) 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:23 INFO - Running setup.py (path:/tmp/pip-NVBih1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:24 INFO - Running setup.py (path:/tmp/pip-R8JKZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:24 INFO - Running setup.py (path:/tmp/pip-B37g8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:24 INFO - Running setup.py (path:/tmp/pip-pOGUQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:24 INFO - Running setup.py (path:/tmp/pip-05L2j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:24 INFO - Running setup.py (path:/tmp/pip-YORvH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:24 INFO - Running setup.py (path:/tmp/pip-J47bjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:24 INFO - Running setup.py (path:/tmp/pip-nr1aqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:24 INFO - Running setup.py (path:/tmp/pip-GHYptv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:24 INFO - Running setup.py (path:/tmp/pip-xGn68L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:24 INFO - Running setup.py (path:/tmp/pip-IYRpVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:24 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)) 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:24 INFO - Running setup.py (path:/tmp/pip-TOOuND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:24 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)) 22:09:24 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)) 22:09:24 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)) 22:09:24 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)) 22:09:24 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)) 22:09:24 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)) 22:09:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:09:24 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 22:09:24 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 22:09:24 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 22:09:24 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 22:09:24 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 22:09:24 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 22:09:26 INFO - Downloading blessings-1.6.tar.gz 22:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:09:26 INFO - Installing collected packages: blessings 22:09:26 INFO - Running setup.py install for blessings 22:09:26 INFO - Successfully installed blessings 22:09:26 INFO - Cleaning up... 22:09:26 INFO - Return code: 0 22:09:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:09:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:27 INFO - Reading from file tmpfile_stdout 22:09:27 INFO - Current package versions: 22:09:27 INFO - argparse == 1.2.1 22:09:27 INFO - blessings == 1.6 22:09:27 INFO - blobuploader == 1.2.4 22:09:27 INFO - docopt == 0.6.1 22:09:27 INFO - manifestparser == 1.1 22:09:27 INFO - mozInstall == 1.12 22:09:27 INFO - mozcrash == 0.17 22:09:27 INFO - mozdebug == 0.1 22:09:27 INFO - mozdevice == 0.48 22:09:27 INFO - mozfile == 1.2 22:09:27 INFO - mozhttpd == 0.7 22:09:27 INFO - mozinfo == 0.9 22:09:27 INFO - mozleak == 0.1 22:09:27 INFO - mozlog == 3.2 22:09:27 INFO - moznetwork == 0.27 22:09:27 INFO - mozprocess == 0.22 22:09:27 INFO - mozprofile == 0.28 22:09:27 INFO - mozrunner == 6.11 22:09:27 INFO - mozscreenshot == 0.1 22:09:27 INFO - mozsystemmonitor == 0.3 22:09:27 INFO - moztest == 0.7 22:09:27 INFO - mozversion == 1.4 22:09:27 INFO - psutil == 3.1.1 22:09:27 INFO - requests == 1.2.3 22:09:27 INFO - wsgiref == 0.1.2 22:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:27 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')]} 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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')]} 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:27 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 22:09:27 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 22:09:27 INFO - Using env: {'DISPLAY': ':0', 22:09:27 INFO - 'HOME': '/home/cltbld', 22:09:27 INFO - 'LANG': 'en_US.UTF-8', 22:09:27 INFO - 'LANGUAGE': 'en_US:en', 22:09:27 INFO - 'LOGNAME': 'cltbld', 22:09:27 INFO - 'MAIL': '/var/mail/cltbld', 22:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:27 INFO - 'MOZ_NO_REMOTE': '1', 22:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:27 INFO - 'NO_EM_RESTART': '1', 22:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:27 INFO - 'PWD': '/builds/slave/test', 22:09:27 INFO - 'SHELL': '/bin/bash', 22:09:27 INFO - 'SHLVL': '1', 22:09:27 INFO - 'TERM': 'linux', 22:09:27 INFO - 'TMOUT': '86400', 22:09:27 INFO - 'USER': 'cltbld', 22:09:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:27 INFO - '_': '/tools/buildbot/bin/python'} 22:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 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)) 22:09:27 INFO - Cleaning up... 22:09:27 INFO - Return code: 0 22:09:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:09:27 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')]} 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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')]} 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09:27 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 22:09:27 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 22:09:27 INFO - Using env: {'DISPLAY': ':0', 22:09:27 INFO - 'HOME': '/home/cltbld', 22:09:27 INFO - 'LANG': 'en_US.UTF-8', 22:09:27 INFO - 'LANGUAGE': 'en_US:en', 22:09:27 INFO - 'LOGNAME': 'cltbld', 22:09:27 INFO - 'MAIL': '/var/mail/cltbld', 22:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:27 INFO - 'MOZ_NO_REMOTE': '1', 22:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:27 INFO - 'NO_EM_RESTART': '1', 22:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:27 INFO - 'PWD': '/builds/slave/test', 22:09:27 INFO - 'SHELL': '/bin/bash', 22:09:27 INFO - 'SHLVL': '1', 22:09:27 INFO - 'TERM': 'linux', 22:09:27 INFO - 'TMOUT': '86400', 22:09:27 INFO - 'USER': 'cltbld', 22:09:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:27 INFO - '_': '/tools/buildbot/bin/python'} 22:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:27 INFO - Downloading/unpacking jsonschema 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:09:29 INFO - Downloading/unpacking functools32 (from jsonschema) 22:09:29 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 22:09:29 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 22:09:29 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 22:09:29 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 22:09:29 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 22:09:29 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 22:09:29 INFO - Downloading functools32-3.2.3-2.tar.gz 22:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:09:29 INFO - warning: no files found matching '*.txt' 22:09:29 INFO - no previously-included directories found matching 'build' 22:09:29 INFO - no previously-included directories found matching 'dist' 22:09:29 INFO - no previously-included directories found matching '.git*' 22:09:29 INFO - Installing collected packages: jsonschema, functools32 22:09:30 INFO - Running setup.py install for functools32 22:09:30 INFO - warning: no files found matching '*.txt' 22:09:30 INFO - no previously-included directories found matching 'build' 22:09:30 INFO - no previously-included directories found matching 'dist' 22:09:30 INFO - no previously-included directories found matching '.git*' 22:09:30 INFO - Successfully installed jsonschema functools32 22:09:30 INFO - Cleaning up... 22:09:30 INFO - Return code: 0 22:09:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4c48e6df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2372770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, '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': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', '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 22:09: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', 'flake8'] in /builds/slave/test/build 22:09: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 flake8 22:09:30 INFO - Using env: {'DISPLAY': ':0', 22:09:30 INFO - 'HOME': '/home/cltbld', 22:09:30 INFO - 'LANG': 'en_US.UTF-8', 22:09:30 INFO - 'LANGUAGE': 'en_US:en', 22:09:30 INFO - 'LOGNAME': 'cltbld', 22:09:30 INFO - 'MAIL': '/var/mail/cltbld', 22:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:30 INFO - 'MOZ_NO_REMOTE': '1', 22:09:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:30 INFO - 'NO_EM_RESTART': '1', 22:09:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:30 INFO - 'PWD': '/builds/slave/test', 22:09:30 INFO - 'SHELL': '/bin/bash', 22:09:30 INFO - 'SHLVL': '1', 22:09:30 INFO - 'TERM': 'linux', 22:09:30 INFO - 'TMOUT': '86400', 22:09:30 INFO - 'USER': 'cltbld', 22:09:30 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:30 INFO - '_': '/tools/buildbot/bin/python'} 22:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:30 INFO - Downloading/unpacking flake8 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:09:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:09:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:09:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:09:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:09:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:09:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:09:32 INFO - no previously-included directories found matching 'docs/_build' 22:09:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:09:33 INFO - Running setup.py install for pep8 22:09:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:09:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:09:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:09:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:09:33 INFO - no previously-included directories found matching 'docs/_build' 22:09:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:09:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:09:33 INFO - Cleaning up... 22:09:33 INFO - Return code: 0 22:09:33 INFO - Running post-action listener: _resource_record_post_action 22:09:33 INFO - Running post-action listener: _start_resource_monitoring 22:09:33 INFO - Starting resource monitoring. 22:09:33 INFO - ##### 22:09:33 INFO - ##### Finished create-virtualenv step (success) 22:09:33 INFO - ##### 22:09:33 INFO - ##### 22:09:33 INFO - ##### Running install step. 22:09:33 INFO - ##### 22:09:33 INFO - Running pre-action listener: _resource_record_pre_action 22:09:33 INFO - Running main action method: install 22:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:33 INFO - Reading from file tmpfile_stdout 22:09:33 INFO - Detecting whether we're running mozinstall >=1.0... 22:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:09:33 INFO - Reading from file tmpfile_stdout 22:09:33 INFO - Output received: 22:09:33 INFO - Usage: mozinstall [options] installer 22:09:33 INFO - Options: 22:09:33 INFO - -h, --help show this help message and exit 22:09:33 INFO - -d DEST, --destination=DEST 22:09:33 INFO - Directory to install application into. [default: 22:09:33 INFO - "/builds/slave/test"] 22:09:33 INFO - --app=APP Application being installed. [default: firefox] 22:09:33 INFO - mkdir: /builds/slave/test/build/application 22:09:33 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'] 22:09:33 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 22:09:50 INFO - Reading from file tmpfile_stdout 22:09:50 INFO - Output received: 22:09:50 INFO - /builds/slave/test/build/application/firefox/firefox 22:09:50 INFO - Running post-action listener: _resource_record_post_action 22:09:50 INFO - ##### 22:09:50 INFO - ##### Finished install step (success) 22:09:50 INFO - ##### 22:09:50 INFO - ##### 22:09:50 INFO - ##### Running run-tests step. 22:09:50 INFO - ##### 22:09:50 INFO - Running pre-action listener: _resource_record_pre_action 22:09:50 INFO - Running main action method: run_tests 22:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:50 INFO - Python 2.7.3 22:09:50 INFO - Return code: 0 22:09:50 INFO - grabbing minidump binary from tooltool 22:09: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')]} 22:09:50 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 0x24019c0>, '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 0x24028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2402d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:50 INFO - Return code: 0 22:09:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:09:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:09:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:09:51 INFO - Return code: 0 22:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 22:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 22:09:51 INFO - Using env: {'DISPLAY': ':0', 22:09:51 INFO - 'HOME': '/home/cltbld', 22:09:51 INFO - 'LANG': 'en_US.UTF-8', 22:09:51 INFO - 'LANGUAGE': 'en_US:en', 22:09:51 INFO - 'LOGNAME': 'cltbld', 22:09:51 INFO - 'MAIL': '/var/mail/cltbld', 22:09:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:51 INFO - 'MOZ_NO_REMOTE': '1', 22:09:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:51 INFO - 'NO_EM_RESTART': '1', 22:09:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:51 INFO - 'PWD': '/builds/slave/test', 22:09:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:51 INFO - 'SHELL': '/bin/bash', 22:09:51 INFO - 'SHLVL': '1', 22:09:51 INFO - 'TERM': 'linux', 22:09:51 INFO - 'TMOUT': '86400', 22:09:51 INFO - 'USER': 'cltbld', 22:09:51 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495', 22:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:51 INFO - '_': '/tools/buildbot/bin/python'} 22:09:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 22:09:51 INFO - mozversion application_buildid: 20160527193125 22:09:51 INFO - mozversion application_changeset: ff41a86fc3b395255982059b6e33d7be4e684d84 22:09:51 INFO - mozversion application_display_name: Nightly 22:09:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:51 INFO - mozversion application_name: Firefox 22:09:51 INFO - mozversion application_remotingname: firefox 22:09:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:09:51 INFO - mozversion application_vendor: Mozilla 22:09:51 INFO - mozversion application_version: 49.0a1 22:09:51 INFO - mozversion platform_buildid: 20160527193125 22:09:51 INFO - mozversion platform_changeset: ff41a86fc3b395255982059b6e33d7be4e684d84 22:09:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:09:51 INFO - mozversion platform_version: 49.0a1 22:09:51 INFO - using testdate: 1464412191 22:09:51 INFO - actual date: 1464412191 22:09:51 INFO - starting webserver on 'localhost:60401' 22:09:51 INFO - SUITE-START | Running 2 tests 22:09:51 INFO - TEST-START | dromaeo_css 22:09:51 INFO - operating with platform_type : linux_ 22:09:51 INFO - Initialising browser for dromaeo_css test... 22:09:51 INFO - TEST-INFO | started process 4511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9XKXWb/profile http://localhost:60401/getInfo.html) 22:09:55 INFO - PROCESS | 4511 | __metrics Screen width/height:1600/1200 22:09:55 INFO - PROCESS | 4511 | colorDepth:24 22:09:55 INFO - PROCESS | 4511 | Browser inner width/height: 1024/697 22:09:55 INFO - PROCESS | 4511 | __metrics 22:09:56 ERROR - PROCESS | 4511 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:09:57 INFO - TEST-INFO | 4511: exit 0 22:09:57 INFO - Browser initialized. 22:09:57 INFO - Running cycle 1/1 for dromaeo_css test... 22:09:57 INFO - TEST-INFO | started process 4591 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9XKXWb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:10:09 INFO - PROCESS | 4591 | RSS: Main: 155709440 22:10:09 INFO - PROCESS | 4591 | 22:11:00 INFO - PROCESS | 4591 | 1464412260066 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] 22:13:07 INFO - PROCESS | 4591 | Cycle 1(1): loaded http://localhost:60401/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60401/tests/dromaeo/cssquery-ext.html) 22:13:08 INFO - PROCESS | 4591 | RSS: Main: 258846720 22:13:08 INFO - PROCESS | 4591 | 22:16:11 INFO - PROCESS | 4591 | Cycle 1(1): loaded http://localhost:60401/tests/dromaeo/cssquery-ext.html (next: http://localhost:60401/tests/dromaeo/cssquery-jquery.html) 22:16:11 INFO - PROCESS | 4591 | RSS: Main: 247382016 22:16:11 INFO - PROCESS | 4591 | 22:19:13 INFO - PROCESS | 4591 | Cycle 1(1): loaded http://localhost:60401/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60401/tests/dromaeo/cssquery-mootools.html) 22:19:14 INFO - PROCESS | 4591 | RSS: Main: 250548224 22:19:14 INFO - PROCESS | 4591 | 22:22:16 INFO - PROCESS | 4591 | Cycle 1(1): loaded http://localhost:60401/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60401/tests/dromaeo/cssquery-prototype.html) 22:22:17 INFO - PROCESS | 4591 | RSS: Main: 242077696 22:22:17 INFO - PROCESS | 4591 | 22:25:19 INFO - PROCESS | 4591 | Cycle 1(1): loaded http://localhost:60401/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60401/tests/dromaeo/cssquery-yui.html) 22:25:20 INFO - PROCESS | 4591 | RSS: Main: 229257216 22:25:20 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | Cycle 1(1): loaded http://localhost:60401/tests/dromaeo/cssquery-yui.html (next: http://localhost:60401/tests/dromaeo/cssquery-dojo.html) 22:28:23 INFO - PROCESS | 4591 | RSS: Main: 289107968 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | __start_tp_report 22:28:23 INFO - PROCESS | 4591 | _x_x_mozilla_page_load 22:28:23 INFO - PROCESS | 4591 | _x_x_mozilla_page_load_details 22:28:23 INFO - PROCESS | 4591 | |i|pagename|runs| 22:28:23 INFO - PROCESS | 4591 | |0;dojo.html;3120.0483852425014;3129.499280115182;3154.842257887106;3163.3515129398475;3198.0246024962375;5273.472652734725;5312.73436328183;5391.110466772982;5479.506844384005;5524.66851988881;5382.058139825534;5401.4868587484225;5426.375966763823;5444.673319600826;5475.534579560742;4850.781714822824;4911.287863259825;4928.260760885878;4941.135301322254;5161.793528258865;5057.544820966113;5079.517445842639;5113.539781419671;5132.794688212467;5133.460986596409;4830.058138662962;4858.732769697665;4879.8780030499165;4911.975440122812;4926.729029903354;4915.188993816016;4944.60443164546;5016.04695107932;5016.147254966664;5061.44324124346;4813.735244561548;4960.503949605047;4981.601471882241;4988.6757360771435;4989.750512474362;5283.762230699628;5403.648762830403;5409.513143817036;5457.481539253765;5483.287172667568;5828.795992140297;5876.794312199096;5897.557683173779;5905.586608937376;5916.171735956969;5603.299587551556;5715.371309155989;5915.08316210988;5943.197668314765;5950.434708702666;5428.077226871442;5543.944560554423;5546.389897111315;5550.139728342114;5552.750126244328;6009.06859436788;6057.121717350959;6067.9684453642985;6073.240844894388;6073.635581865102;5516.420775818561;5637.267155269788;5677.971610142005;5765.711714414262;5818.301803783573;5831.620944638585;5888.617239879394;5892.823215303548;5895.380984996599;5898.439648233411;5782.884342313214;5818.8254352369495;5820.854478638068;5828.446297601721;5832.737526811304;5617.775288988477;5717.256756621696;5776.220210271559;5790.855228619318;5838.854028649318;4417.447819022622;4417.668674849399;4418.248897687401;4420.9336859947125;4447.310666846644;3927.7446965947693;3943.0339566806315;3954.149857780591;3981.5620281768984;4008.318585840414;5271.868203294872;5364.758585863646;5472.2338872557875;5545.750441230073;5548.528375088082;5931.673757943355;5958.851028724316;5965.910511342333;5969.731362088716;6010.549208809357;5577.609567330329;5584.91622625661;5585.162225666101;5587.1339942309005;5588.105903055492;4733.905321893543;4848.418189817245;4893.339399181136;4894.828680996148;4915.8033678652455;6062.939370606326;6067.484263837535;6067.54493412987;6069.787557435557;6071.787487437919;5704;5886.352501224862;5908.586398952032;5909.468147866713;5910.23166988306;5975.492083172979;6039.63762174271;6048.576599637983;6055.091736239599;6080.847978800565;5736.799212027559;5782.566307527037;5790.739416726173;5792.82621521355;5798.420157984337;5621.690807005654;5737.627054241461;5860.970695146497;5873.647581145145;5877.147813567094;5636.830895073154;5692.117721753135;5701.116326969326;5706.201131841629;5748.137779333133;5643.71781410936;5683.090705487268;5683.943160568507;5692.0608099663095;5692.658440493584;5401.8919621606565;5559.19391688211;5594;5612.102063669793;5615.606907516435;5754.482096611325;5787.2187254720075;5788;5792.044312688359;5802.4197580241635;5565;5609.411011843697;5611.046122159324;5614.6631202128;5643.661380317194 22:28:23 INFO - PROCESS | 4591 | |1;ext.html;575.2952633024546;626.0890404461508;630.4767043354017;639.0956796133471;650.158045331296;32859.67140328596;43518;44757.328640070424;44883.77558112213;44918.77540612301;45487;45968.3104753429;45988;46041;46084.53915460844;8849.557522123883;8994.68518601849;9015.864762028576;9023.503707296097;9028.548572571379;8809.779755506132;8963.775905602346;8976.685815996441;8976.730698079067;8991;8982.461052336881;9027.503487308195;9045.3668243223;9050.954745226298;9051.185393314601;8899.73300800977;8925.241354484877;8959.686410975584;8969.551522423886;8977.192052715254;8756.299496040301;8864.911350886538;8867.512286824222;8872.46765194084;8876.20114189726;42342.78828605868;42677.35983960243;42777;42833;42835;3405.6594340565994;3500.9649903500895;3636.4908912752235;3940.9556467536127;4164.208800327959;31767.36465270705;32102.678973210204;32462.513062304086;32474;33149.17127071819;4233.872983810491;4587.013792034695;4607.562281583244;4640.953590464086;4693.342931989524;340809;342809;342975;343198;343788;59423.702881486184;59719.70140149271;59943.400565994656;60075.69962150161;60076.39923600708;62932;63097;63407.6829615849;63552;63701.681491593175;17315.91342043282;17526.298948041935;17554.385596504315;17613;17632.735508967377;13489.12320699171;13514.32428378577;13569.52506662262;13600.455981760817;13603.795943060863;15964.600884977968;17627.206775695126;17723.911380443275;17890.55273618144;17918.5520361992;6728.6635668216395;6764.458843292525;6846.554973926679;6853.7943861684225;6892.655367231619;941.9670311539063;1010.141379827141;1044.0134073300658;1075.9085477734325;1099.7745462180235;31530.684693152776;31916.361672766878;31953.52069718956;32349.730045017666;32650.67349326524;1090.7109615951779;1118.116687816632;1124.5052177042076;1170.9004734597484;1175.1362748379934;16763.748543771853;16912.238949247312;16932.40736574214;17023.829761702225;17224.311027558757;505648;508642;516016;801910;803941;57947.4205257942;58986;59129.40870591325;59209.70395147997;59276.11085833716;1379.7240551889663;1397.2873834344557;1408.1199250468458;1420.659041829974;1428.5357258890901;30800.69199308068;32657.836710816293;32987;33003.339933201685;33613.49579756355;51927.74036129946;52290.47709523008;52462;52496.737516312176;52500;328138;334147;335364;335486;336053;27750;27891.16326510208;27966.720332797224;27968.44063118767;28212.153635390972;46063.53936460726;46767.76616116898;47048;47656;47715;39064.6093539061;39852.60147398489;39926.80036599799;40511.594884050784;41457;37916.24167516579;39846.800765995984;40456.39315410212;41297.587024129374;41685.166296674506;40163.19673606453;40585.79707101446;40764.1847163061;41452.37821432621;41521.79239103785;35429;35781.46327805033;36478.45282320714;36581;37097.814510927274;27106.72893271042;27410;27478.862605686845;27609.447811043265;27739.167824965283 22:28:23 INFO - PROCESS | 4591 | |2;jquery.html;382.0658489991971;396.4469564956884;399.3510545363784;402.23775944585014;416.4523651398411;13848.861511384883;17445.82554174458;18586.81413185868;19063.428097157073;19091.809081909178;17902.82097179028;18322;18605.627887442242;18628.53428664284;18650.626987460244;4058.5535591085104;5184.7407629618465;5327.840164795043;5481.725913704318;5528.75120619573;4674.298855171717;6150.200473938382;6160.630362178261;6250.187475628162;6253.906191407133;6024.2770867495965;6139.2325959255095;6178.938210617904;6207.751689932397;6218.440340369366;6125.111858780452;6174.9073763893575;6206.7827626033095;6212.78255261066;6220.377962203788;5274.593541619832;5460.890782184334;5464.644798088151;5498;5499.037668408013;22027.889860550593;22180;22536;22726.659100113513;22787.658185127235;2114.7990940860595;2224.6440569508964;2487.0549191307186;2694.7978901582264;2942.955855662167;9908.504574771232;9915.752106197331;10050.89949100507;10299.845502317472;10510.632127875488;1880.7743070831589;1919.424172748184;1968.3799603125112;1982.2566537548419;2021.080408414168;62010.37989620091;73932;74554.88167677543;77060;80126.19873801246;10894.291871028357;10951.945240273748;11132;11203.831942520868;11212.27120237182;15465;15557;15558;15577.766333505006;15605.219739013004;8036.9953755780525;8051.355891528664;8291.58542072894;8341.8331633367;8347;7917.445778795429;7919.20807919215;8164.877526837102;8328.33373330132;8410.705625303084;4655.95344046562;4790.736509491941;4791.592714619227;4815.662903596784;4897.314375987365;1917.769867615895;1984.593158402524;1985.8709183903;1992.8604997650316;2000.9799902000793;925.6991477769752;932.9020452852488;959.2661613865398;1010.696790962723;1103.0734183286079;18325.266989320546;18775;18798.248070077316;18827.529311767314;18985.335513257247;944.3436811416;964.1804466203811;966.3766870368573;993.6571882700457;1002.7192386131894;10305.330153539995;10518.684439466828;10580.735481612868;10595.470226488645;10598.735031624117;462543;471834;474833;485102;485522;69432;76847.61576192194;81367.59316203381;89250;89887.55056224809;1027.6505987964129;1052.742078190833;1064.3720205079042;1070.661672908864;1073.5759375046812;16533.33866645369;16674.505532589246;16757;16985;17401.56496087608;42214.788926055175;42416;42589.787051064544;42693.78653106715;43809.78095109504;58689.5327616813;59772.70113649578;60341.21698740667;60798.69600651968;61061.08408373967;8413.95793021031;8450;8535.487870727777;8847.823043539222;8848.601812918563;12253.877461225273;12908.483660653468;13141.211527308393;13901;14174;11916.34460104668;12119.636410907688;12166.209196402206;12247.101699236633;12267.263964162179;11821.172517923662;11961.162718609614;12037.699057523281;12303.261804291771;12305.446254918372;12261.754764904474;12297.692557685772;12379.62861114132;12595.93702031484;12738.299393533445;8402.369822263232;8902.198802107841;8966;9009.324300677607;9182.17360437564;11939.76120477603;12216.877831221575;12236;12304.840452423101;12452.937735311265 22:28:23 INFO - PROCESS | 4591 | |3;mootools.html;1377.1117629129205;1519.278342787176;1539.5689207022035;1711.3839017953535;1816.473222765399;8352.749417517476;8563.78590535237;8566.057733649293;8654.134586541342;8844.646214151426;8407.57962101894;8671.43635663683;8846.646134154642;8932.151445612673;8938.329625278098;2025.2607798153642;2305.9423514412106;2320.1879342230254;2361.8110551155864;2391.988040059797;2359.8112151027963;2367.6330168823774;2373.193114341124;2383.177803657744;2388.4387169015354;2305.9192928247517;2336.9600527765065;2371.5138396628736;2374.097842819726;2375.3942744600104;2279.9658005129936;2295.1049090854663;2314.687517185192;2315.0623997281127;2325.186184835311;2273.943151421228;2279.7150356205475;2285.108807565051;2304.9308520744403;2309.3880121767743;8917.108289171096;8958.552072396355;8969.461832290084;8975.147361000694;8982.775430614221;5227.686338819683;5317.601179911483;5320.787168513255;5332.520073193431;5338.225957236179;3184.2994541200896;3334.6498617645284;3360.479125735515;3383.898483045488;3393.5079413485155;980.9019098090205;991.9305648604601;1025.9589616415335;1033.1734612310197;1046.0899017854515;12298.446569904376;12606.936965315115;12613.432395542222;12634;12635.93682031584;4771.880702982453;4786.16242157621;4806.158922188603;4868.659193856396;4901.852944411673;10552.261341706007;10558.788824223473;10562.841557376645;10573.788524229627;10590.993855583707;5276.498732620472;5419.566434685215;5424.783008679608;5432.483914028161;5434.538064264502;4317.481902171759;4822.614190864722;4965.553100220925;5004.799808007639;5050.267711181931;4089.27642497954;4107.753534787863;4110.979445102816;4138.52406973202;4155.355919832431;1173.7652469506133;1300.921944683322;1309.6987692830703;1311.6261865368515;1338.1703343926827;804.2158895077253;894.0612357025227;928.475411392561;946.2146418472652;978.9804203915882;6071.150038994544;6107.350072587851;6246.906296405557;6265.780697675651;6282;499.0592732698889;499.6876951905059;501.26564582885516;509.5694138453001;657.2441692054081;4196.223698615766;4302.09655972243;4302.290122129813;4307.117041006649;4325.675574331937;10959.561617535368;11224.214304998572;11361.14791390649;11369.658910232707;11384.487698053608;11229.943850280859;11305.604303849326;11385.430728463543;11389.829152562717;11395.316281022999;7891;8168.305694016075;8338.541380224025;8511.95744021276;8542.658293668308;6095.2685677718955;6118.063329601727;6260.843478912881;6338;6392.744290228525;9723.454083412114;10105.646302379382;10220.897791021995;10303.793924121626;10305.948470257901;10351.482425878525;10376.014278643517;10421.583136674448;10454.477276136011;10509.737256568647;7104.928950710633;7287.453440991841;7747.457677962714;8065.838683226185;8218.794530136554;8416.074231834617;8581.613827377658;8664.48013119215;8669.523176225368;8673;7409.777706668809;7564.697412103675;7694.730684426018;7702.075750909703;8000.919990800017;7493.962530187314;7538.133114691661;7560.508566943131;7651.043619547557;7724.806879828049;6778.186617605918;6840.213375461885;6841.794746157623;6849.452043836581;6858.554193977376;4938.38270216223;5103.10695628271;5191.792328306826;5232.633715639869;5254.290670759398;5108.514691104339;5180.7409629517615;5213.452587478412;5231.712255825966;5348.893022139458 22:28:23 INFO - PROCESS | 4591 | |4;prototype.html;325.56374458225974;326.55099238546995;337.04111801923534;340.16150189782184;342.88170581149495;5125.384953805539;5257.815976440816;5288.8413347599535;5292.576593872491;5324.840254792358;5201.349831271091;5240.659357141783;5246.606504512158;5268.394134674513;5274.367075950891;4165.791710414483;4169.124483858378;4169.791510424482;4205.768682722457;4209.473815773028;3930.430087637291;4126.690498212631;4130.070734084837;4177.394277829713;4181.519125300599;4067.2678917794933;4069.2471892699796;4072.735272207297;4082.816273267695;4108.28105081611;4082.3672330788777;4090.550039495653;4108.630223279919;4117.505899292074;4119.258533463975;3444.621091679913;3631.182983828644;3634.545681789776;3636.8181590920613;3638.4178531434845;5075.289459475676;5077.796888124471;5089.211172268304;5093.949060509384;5115;4616.699914505546;4662.557057079606;4694.061187762427;4715.221988371914;4737.384140061805;5281.841544753664;5464.480874316933;5487.259220005288;5494.532964697965;5532.585056120807;3906.726529142961;3925.60538071776;3931.312020396419;3934.11482416454;3935.0162459385156;5462.754176062087;5490.615656904059;5693.20295158674;5701.515371193454;5706.400827913051;3809.4476300936763;3926.2147570485445;3940.4286378475535;3950.881473555798;3963.6630886374405;3983.2830090583393;4007.899802504961;4010.5387880393546;4045.1505183911117;4060.4924384451942;4398.73607583546;4537.750423726727;4578.008566273682;4632.753114739348;4636.397268355092;5461.453854614507;5466.590005749585;5468.648481153143;5480.506754392124;5503.2020357048805;1367.5555444480385;1382.5783136143373;1384.1349156777014;1386.6256110850215;1398.6713122416224;882.9955850220837;907.6278725722422;911.0889110889111;923.5520772425368;970.9563069661882;5247.1866860636665;5279.868003299948;5303.231031500488;5307.858810355792;5324.893502129936;5580.302462192226;5608.747606357723;5616.831495055155;5618.747156377973;5638.887222255532;5046.343975283263;5202.505761952609;5218.165093585008;5260.579153667697;5278.709670968057;2969.9851500742357;2974.8215107093642;2981.1950773291524;2992.8653210605576;3011.7741169412384;5675.716214189274;5782.855428614234;5799.43415277881;5803.303603567599;5818.265415254824;5464.890702186013;5748.540116790647;5749.3963133871175;5750.913736293875;5782.2483077199695;5751.338596061507;5751.827445176651;5757.165211044459;5826.737796799154;5848.444397782204;5299.49654782795;5535;5542.584306177134;5578.63738856973;5672.829815105553;5301.23132145829;5579.4420557945505;5598.488408129863;5641.379448260606;5651.406602306863;5657.2928383952;5693.3168019837885;5695.316562012585;5705.144228365778;5736.254286942838;3978.4629075075654;4007.358822588372;4026.91946161081;4041.2321658884716;4044.2315959967514;5388.353397592314;5624.918780051407;5625.6498440374835;5637.661740295595;5678.3753787084215;5511.366192887869;5525.944740552543;5526.55787536988;5535.557155427476;5559.749811258584;5410.728478807556;5447.6380904773805;5485.945140548543;5495.890082198413;5534.08687566547;5137.8201762938115;5420.593455490933;5423.701696406735;5427.6200665953;5452.26394436746;5510.586705997146;5520.819276385725;5522.66863988162;5530.281063461724;5557.249771280987;5296.629235953446;5410.540104091197;5418.349798024262;5419.214213939035;5437.4018857926385 22:28:23 INFO - PROCESS | 4591 | |5;yui.html;764.2586690909819;793.4445887878486;802.8394321135767;811.7929927868392;862.7843039240192;14807.925960370196;15739.685206295882;15776.921115394436;15780.684386312267;15789.763153552705;14207.78688319676;14563.126212427262;14581.562553123396;14686.118832870035;14711.55865324042;655.678717428459;787.3465024030041;816.9632366543505;825.793551612097;830.8213734047179;817.7791996161033;827.8302947895697;830.8587540118194;831.476170012891;831.9558953513332;858.3991206155684;859.793649524116;863.7797361672765;865.2688478235881;865.9004214515317;695.5896021347429;836.9832603347899;856.6359297298654;857.9313654907593;858.2490320969152;632.5350867112714;632.7405763636933;633.1863555331377;633.7369991453542;633.8035209085152;13787.448502059906;13795.793063104062;13807.240601766898;13842.446302147902;13851.445942162301;12717.364131793373;12888.935555322258;12961.416736246798;12968;13043;11110;11558.422078896107;11757.58848440309;11899.821502677467;11940.940295298467;4374.6062854342945;4459.197344477992;4477.798499067517;4640.67515273931;4683.953160468386;56027;56415.153772693855;56458.717706411204;56548;56555.717221414045;10647.30792498485;10838.07876330505;10914.23600347968;11017.063549598213;11355.282163669805;18575.535611609546;18628;18653;18684.06579671011;18689.252429902925;8290.626921788535;8414.410991230678;8716.389852710376;8723.432976856484;8726.432781869158;4887.65786394956;4917.754112294442;4951.628627852925;4957.182064959314;5008.023435430129;3264.771465997357;3322.7341812655413;3339.098443420263;3359.227377703093;3360.5631267935014;638.9169407977026;641.3256719479781;642.7686033028106;646;651.4430162211336;663.8539521305305;726.4478995963001;745.6681776609465;751.4364226829878;761.6839011810148;9104.8634270486;9712.75718107053;9737.73409456766;9813.607455701693;9848.655297064568;648.7405037984844;671.2616122265468;702.7118881258659;743.0451869347862;761.417515600566;4222.577742225752;4374.168907907551;4429.180601588717;4432.853989964455;4520.389747384127;58377.416225838046;61463.692681536304;61687.383126169065;65335.346646532926;66273;58810.41189588049;59233.40766592365;59768.40231597628;61271.38728612657;62933.37066629367;49507.75246123674;53037.734811325696;55111.44888551143;55553;55647;9626.90373096288;9716.854247186156;9763.218942484442;9861.359011664506;10208.591656333663;35320;36947.63052369442;37682.62317376791;38018;38167.2366552673;45577.77211113923;50346.49653503418;51071.489285106676;51214.74392628013;52334.73832630813;13158.736825263633;13775.931120344334;13810.240436776074;13891;13896.861031389557;14381.352839122055;14381.85618143847;14586;14783.186924718813;15032.624184395478;12634.936825315815;12662.683432914251;12856.742865142458;13445.394957226752;13640.590782276548;13457.730845383234;13771.242581658105;13903.930480347533;13929.58211253664;13972.790408143683;13051.673708157372;13248.668783280109;13332.200067995951;13388.93305533466;13489.797653035408;11064.336139831636;11070.560827092426;11240;11267.507107773567;11422.143339249424;10916.672499825017;11387.43062846871;11557.24877882935;11607.76784464323;11620.5351785931 22:28:23 INFO - PROCESS | 4591 | __end_tp_report 22:28:23 INFO - PROCESS | 4591 | __start_cc_report 22:28:23 INFO - PROCESS | 4591 | _x_x_mozilla_cycle_collect,896 22:28:23 INFO - PROCESS | 4591 | __end_cc_report 22:28:23 INFO - PROCESS | 4591 | __startTimestamp1464413303379__endTimestamp 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | ------- Summary: start ------- 22:28:23 INFO - PROCESS | 4591 | Number of tests: 6 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | [#0] dojo.html Cycles:165 Average:5417.99 Median:5611.05 stddev:628.99 (11.2%) stddev-sans-first:604.53 22:28:23 INFO - PROCESS | 4591 | Values: 3120.0 3129.5 3154.8 3163.4 3198.0 5273.5 5312.7 5391.1 5479.5 5524.7 5382.1 5401.5 5426.4 5444.7 5475.5 4850.8 4911.3 4928.3 4941.1 5161.8 5057.5 5079.5 5113.5 5132.8 5133.5 4830.1 4858.7 4879.9 4912.0 4926.7 4915.2 4944.6 5016.0 5016.1 5061.4 4813.7 4960.5 4981.6 4988.7 4989.8 5283.8 5403.6 5409.5 5457.5 5483.3 5828.8 5876.8 5897.6 5905.6 5916.2 5603.3 5715.4 5915.1 5943.2 5950.4 5428.1 5543.9 5546.4 5550.1 5552.8 6009.1 6057.1 6068.0 6073.2 6073.6 5516.4 5637.3 5678.0 5765.7 5818.3 5831.6 5888.6 5892.8 5895.4 5898.4 5782.9 5818.8 5820.9 5828.4 5832.7 5617.8 5717.3 5776.2 5790.9 5838.9 4417.4 4417.7 4418.2 4420.9 4447.3 3927.7 3943.0 3954.1 3981.6 4008.3 5271.9 5364.8 5472.2 5545.8 5548.5 5931.7 5958.9 5965.9 5969.7 6010.5 5577.6 5584.9 5585.2 5587.1 5588.1 4733.9 4848.4 4893.3 4894.8 4915.8 6062.9 6067.5 6067.5 6069.8 6071.8 5704.0 5886.4 5908.6 5909.5 5910.2 5975.5 6039.6 6048.6 6055.1 6080.8 5736.8 5782.6 5790.7 5792.8 5798.4 5621.7 5737.6 5861.0 5873.6 5877.1 5636.8 5692.1 5701.1 5706.2 5748.1 5643.7 5683.1 5683.9 5692.1 5692.7 5401.9 5559.2 5594.0 5612.1 5615.6 5754.5 5787.2 5788.0 5792.0 5802.4 5565.0 5609.4 5611.0 5614.7 5643.7 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | [#1] ext.html Cycles:180 Average:59879.91 Median:41454.69 stddev:123729.56 (298.5%) stddev-sans-first:123996.53 22:28:23 INFO - PROCESS | 4591 | Values: 575.3 626.1 630.5 639.1 650.2 32859.7 43518.0 44757.3 44883.8 44918.8 45487.0 45968.3 45988.0 46041.0 46084.5 8849.6 8994.7 9015.9 9023.5 9028.5 8809.8 8963.8 8976.7 8976.7 8991.0 8982.5 9027.5 9045.4 9051.0 9051.2 8899.7 8925.2 8959.7 8969.6 8977.2 8756.3 8864.9 8867.5 8872.5 8876.2 42342.8 42677.4 42777.0 42833.0 42835.0 3405.7 3501.0 3636.5 3941.0 4164.2 31767.4 32102.7 32462.5 32474.0 33149.2 4233.9 4587.0 4607.6 4641.0 4693.3 340809.0 342809.0 342975.0 343198.0 343788.0 59423.7 59719.7 59943.4 60075.7 60076.4 62932.0 63097.0 63407.7 63552.0 63701.7 17315.9 17526.3 17554.4 17613.0 17632.7 13489.1 13514.3 13569.5 13600.5 13603.8 15964.6 17627.2 17723.9 17890.6 17918.6 6728.7 6764.5 6846.6 6853.8 6892.7 942.0 1010.1 1044.0 1075.9 1099.8 31530.7 31916.4 31953.5 32349.7 32650.7 1090.7 1118.1 1124.5 1170.9 1175.1 16763.7 16912.2 16932.4 17023.8 17224.3 505648.0 508642.0 516016.0 801910.0 803941.0 57947.4 58986.0 59129.4 59209.7 59276.1 1379.7 1397.3 1408.1 1420.7 1428.5 30800.7 32657.8 32987.0 33003.3 33613.5 51927.7 52290.5 52462.0 52496.7 52500.0 328138.0 334147.0 335364.0 335486.0 336053.0 27750.0 27891.2 27966.7 27968.4 28212.2 46063.5 46767.8 47048.0 47656.0 47715.0 39064.6 39852.6 39926.8 40511.6 41457.0 37916.2 39846.8 40456.4 41297.6 41685.2 40163.2 40585.8 40764.2 41452.4 41521.8 35429.0 35781.5 36478.5 36581.0 37097.8 27106.7 27410.0 27478.9 27609.4 27739.2 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | [#2] jquery.html Cycles:180 Average:28183.60 Median:1662.51 stddev:78254.99 (4707.0%) stddev-sans-first:78446.67 22:28:23 INFO - PROCESS | 4591 | Values: 382.1 396.4 399.4 402.2 416.5 13848.9 17445.8 18586.8 19063.4 19091.8 17902.8 18322.0 18605.6 18628.5 18650.6 4058.6 5184.7 5327.8 5481.7 5528.8 4674.3 6150.2 6160.6 6250.2 6253.9 6024.3 6139.2 6178.9 6207.8 6218.4 6125.1 6174.9 6206.8 6212.8 6220.4 5274.6 5460.9 5464.6 5498.0 5499.0 22027.9 22180.0 22536.0 22726.7 22787.7 2114.8 2224.6 2487.1 2694.8 2943.0 9908.5 9915.8 10050.9 10299.8 10510.6 1880.8 1919.4 1968.4 1982.3 2021.1 62010.4 73932.0 74554.9 77060.0 80126.2 10894.3 10951.9 11132.0 11203.8 11212.3 15465.0 15557.0 15558.0 15577.8 15605.2 8037.0 8051.4 8291.6 8341.8 8347.0 7917.4 7919.2 8164.9 8328.3 8410.7 4656.0 4790.7 4791.6 4815.7 4897.3 1917.8 1984.6 1985.9 1992.9 2001.0 925.7 932.9 959.3 1010.7 1103.1 18325.3 18775.0 18798.2 18827.5 18985.3 944.3 964.2 966.4 993.7 1002.7 10305.3 10518.7 10580.7 10595.5 10598.7 462543.0 471834.0 474833.0 485102.0 485522.0 69432.0 76847.6 81367.6 89250.0 89887.6 1027.7 1052.7 1064.4 1070.7 1073.6 16533.3 16674.5 16757.0 16985.0 17401.6 42214.8 42416.0 42589.8 42693.8 43809.8 58689.5 59772.7 60341.2 60798.7 61061.1 8414.0 8450.0 8535.5 8847.8 8848.6 12253.9 12908.5 13141.2 13901.0 14174.0 11916.3 12119.6 12166.2 12247.1 12267.3 11821.2 11961.2 12037.7 12303.3 12305.4 12261.8 12297.7 12379.6 12595.9 12738.3 8402.4 8902.2 8966.0 9009.3 9182.2 11939.8 12216.9 12236.0 12304.8 12452.9 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | [#3] mootools.html Cycles:180 Average:5864.39 Median:2732.31 stddev:3381.08 (123.7%) stddev-sans-first:3373.74 22:28:23 INFO - PROCESS | 4591 | Values: 1377.1 1519.3 1539.6 1711.4 1816.5 8352.7 8563.8 8566.1 8654.1 8844.6 8407.6 8671.4 8846.6 8932.2 8938.3 2025.3 2305.9 2320.2 2361.8 2392.0 2359.8 2367.6 2373.2 2383.2 2388.4 2305.9 2337.0 2371.5 2374.1 2375.4 2280.0 2295.1 2314.7 2315.1 2325.2 2273.9 2279.7 2285.1 2304.9 2309.4 8917.1 8958.6 8969.5 8975.1 8982.8 5227.7 5317.6 5320.8 5332.5 5338.2 3184.3 3334.6 3360.5 3383.9 3393.5 980.9 991.9 1026.0 1033.2 1046.1 12298.4 12606.9 12613.4 12634.0 12635.9 4771.9 4786.2 4806.2 4868.7 4901.9 10552.3 10558.8 10562.8 10573.8 10591.0 5276.5 5419.6 5424.8 5432.5 5434.5 4317.5 4822.6 4965.6 5004.8 5050.3 4089.3 4107.8 4111.0 4138.5 4155.4 1173.8 1300.9 1309.7 1311.6 1338.2 804.2 894.1 928.5 946.2 979.0 6071.2 6107.4 6246.9 6265.8 6282.0 499.1 499.7 501.3 509.6 657.2 4196.2 4302.1 4302.3 4307.1 4325.7 10959.6 11224.2 11361.1 11369.7 11384.5 11229.9 11305.6 11385.4 11389.8 11395.3 7891.0 8168.3 8338.5 8512.0 8542.7 6095.3 6118.1 6260.8 6338.0 6392.7 9723.5 10105.6 10220.9 10303.8 10305.9 10351.5 10376.0 10421.6 10454.5 10509.7 7104.9 7287.5 7747.5 8065.8 8218.8 8416.1 8581.6 8664.5 8669.5 8673.0 7409.8 7564.7 7694.7 7702.1 8000.9 7494.0 7538.1 7560.5 7651.0 7724.8 6778.2 6840.2 6841.8 6849.5 6858.6 4938.4 5103.1 5191.8 5232.6 5254.3 5108.5 5180.7 5213.5 5231.7 5348.9 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | [#4] prototype.html Cycles:180 Average:4611.12 Median:5285.34 stddev:1352.84 (25.6%) stddev-sans-first:1317.84 22:28:23 INFO - PROCESS | 4591 | Values: 325.6 326.6 337.0 340.2 342.9 5125.4 5257.8 5288.8 5292.6 5324.8 5201.3 5240.7 5246.6 5268.4 5274.4 4165.8 4169.1 4169.8 4205.8 4209.5 3930.4 4126.7 4130.1 4177.4 4181.5 4067.3 4069.2 4072.7 4082.8 4108.3 4082.4 4090.6 4108.6 4117.5 4119.3 3444.6 3631.2 3634.5 3636.8 3638.4 5075.3 5077.8 5089.2 5093.9 5115.0 4616.7 4662.6 4694.1 4715.2 4737.4 5281.8 5464.5 5487.3 5494.5 5532.6 3906.7 3925.6 3931.3 3934.1 3935.0 5462.8 5490.6 5693.2 5701.5 5706.4 3809.4 3926.2 3940.4 3950.9 3963.7 3983.3 4007.9 4010.5 4045.2 4060.5 4398.7 4537.8 4578.0 4632.8 4636.4 5461.5 5466.6 5468.6 5480.5 5503.2 1367.6 1382.6 1384.1 1386.6 1398.7 883.0 907.6 911.1 923.6 971.0 5247.2 5279.9 5303.2 5307.9 5324.9 5580.3 5608.7 5616.8 5618.7 5638.9 5046.3 5202.5 5218.2 5260.6 5278.7 2970.0 2974.8 2981.2 2992.9 3011.8 5675.7 5782.9 5799.4 5803.3 5818.3 5464.9 5748.5 5749.4 5750.9 5782.2 5751.3 5751.8 5757.2 5826.7 5848.4 5299.5 5535.0 5542.6 5578.6 5672.8 5301.2 5579.4 5598.5 5641.4 5651.4 5657.3 5693.3 5695.3 5705.1 5736.3 3978.5 4007.4 4026.9 4041.2 4044.2 5388.4 5624.9 5625.6 5637.7 5678.4 5511.4 5525.9 5526.6 5535.6 5559.7 5410.7 5447.6 5485.9 5495.9 5534.1 5137.8 5420.6 5423.7 5427.6 5452.3 5510.6 5520.8 5522.7 5530.3 5557.2 5296.6 5410.5 5418.3 5419.2 5437.4 22:28:23 INFO - PROCESS | 4591 | 22:28:23 INFO - PROCESS | 4591 | [#5] yui.html Cycles:180 Average:15606.86 Median:4662.31 stddev:18116.79 (388.6%) stddev-sans-first:18133.32 22:28:23 INFO - PROCESS | 4591 | Values: 764.3 793.4 802.8 811.8 862.8 14807.9 15739.7 15776.9 15780.7 15789.8 14207.8 14563.1 14581.6 14686.1 14711.6 655.7 787.3 817.0 825.8 830.8 817.8 827.8 830.9 831.5 832.0 858.4 859.8 863.8 865.3 865.9 695.6 837.0 856.6 857.9 858.2 632.5 632.7 633.2 633.7 633.8 13787.4 13795.8 13807.2 13842.4 13851.4 12717.4 12888.9 12961.4 12968.0 13043.0 11110.0 11558.4 11757.6 11899.8 11940.9 4374.6 4459.2 4477.8 4640.7 4684.0 56027.0 56415.2 56458.7 56548.0 56555.7 10647.3 10838.1 10914.2 11017.1 11355.3 18575.5 18628.0 18653.0 18684.1 18689.3 8290.6 8414.4 8716.4 8723.4 8726.4 4887.7 4917.8 4951.6 4957.2 5008.0 3264.8 3322.7 3339.1 3359.2 3360.6 638.9 641.3 642.8 646.0 651.4 663.9 726.4 745.7 751.4 761.7 9104.9 9712.8 9737.7 9813.6 9848.7 648.7 671.3 702.7 743.0 761.4 4222.6 4374.2 4429.2 4432.9 4520.4 58377.4 61463.7 61687.4 65335.3 66273.0 58810.4 59233.4 59768.4 61271.4 62933.4 49507.8 53037.7 55111.4 55553.0 55647.0 9626.9 9716.9 9763.2 9861.4 10208.6 35320.0 36947.6 37682.6 38018.0 38167.2 45577.8 50346.5 51071.5 51214.7 52334.7 13158.7 13775.9 13810.2 13891.0 13896.9 14381.4 14381.9 14586.0 14783.2 15032.6 12634.9 12662.7 12856.7 13445.4 13640.6 13457.7 13771.2 13903.9 13929.6 13972.8 13051.7 13248.7 13332.2 13388.9 13489.8 11064.3 11070.6 11240.0 11267.5 11422.1 10916.7 11387.4 11557.2 11607.8 11620.5 22:28:23 INFO - PROCESS | 4591 | -------- Summary: end -------- 22:28:23 INFO - PROCESS | 4591 | 22:28:24 INFO - TEST-INFO | 4591: exit 0 22:28:24 INFO - TEST-OK | dromaeo_css | took 1112634ms 22:28:24 INFO - TEST-START | kraken 22:28:24 INFO - operating with platform_type : linux_ 22:28:24 INFO - Initialising browser for kraken test... 22:28:24 INFO - TEST-INFO | started process 4772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHqpwXD/profile http://localhost:60401/getInfo.html) 22:28:28 INFO - PROCESS | 4772 | __metrics Screen width/height:1600/1200 22:28:28 INFO - PROCESS | 4772 | colorDepth:24 22:28:28 INFO - PROCESS | 4772 | Browser inner width/height: 1024/697 22:28:28 INFO - PROCESS | 4772 | __metrics 22:28:28 ERROR - PROCESS | 4772 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:28:29 INFO - TEST-INFO | 4772: exit 0 22:28:29 INFO - Browser initialized. 22:28:29 INFO - Running cycle 1/1 for kraken test... 22:28:29 INFO - TEST-INFO | started process 4856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHqpwXD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:41 INFO - PROCESS | 4856 | RSS: Main: 155430912 22:28:41 INFO - PROCESS | 4856 | 22:28:44 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60401/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:44 INFO - PROCESS | 4856 | RSS: Main: 213839872 22:28:44 INFO - PROCESS | 4856 | 22:28:47 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60401/tests/kraken/driver.html?testName=audio-dft) 22:28:48 INFO - PROCESS | 4856 | RSS: Main: 197742592 22:28:48 INFO - PROCESS | 4856 | 22:28:51 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60401/tests/kraken/driver.html?testName=audio-fft) 22:28:51 INFO - PROCESS | 4856 | RSS: Main: 268247040 22:28:51 INFO - PROCESS | 4856 | 22:28:54 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60401/tests/kraken/driver.html?testName=audio-oscillator) 22:28:54 INFO - PROCESS | 4856 | RSS: Main: 181620736 22:28:54 INFO - PROCESS | 4856 | 22:28:56 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60401/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:28:57 INFO - PROCESS | 4856 | RSS: Main: 184422400 22:28:57 INFO - PROCESS | 4856 | 22:29:00 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60401/tests/kraken/driver.html?testName=imaging-darkroom) 22:29:00 INFO - PROCESS | 4856 | RSS: Main: 217673728 22:29:00 INFO - PROCESS | 4856 | 22:29:04 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60401/tests/kraken/driver.html?testName=imaging-desaturate) 22:29:04 INFO - PROCESS | 4856 | RSS: Main: 219774976 22:29:04 INFO - PROCESS | 4856 | 22:29:07 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60401/tests/kraken/driver.html?testName=json-parse-financial) 22:29:08 INFO - PROCESS | 4856 | RSS: Main: 219398144 22:29:08 INFO - PROCESS | 4856 | 22:29:09 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60401/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:29:10 INFO - PROCESS | 4856 | RSS: Main: 206102528 22:29:10 INFO - PROCESS | 4856 | 22:29:12 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:29:12 INFO - PROCESS | 4856 | RSS: Main: 208457728 22:29:12 INFO - PROCESS | 4856 | 22:29:14 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:29:15 INFO - PROCESS | 4856 | RSS: Main: 216653824 22:29:15 INFO - PROCESS | 4856 | 22:29:18 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:29:18 INFO - PROCESS | 4856 | RSS: Main: 220938240 22:29:18 INFO - PROCESS | 4856 | 22:29:21 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:29:22 INFO - PROCESS | 4856 | RSS: Main: 219291648 22:29:22 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | Cycle 1(1): loaded http://localhost:60401/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60401/tests/kraken/driver.html?testName=ai-astar) 22:29:23 INFO - PROCESS | 4856 | RSS: Main: 261804032 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | __start_tp_report 22:29:23 INFO - PROCESS | 4856 | _x_x_mozilla_page_load 22:29:23 INFO - PROCESS | 4856 | _x_x_mozilla_page_load_details 22:29:23 INFO - PROCESS | 4856 | |i|pagename|runs| 22:29:23 INFO - PROCESS | 4856 | |0;ai-astar;111;105;110;110;112;121;110;111;111;110 22:29:23 INFO - PROCESS | 4856 | |1;audio-beat-detection;170;124;130;189;129;129;155;131;182;124 22:29:23 INFO - PROCESS | 4856 | |2;audio-dft;159;158;160;158;161;160;160;161;160;161 22:29:23 INFO - PROCESS | 4856 | |3;audio-fft;75;74;76;128;75;77;75;74;99;74 22:29:23 INFO - PROCESS | 4856 | |4;audio-oscillator;84;84;123;84;82;84;117;84;83;127 22:29:23 INFO - PROCESS | 4856 | |5;imaging-gaussian-blur;117;117;117;117;118;116;117;117;117;117 22:29:23 INFO - PROCESS | 4856 | |6;imaging-darkroom;146;147;147;146;146;148;146;146;146;145 22:29:23 INFO - PROCESS | 4856 | |7;imaging-desaturate;95;95;95;94;94;94;95;94;95;95 22:29:23 INFO - PROCESS | 4856 | |8;json-parse-financial;55;54;55;56;54;55;54;56;53;54 22:29:23 INFO - PROCESS | 4856 | |9;json-stringify-tinderbox;61;61;61;62;61;61;61;62;61;61 22:29:23 INFO - PROCESS | 4856 | |10;stanford-crypto-aes;80;80;80;85;81;84;86;81;125;81 22:29:23 INFO - PROCESS | 4856 | |11;stanford-crypto-ccm;128;130;130;117;128;135;126;119;124;130 22:29:23 INFO - PROCESS | 4856 | |12;stanford-crypto-pbkdf2;166;164;158;168;161;157;159;160;162;159 22:29:23 INFO - PROCESS | 4856 | |13;stanford-crypto-sha256-iterative;62;63;64;65;63;62;66;62;62;61 22:29:23 INFO - PROCESS | 4856 | __end_tp_report 22:29:23 INFO - PROCESS | 4856 | __start_cc_report 22:29:23 INFO - PROCESS | 4856 | _x_x_mozilla_cycle_collect,1642 22:29:23 INFO - PROCESS | 4856 | __end_cc_report 22:29:23 INFO - PROCESS | 4856 | __startTimestamp1464413363952__endTimestamp 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | ------- Summary: start ------- 22:29:23 INFO - PROCESS | 4856 | Number of tests: 14 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:3.96 (3.6%) stddev-sans-first:4.20 22:29:23 INFO - PROCESS | 4856 | Values: 111.0 105.0 110.0 110.0 112.0 121.0 110.0 111.0 111.0 110.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:143.00 stddev:25.45 (17.8%) stddev-sans-first:25.50 22:29:23 INFO - PROCESS | 4856 | Values: 170.0 124.0 130.0 189.0 129.0 129.0 155.0 131.0 182.0 124.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 22:29:23 INFO - PROCESS | 4856 | Values: 159.0 158.0 160.0 158.0 161.0 160.0 160.0 161.0 160.0 161.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#3] audio-fft Cycles:10 Average:82.70 Median:75.00 stddev:17.64 (23.5%) stddev-sans-first:18.49 22:29:23 INFO - PROCESS | 4856 | Values: 75.0 74.0 76.0 128.0 75.0 77.0 75.0 74.0 99.0 74.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:84.00 stddev:18.88 (22.5%) stddev-sans-first:19.59 22:29:23 INFO - PROCESS | 4856 | Values: 84.0 84.0 123.0 84.0 82.0 84.0 117.0 84.0 83.0 127.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 22:29:23 INFO - PROCESS | 4856 | Values: 117.0 117.0 117.0 117.0 118.0 116.0 117.0 117.0 117.0 117.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 22:29:23 INFO - PROCESS | 4856 | Values: 146.0 147.0 147.0 146.0 146.0 148.0 146.0 146.0 146.0 145.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:29:23 INFO - PROCESS | 4856 | Values: 95.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 22:29:23 INFO - PROCESS | 4856 | Values: 55.0 54.0 55.0 56.0 54.0 55.0 54.0 56.0 53.0 54.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 22:29:23 INFO - PROCESS | 4856 | Values: 61.0 61.0 61.0 62.0 61.0 61.0 61.0 62.0 61.0 61.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:81.00 stddev:13.78 (17.0%) stddev-sans-first:14.42 22:29:23 INFO - PROCESS | 4856 | Values: 80.0 80.0 80.0 85.0 81.0 84.0 86.0 81.0 125.0 81.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:129.00 stddev:5.44 (4.2%) stddev-sans-first:5.75 22:29:23 INFO - PROCESS | 4856 | Values: 128.0 130.0 130.0 117.0 128.0 135.0 126.0 119.0 124.0 130.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.40 Median:161.50 stddev:3.60 (2.2%) stddev-sans-first:3.41 22:29:23 INFO - PROCESS | 4856 | Values: 166.0 164.0 158.0 168.0 161.0 157.0 159.0 160.0 162.0 159.0 22:29:23 INFO - PROCESS | 4856 | 22:29:23 INFO - PROCESS | 4856 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:1.56 (2.5%) stddev-sans-first:1.62 22:29:23 INFO - PROCESS | 4856 | Values: 62.0 63.0 64.0 65.0 63.0 62.0 66.0 62.0 62.0 61.0 22:29:23 INFO - PROCESS | 4856 | -------- Summary: end -------- 22:29:23 INFO - PROCESS | 4856 | 22:29:24 INFO - TEST-INFO | 4856: exit 0 22:29:24 INFO - TEST-OK | kraken | took 60897ms 22:29:24 INFO - SUITE-END | took 1173s 22:29:25 INFO - Completed test suite (00:19:33) 22:29:25 INFO - javascript benchmark 22:29:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.0483852425014, 3129.499280115182, 3154.842257887106, 3163.3515129398475, 3198.0246024962375, 5273.472652734725, 5312.73436328183, 5391.110466772982, 5479.506844384005, 5524.66851988881, 5382.058139825534, 5401.4868587484225, 5426.375966763823, 5444.673319600826, 5475.534579560742, 4850.781714822824, 4911.287863259825, 4928.260760885878, 4941.135301322254, 5161.793528258865, 5057.544820966113, 5079.517445842639, 5113.539781419671, 5132.794688212467, 5133.460986596409, 4830.058138662962, 4858.732769697665, 4879.8780030499165, 4911.975440122812, 4926.729029903354, 4915.188993816016, 4944.60443164546, 5016.04695107932, 5016.147254966664, 5061.44324124346, 4813.735244561548, 4960.503949605047, 4981.601471882241, 4988.6757360771435, 4989.750512474362, 5283.762230699628, 5403.648762830403, 5409.513143817036, 5457.481539253765, 5483.287172667568, 5828.795992140297, 5876.794312199096, 5897.557683173779, 5905.586608937376, 5916.171735956969, 5603.299587551556, 5715.371309155989, 5915.08316210988, 5943.197668314765, 5950.434708702666, 5428.077226871442, 5543.944560554423, 5546.389897111315, 5550.139728342114, 5552.750126244328, 6009.06859436788, 6057.121717350959, 6067.9684453642985, 6073.240844894388, 6073.635581865102, 5516.420775818561, 5637.267155269788, 5677.971610142005, 5765.711714414262, 5818.301803783573, 5831.620944638585, 5888.617239879394, 5892.823215303548, 5895.380984996599, 5898.439648233411, 5782.884342313214, 5818.8254352369495, 5820.854478638068, 5828.446297601721, 5832.737526811304, 5617.775288988477, 5717.256756621696, 5776.220210271559, 5790.855228619318, 5838.854028649318, 4417.447819022622, 4417.668674849399, 4418.248897687401, 4420.9336859947125, 4447.310666846644, 3927.7446965947693, 3943.0339566806315, 3954.149857780591, 3981.5620281768984, 4008.318585840414, 5271.868203294872, 5364.758585863646, 5472.2338872557875, 5545.750441230073, 5548.528375088082, 5931.673757943355, 5958.851028724316, 5965.910511342333, 5969.731362088716, 6010.549208809357, 5577.609567330329, 5584.91622625661, 5585.162225666101, 5587.1339942309005, 5588.105903055492, 4733.905321893543, 4848.418189817245, 4893.339399181136, 4894.828680996148, 4915.8033678652455, 6062.939370606326, 6067.484263837535, 6067.54493412987, 6069.787557435557, 6071.787487437919, 5704.0, 5886.352501224862, 5908.586398952032, 5909.468147866713, 5910.23166988306, 5975.492083172979, 6039.63762174271, 6048.576599637983, 6055.091736239599, 6080.847978800565, 5736.799212027559, 5782.566307527037, 5790.739416726173, 5792.82621521355, 5798.420157984337, 5621.690807005654, 5737.627054241461, 5860.970695146497, 5873.647581145145, 5877.147813567094, 5636.830895073154, 5692.117721753135, 5701.116326969326, 5706.201131841629, 5748.137779333133, 5643.71781410936, 5683.090705487268, 5683.943160568507, 5692.0608099663095, 5692.658440493584, 5401.8919621606565, 5559.19391688211, 5594.0, 5612.102063669793, 5615.606907516435, 5754.482096611325, 5787.2187254720075, 5788.0, 5792.044312688359, 5802.4197580241635, 5565.0, 5609.411011843697, 5611.046122159324, 5614.6631202128, 5643.661380317194], "value": 5374.88616019586, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.2952633024546, 626.0890404461508, 630.4767043354017, 639.0956796133471, 650.158045331296, 32859.67140328596, 43518.0, 44757.328640070424, 44883.77558112213, 44918.77540612301, 45487.0, 45968.3104753429, 45988.0, 46041.0, 46084.53915460844, 8849.557522123883, 8994.68518601849, 9015.864762028576, 9023.503707296097, 9028.548572571379, 8809.779755506132, 8963.775905602346, 8976.685815996441, 8976.730698079067, 8991.0, 8982.461052336881, 9027.503487308195, 9045.3668243223, 9050.954745226298, 9051.185393314601, 8899.73300800977, 8925.241354484877, 8959.686410975584, 8969.551522423886, 8977.192052715254, 8756.299496040301, 8864.911350886538, 8867.512286824222, 8872.46765194084, 8876.20114189726, 42342.78828605868, 42677.35983960243, 42777.0, 42833.0, 42835.0, 3405.6594340565994, 3500.9649903500895, 3636.4908912752235, 3940.9556467536127, 4164.208800327959, 31767.36465270705, 32102.678973210204, 32462.513062304086, 32474.0, 33149.17127071819, 4233.872983810491, 4587.013792034695, 4607.562281583244, 4640.953590464086, 4693.342931989524, 340809.0, 342809.0, 342975.0, 343198.0, 343788.0, 59423.702881486184, 59719.70140149271, 59943.400565994656, 60075.69962150161, 60076.39923600708, 62932.0, 63097.0, 63407.6829615849, 63552.0, 63701.681491593175, 17315.91342043282, 17526.298948041935, 17554.385596504315, 17613.0, 17632.735508967377, 13489.12320699171, 13514.32428378577, 13569.52506662262, 13600.455981760817, 13603.795943060863, 15964.600884977968, 17627.206775695126, 17723.911380443275, 17890.55273618144, 17918.5520361992, 6728.6635668216395, 6764.458843292525, 6846.554973926679, 6853.7943861684225, 6892.655367231619, 941.9670311539063, 1010.141379827141, 1044.0134073300658, 1075.9085477734325, 1099.7745462180235, 31530.684693152776, 31916.361672766878, 31953.52069718956, 32349.730045017666, 32650.67349326524, 1090.7109615951779, 1118.116687816632, 1124.5052177042076, 1170.9004734597484, 1175.1362748379934, 16763.748543771853, 16912.238949247312, 16932.40736574214, 17023.829761702225, 17224.311027558757, 505648.0, 508642.0, 516016.0, 801910.0, 803941.0, 57947.4205257942, 58986.0, 59129.40870591325, 59209.70395147997, 59276.11085833716, 1379.7240551889663, 1397.2873834344557, 1408.1199250468458, 1420.659041829974, 1428.5357258890901, 30800.69199308068, 32657.836710816293, 32987.0, 33003.339933201685, 33613.49579756355, 51927.74036129946, 52290.47709523008, 52462.0, 52496.737516312176, 52500.0, 328138.0, 334147.0, 335364.0, 335486.0, 336053.0, 27750.0, 27891.16326510208, 27966.720332797224, 27968.44063118767, 28212.153635390972, 46063.53936460726, 46767.76616116898, 47048.0, 47656.0, 47715.0, 39064.6093539061, 39852.60147398489, 39926.80036599799, 40511.594884050784, 41457.0, 37916.24167516579, 39846.800765995984, 40456.39315410212, 41297.587024129374, 41685.166296674506, 40163.19673606453, 40585.79707101446, 40764.1847163061, 41452.37821432621, 41521.79239103785, 35429.0, 35781.46327805033, 36478.45282320714, 36581.0, 37097.814510927274, 27106.72893271042, 27410.0, 27478.862605686845, 27609.447811043265, 27739.167824965283], "value": 20201.278372937588, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.0658489991971, 396.4469564956884, 399.3510545363784, 402.23775944585014, 416.4523651398411, 13848.861511384883, 17445.82554174458, 18586.81413185868, 19063.428097157073, 19091.809081909178, 17902.82097179028, 18322.0, 18605.627887442242, 18628.53428664284, 18650.626987460244, 4058.5535591085104, 5184.7407629618465, 5327.840164795043, 5481.725913704318, 5528.75120619573, 4674.298855171717, 6150.200473938382, 6160.630362178261, 6250.187475628162, 6253.906191407133, 6024.2770867495965, 6139.2325959255095, 6178.938210617904, 6207.751689932397, 6218.440340369366, 6125.111858780452, 6174.9073763893575, 6206.7827626033095, 6212.78255261066, 6220.377962203788, 5274.593541619832, 5460.890782184334, 5464.644798088151, 5498.0, 5499.037668408013, 22027.889860550593, 22180.0, 22536.0, 22726.659100113513, 22787.658185127235, 2114.7990940860595, 2224.6440569508964, 2487.0549191307186, 2694.7978901582264, 2942.955855662167, 9908.504574771232, 9915.752106197331, 10050.89949100507, 10299.845502317472, 10510.632127875488, 1880.7743070831589, 1919.424172748184, 1968.3799603125112, 1982.2566537548419, 2021.080408414168, 62010.37989620091, 73932.0, 74554.88167677543, 77060.0, 80126.19873801246, 10894.291871028357, 10951.945240273748, 11132.0, 11203.831942520868, 11212.27120237182, 15465.0, 15557.0, 15558.0, 15577.766333505006, 15605.219739013004, 8036.9953755780525, 8051.355891528664, 8291.58542072894, 8341.8331633367, 8347.0, 7917.445778795429, 7919.20807919215, 8164.877526837102, 8328.33373330132, 8410.705625303084, 4655.95344046562, 4790.736509491941, 4791.592714619227, 4815.662903596784, 4897.314375987365, 1917.769867615895, 1984.593158402524, 1985.8709183903, 1992.8604997650316, 2000.9799902000793, 925.6991477769752, 932.9020452852488, 959.2661613865398, 1010.696790962723, 1103.0734183286079, 18325.266989320546, 18775.0, 18798.248070077316, 18827.529311767314, 18985.335513257247, 944.3436811416, 964.1804466203811, 966.3766870368573, 993.6571882700457, 1002.7192386131894, 10305.330153539995, 10518.684439466828, 10580.735481612868, 10595.470226488645, 10598.735031624117, 462543.0, 471834.0, 474833.0, 485102.0, 485522.0, 69432.0, 76847.61576192194, 81367.59316203381, 89250.0, 89887.55056224809, 1027.6505987964129, 1052.742078190833, 1064.3720205079042, 1070.661672908864, 1073.5759375046812, 16533.33866645369, 16674.505532589246, 16757.0, 16985.0, 17401.56496087608, 42214.788926055175, 42416.0, 42589.787051064544, 42693.78653106715, 43809.78095109504, 58689.5327616813, 59772.70113649578, 60341.21698740667, 60798.69600651968, 61061.08408373967, 8413.95793021031, 8450.0, 8535.487870727777, 8847.823043539222, 8848.601812918563, 12253.877461225273, 12908.483660653468, 13141.211527308393, 13901.0, 14174.0, 11916.34460104668, 12119.636410907688, 12166.209196402206, 12247.101699236633, 12267.263964162179, 11821.172517923662, 11961.162718609614, 12037.699057523281, 12303.261804291771, 12305.446254918372, 12261.754764904474, 12297.692557685772, 12379.62861114132, 12595.93702031484, 12738.299393533445, 8402.369822263232, 8902.198802107841, 8966.0, 9009.324300677607, 9182.17360437564, 11939.76120477603, 12216.877831221575, 12236.0, 12304.840452423101, 12452.937735311265], "value": 9228.146200382518, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.1117629129205, 1519.278342787176, 1539.5689207022035, 1711.3839017953535, 1816.473222765399, 8352.749417517476, 8563.78590535237, 8566.057733649293, 8654.134586541342, 8844.646214151426, 8407.57962101894, 8671.43635663683, 8846.646134154642, 8932.151445612673, 8938.329625278098, 2025.2607798153642, 2305.9423514412106, 2320.1879342230254, 2361.8110551155864, 2391.988040059797, 2359.8112151027963, 2367.6330168823774, 2373.193114341124, 2383.177803657744, 2388.4387169015354, 2305.9192928247517, 2336.9600527765065, 2371.5138396628736, 2374.097842819726, 2375.3942744600104, 2279.9658005129936, 2295.1049090854663, 2314.687517185192, 2315.0623997281127, 2325.186184835311, 2273.943151421228, 2279.7150356205475, 2285.108807565051, 2304.9308520744403, 2309.3880121767743, 8917.108289171096, 8958.552072396355, 8969.461832290084, 8975.147361000694, 8982.775430614221, 5227.686338819683, 5317.601179911483, 5320.787168513255, 5332.520073193431, 5338.225957236179, 3184.2994541200896, 3334.6498617645284, 3360.479125735515, 3383.898483045488, 3393.5079413485155, 980.9019098090205, 991.9305648604601, 1025.9589616415335, 1033.1734612310197, 1046.0899017854515, 12298.446569904376, 12606.936965315115, 12613.432395542222, 12634.0, 12635.93682031584, 4771.880702982453, 4786.16242157621, 4806.158922188603, 4868.659193856396, 4901.852944411673, 10552.261341706007, 10558.788824223473, 10562.841557376645, 10573.788524229627, 10590.993855583707, 5276.498732620472, 5419.566434685215, 5424.783008679608, 5432.483914028161, 5434.538064264502, 4317.481902171759, 4822.614190864722, 4965.553100220925, 5004.799808007639, 5050.267711181931, 4089.27642497954, 4107.753534787863, 4110.979445102816, 4138.52406973202, 4155.355919832431, 1173.7652469506133, 1300.921944683322, 1309.6987692830703, 1311.6261865368515, 1338.1703343926827, 804.2158895077253, 894.0612357025227, 928.475411392561, 946.2146418472652, 978.9804203915882, 6071.150038994544, 6107.350072587851, 6246.906296405557, 6265.780697675651, 6282.0, 499.0592732698889, 499.6876951905059, 501.26564582885516, 509.5694138453001, 657.2441692054081, 4196.223698615766, 4302.09655972243, 4302.290122129813, 4307.117041006649, 4325.675574331937, 10959.561617535368, 11224.214304998572, 11361.14791390649, 11369.658910232707, 11384.487698053608, 11229.943850280859, 11305.604303849326, 11385.430728463543, 11389.829152562717, 11395.316281022999, 7891.0, 8168.305694016075, 8338.541380224025, 8511.95744021276, 8542.658293668308, 6095.2685677718955, 6118.063329601727, 6260.843478912881, 6338.0, 6392.744290228525, 9723.454083412114, 10105.646302379382, 10220.897791021995, 10303.793924121626, 10305.948470257901, 10351.482425878525, 10376.014278643517, 10421.583136674448, 10454.477276136011, 10509.737256568647, 7104.928950710633, 7287.453440991841, 7747.457677962714, 8065.838683226185, 8218.794530136554, 8416.074231834617, 8581.613827377658, 8664.48013119215, 8669.523176225368, 8673.0, 7409.777706668809, 7564.697412103675, 7694.730684426018, 7702.075750909703, 8000.919990800017, 7493.962530187314, 7538.133114691661, 7560.508566943131, 7651.043619547557, 7724.806879828049, 6778.186617605918, 6840.213375461885, 6841.794746157623, 6849.452043836581, 6858.554193977376, 4938.38270216223, 5103.10695628271, 5191.792328306826, 5232.633715639869, 5254.290670759398, 5108.514691104339, 5180.7409629517615, 5213.452587478412, 5231.712255825966, 5348.893022139458], "value": 4601.870935685819, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.56374458225974, 326.55099238546995, 337.04111801923534, 340.16150189782184, 342.88170581149495, 5125.384953805539, 5257.815976440816, 5288.8413347599535, 5292.576593872491, 5324.840254792358, 5201.349831271091, 5240.659357141783, 5246.606504512158, 5268.394134674513, 5274.367075950891, 4165.791710414483, 4169.124483858378, 4169.791510424482, 4205.768682722457, 4209.473815773028, 3930.430087637291, 4126.690498212631, 4130.070734084837, 4177.394277829713, 4181.519125300599, 4067.2678917794933, 4069.2471892699796, 4072.735272207297, 4082.816273267695, 4108.28105081611, 4082.3672330788777, 4090.550039495653, 4108.630223279919, 4117.505899292074, 4119.258533463975, 3444.621091679913, 3631.182983828644, 3634.545681789776, 3636.8181590920613, 3638.4178531434845, 5075.289459475676, 5077.796888124471, 5089.211172268304, 5093.949060509384, 5115.0, 4616.699914505546, 4662.557057079606, 4694.061187762427, 4715.221988371914, 4737.384140061805, 5281.841544753664, 5464.480874316933, 5487.259220005288, 5494.532964697965, 5532.585056120807, 3906.726529142961, 3925.60538071776, 3931.312020396419, 3934.11482416454, 3935.0162459385156, 5462.754176062087, 5490.615656904059, 5693.20295158674, 5701.515371193454, 5706.400827913051, 3809.4476300936763, 3926.2147570485445, 3940.4286378475535, 3950.881473555798, 3963.6630886374405, 3983.2830090583393, 4007.899802504961, 4010.5387880393546, 4045.1505183911117, 4060.4924384451942, 4398.73607583546, 4537.750423726727, 4578.008566273682, 4632.753114739348, 4636.397268355092, 5461.453854614507, 5466.590005749585, 5468.648481153143, 5480.506754392124, 5503.2020357048805, 1367.5555444480385, 1382.5783136143373, 1384.1349156777014, 1386.6256110850215, 1398.6713122416224, 882.9955850220837, 907.6278725722422, 911.0889110889111, 923.5520772425368, 970.9563069661882, 5247.1866860636665, 5279.868003299948, 5303.231031500488, 5307.858810355792, 5324.893502129936, 5580.302462192226, 5608.747606357723, 5616.831495055155, 5618.747156377973, 5638.887222255532, 5046.343975283263, 5202.505761952609, 5218.165093585008, 5260.579153667697, 5278.709670968057, 2969.9851500742357, 2974.8215107093642, 2981.1950773291524, 2992.8653210605576, 3011.7741169412384, 5675.716214189274, 5782.855428614234, 5799.43415277881, 5803.303603567599, 5818.265415254824, 5464.890702186013, 5748.540116790647, 5749.3963133871175, 5750.913736293875, 5782.2483077199695, 5751.338596061507, 5751.827445176651, 5757.165211044459, 5826.737796799154, 5848.444397782204, 5299.49654782795, 5535.0, 5542.584306177134, 5578.63738856973, 5672.829815105553, 5301.23132145829, 5579.4420557945505, 5598.488408129863, 5641.379448260606, 5651.406602306863, 5657.2928383952, 5693.3168019837885, 5695.316562012585, 5705.144228365778, 5736.254286942838, 3978.4629075075654, 4007.358822588372, 4026.91946161081, 4041.2321658884716, 4044.2315959967514, 5388.353397592314, 5624.918780051407, 5625.6498440374835, 5637.661740295595, 5678.3753787084215, 5511.366192887869, 5525.944740552543, 5526.55787536988, 5535.557155427476, 5559.749811258584, 5410.728478807556, 5447.6380904773805, 5485.945140548543, 5495.890082198413, 5534.08687566547, 5137.8201762938115, 5420.593455490933, 5423.701696406735, 5427.6200665953, 5452.26394436746, 5510.586705997146, 5520.819276385725, 5522.66863988162, 5530.281063461724, 5557.249771280987, 5296.629235953446, 5410.540104091197, 5418.349798024262, 5419.214213939035, 5437.4018857926385], "value": 4179.932866712051, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.2586690909819, 793.4445887878486, 802.8394321135767, 811.7929927868392, 862.7843039240192, 14807.925960370196, 15739.685206295882, 15776.921115394436, 15780.684386312267, 15789.763153552705, 14207.78688319676, 14563.126212427262, 14581.562553123396, 14686.118832870035, 14711.55865324042, 655.678717428459, 787.3465024030041, 816.9632366543505, 825.793551612097, 830.8213734047179, 817.7791996161033, 827.8302947895697, 830.8587540118194, 831.476170012891, 831.9558953513332, 858.3991206155684, 859.793649524116, 863.7797361672765, 865.2688478235881, 865.9004214515317, 695.5896021347429, 836.9832603347899, 856.6359297298654, 857.9313654907593, 858.2490320969152, 632.5350867112714, 632.7405763636933, 633.1863555331377, 633.7369991453542, 633.8035209085152, 13787.448502059906, 13795.793063104062, 13807.240601766898, 13842.446302147902, 13851.445942162301, 12717.364131793373, 12888.935555322258, 12961.416736246798, 12968.0, 13043.0, 11110.0, 11558.422078896107, 11757.58848440309, 11899.821502677467, 11940.940295298467, 4374.6062854342945, 4459.197344477992, 4477.798499067517, 4640.67515273931, 4683.953160468386, 56027.0, 56415.153772693855, 56458.717706411204, 56548.0, 56555.717221414045, 10647.30792498485, 10838.07876330505, 10914.23600347968, 11017.063549598213, 11355.282163669805, 18575.535611609546, 18628.0, 18653.0, 18684.06579671011, 18689.252429902925, 8290.626921788535, 8414.410991230678, 8716.389852710376, 8723.432976856484, 8726.432781869158, 4887.65786394956, 4917.754112294442, 4951.628627852925, 4957.182064959314, 5008.023435430129, 3264.771465997357, 3322.7341812655413, 3339.098443420263, 3359.227377703093, 3360.5631267935014, 638.9169407977026, 641.3256719479781, 642.7686033028106, 646.0, 651.4430162211336, 663.8539521305305, 726.4478995963001, 745.6681776609465, 751.4364226829878, 761.6839011810148, 9104.8634270486, 9712.75718107053, 9737.73409456766, 9813.607455701693, 9848.655297064568, 648.7405037984844, 671.2616122265468, 702.7118881258659, 743.0451869347862, 761.417515600566, 4222.577742225752, 4374.168907907551, 4429.180601588717, 4432.853989964455, 4520.389747384127, 58377.416225838046, 61463.692681536304, 61687.383126169065, 65335.346646532926, 66273.0, 58810.41189588049, 59233.40766592365, 59768.40231597628, 61271.38728612657, 62933.37066629367, 49507.75246123674, 53037.734811325696, 55111.44888551143, 55553.0, 55647.0, 9626.90373096288, 9716.854247186156, 9763.218942484442, 9861.359011664506, 10208.591656333663, 35320.0, 36947.63052369442, 37682.62317376791, 38018.0, 38167.2366552673, 45577.77211113923, 50346.49653503418, 51071.489285106676, 51214.74392628013, 52334.73832630813, 13158.736825263633, 13775.931120344334, 13810.240436776074, 13891.0, 13896.861031389557, 14381.352839122055, 14381.85618143847, 14586.0, 14783.186924718813, 15032.624184395478, 12634.936825315815, 12662.683432914251, 12856.742865142458, 13445.394957226752, 13640.590782276548, 13457.730845383234, 13771.242581658105, 13903.930480347533, 13929.58211253664, 13972.790408143683, 13051.673708157372, 13248.668783280109, 13332.200067995951, 13388.93305533466, 13489.797653035408, 11064.336139831636, 11070.560827092426, 11240.0, 11267.507107773567, 11422.143339249424, 10916.672499825017, 11387.43062846871, 11557.24877882935, 11607.76784464323, 11620.5351785931], "value": 7000.193536040169, "unit": "score"}], "name": "dromaeo_css", "value": 7161.769942972919, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 110.0, 112.0, 121.0, 110.0, 111.0, 111.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 124.0, 130.0, 189.0, 129.0, 129.0, 155.0, 131.0, 182.0, 124.0], "value": 146.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 160.0, 158.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 128.0, 75.0, 77.0, 75.0, 74.0, 99.0, 74.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 123.0, 84.0, 82.0, 84.0, 117.0, 84.0, 83.0, 127.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 146.0, 148.0, 146.0, 146.0, 146.0, 145.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 54.0, 55.0, 54.0, 56.0, 53.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 85.0, 81.0, 84.0, 86.0, 81.0, 125.0, 81.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 130.0, 130.0, 117.0, 128.0, 135.0, 126.0, 119.0, 124.0, 130.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 158.0, 168.0, 161.0, 157.0, 159.0, 160.0, 162.0, 159.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 64.0, 65.0, 63.0, 62.0, 66.0, 62.0, 62.0, 61.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1506.2000000000003, "alertThreshold": 2.0}]} 22:29:25 INFO - Return code: 0 22:29:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:29:25 ERROR - # TBPL SUCCESS # 22:29:25 INFO - Running post-action listener: _resource_record_post_action 22:29:25 INFO - ##### 22:29:25 INFO - ##### Finished run-tests step (success) 22:29:25 INFO - ##### 22:29:25 INFO - Running post-run listener: _resource_record_post_run 22:29:25 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 421888; Write bytes: 305659904; Read time: 32; Write time: 188488 22:29:25 INFO - TinderboxPrint: CPU usage
13.1% 22:29:25 INFO - TinderboxPrint: I/O read bytes / time
421,888 / 32 22:29:25 INFO - TinderboxPrint: I/O write bytes / time
305,659,904 / 188,488 22:29:25 INFO - TinderboxPrint: CPU idle
8,261.0 (86.9%) 22:29:25 INFO - TinderboxPrint: CPU user
1,203.2 (12.7%) 22:29:25 INFO - TinderboxPrint: Swap in / out
0 / 0 22:29:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 17362944; Read time: 0; Write time: 9032 22:29:25 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 421888; Write bytes: 288296960; Read time: 32; Write time: 179456 22:29:25 INFO - Running post-run listener: _upload_blobber_files 22:29:25 INFO - Blob upload gear active. 22:29:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:29:25 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 22:29:25 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:29:25 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:29:26 INFO - (blobuploader) - INFO - Open directory for files ... 22:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:29:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:27 INFO - (blobuploader) - INFO - Done attempting. 22:29:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:29:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:31 INFO - (blobuploader) - INFO - Done attempting. 22:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:32 INFO - (blobuploader) - INFO - Done attempting. 22:29:32 INFO - (blobuploader) - INFO - Iteration through files over. 22:29:32 INFO - Return code: 0 22:29:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:29:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:29:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1c06653426dd9eb4b01159abac5486502f1391227d93cea7d9fcf8091f495011612c62024911768a69766e0105e47eaad5c5edbbc705230970c49d0a2c19a47e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e6c9881e07eba14e71c3f02868ec5d405f43ecf9fa020353facdb37a33e7e67b9add7a3297a70e40d303a2995ecfedfc1cca360622d29b4597290498056d628", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b7f179e4ddfe399d592ab88f5ed2106feadb198963ab1ca68ab9357be092f9bcdd1ab7eae1562e81a6ef689b7f1235fbd8aa0f9469d579fa770768c25ee2c8f"} 22:29:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:29:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:29:32 INFO - Contents: 22:29:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1c06653426dd9eb4b01159abac5486502f1391227d93cea7d9fcf8091f495011612c62024911768a69766e0105e47eaad5c5edbbc705230970c49d0a2c19a47e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e6c9881e07eba14e71c3f02868ec5d405f43ecf9fa020353facdb37a33e7e67b9add7a3297a70e40d303a2995ecfedfc1cca360622d29b4597290498056d628", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b7f179e4ddfe399d592ab88f5ed2106feadb198963ab1ca68ab9357be092f9bcdd1ab7eae1562e81a6ef689b7f1235fbd8aa0f9469d579fa770768c25ee2c8f"} 22:29:32 INFO - Running post-run listener: copy_logs_to_upload_dir 22:29:32 INFO - Copying logs to upload dir... 22:29:32 INFO - mkdir: /builds/slave/test/build/upload/logs 22:29:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.844449 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-27 22:29:32.292626) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 22:29:32.295911) ========= 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=00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1c06653426dd9eb4b01159abac5486502f1391227d93cea7d9fcf8091f495011612c62024911768a69766e0105e47eaad5c5edbbc705230970c49d0a2c19a47e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e6c9881e07eba14e71c3f02868ec5d405f43ecf9fa020353facdb37a33e7e67b9add7a3297a70e40d303a2995ecfedfc1cca360622d29b4597290498056d628", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b7f179e4ddfe399d592ab88f5ed2106feadb198963ab1ca68ab9357be092f9bcdd1ab7eae1562e81a6ef689b7f1235fbd8aa0f9469d579fa770768c25ee2c8f"} build_url:https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011237 build_url: 'https://queue.taskcluster.net/v1/task/MJlrBbBlQp6_ccnGpbnodQ/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/Mozilla-Inbound/sha512/1c06653426dd9eb4b01159abac5486502f1391227d93cea7d9fcf8091f495011612c62024911768a69766e0105e47eaad5c5edbbc705230970c49d0a2c19a47e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e6c9881e07eba14e71c3f02868ec5d405f43ecf9fa020353facdb37a33e7e67b9add7a3297a70e40d303a2995ecfedfc1cca360622d29b4597290498056d628", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b7f179e4ddfe399d592ab88f5ed2106feadb198963ab1ca68ab9357be092f9bcdd1ab7eae1562e81a6ef689b7f1235fbd8aa0f9469d579fa770768c25ee2c8f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 22:29:32.323194) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:29:32.323847) ========= 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=00204705a9daa57ed339a427000001b9-1464405259.295420-1831324495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004369 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:29:32.408219) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 22:29:32.408952) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 22:29:32.409641) ========= ========= Total master_lag: 0.19 =========