builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-046 starttime: 1450452577.08 results: success (0) buildid: 20151218061251 builduid: 9ad0afd0d2244636a57668aad804acb3 revision: 36f697d1307c0af010329aad2a0d5f37cad1f67f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.079786) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.080357) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.080660) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006362 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.107176) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.107479) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.123648) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.124119) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004612 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.172886) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.173152) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.173499) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.173778) ========= 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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-18 07:29:37-- 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% 214M=0s 2015-12-18 07:29:37 (214 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.204314 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.390349) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.390641) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018176 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.439821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:37.440125) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 36f697d1307c0af010329aad2a0d5f37cad1f67f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 36f697d1307c0af010329aad2a0d5f37cad1f67f --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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-18 07:29:37,937 truncating revision to first 12 chars 2015-12-18 07:29:37,937 Setting DEBUG logging. 2015-12-18 07:29:37,937 attempt 1/10 2015-12-18 07:29:37,937 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/36f697d1307c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-18 07:29:38,348 unpacking tar archive at: mozilla-central-36f697d1307c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.685225 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:38.137999) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:38.138319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:38.148260) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:38.148529) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 07:29:38.148950) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-18 07:29:38.149236) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:29:38 INFO - MultiFileLogger online at 20151218 07:29:38 in /builds/slave/test 07:29:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:29:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:29:38 INFO - {'append_to_log': False, 07:29:38 INFO - 'base_work_dir': '/builds/slave/test', 07:29:38 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:29:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:29:38 INFO - 'branch': 'Firefox-Non-PGO', 07:29:38 INFO - 'buildbot_json_path': 'buildprops.json', 07:29:38 INFO - 'config_files': ('talos/linux_config.py',), 07:29:38 INFO - 'default_actions': ('clobber', 07:29:38 INFO - 'read-buildbot-config', 07:29:38 INFO - 'download-and-extract', 07:29:38 INFO - 'populate-webroot', 07:29:38 INFO - 'create-virtualenv', 07:29:38 INFO - 'install', 07:29:38 INFO - 'run-tests'), 07:29:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:29:38 INFO - 'download_minidump_stackwalk': True, 07:29:38 INFO - 'download_symbols': 'ondemand', 07:29:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:29:38 INFO - 'tooltool.py': '/tools/tooltool.py', 07:29:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:29:38 INFO - '/tools/misc-python/virtualenv.py')}, 07:29:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:29:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:29:38 INFO - 'installer_path': 'installer.exe', 07:29:38 INFO - 'log_level': 'info', 07:29:38 INFO - 'log_name': 'talos', 07:29:38 INFO - 'log_to_console': True, 07:29:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:29:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:29:38 INFO - 'opt_config_files': (), 07:29:38 INFO - 'pip_index': False, 07:29:38 INFO - 'sps_profile': False, 07:29:38 INFO - 'sps_profile_interval': 0, 07:29:38 INFO - 'suite': 'dromaeojs', 07:29:38 INFO - 'system_bits': '64', 07:29:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:29:38 INFO - 'title': 'talos-linux64-ix-046', 07:29:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:29:38 INFO - 'use_talos_json': True, 07:29:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:29:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:29:38 INFO - 'work_dir': 'build'} 07:29:38 INFO - ##### 07:29:38 INFO - ##### Running clobber step. 07:29:38 INFO - ##### 07:29:38 INFO - Running pre-action listener: _resource_record_pre_action 07:29:38 INFO - Running main action method: clobber 07:29:38 INFO - rmtree: /builds/slave/test/build 07:29:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:29:39 INFO - Running post-action listener: _resource_record_post_action 07:29:39 INFO - ##### 07:29:39 INFO - ##### Running read-buildbot-config step. 07:29:39 INFO - ##### 07:29:39 INFO - Running pre-action listener: _resource_record_pre_action 07:29:39 INFO - Running main action method: read_buildbot_config 07:29:39 INFO - Using buildbot properties: 07:29:39 INFO - { 07:29:39 INFO - "project": "", 07:29:39 INFO - "product": "firefox", 07:29:39 INFO - "script_repo_revision": "production", 07:29:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:29:39 INFO - "repository": "", 07:29:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:29:39 INFO - "buildid": "20151218061251", 07:29:39 INFO - "pgo_build": "False", 07:29:39 INFO - "basedir": "/builds/slave/test", 07:29:39 INFO - "buildnumber": 244, 07:29:39 INFO - "slavename": "talos-linux64-ix-046", 07:29:39 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:29:39 INFO - "platform": "ubuntu64_hw", 07:29:39 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:29:39 INFO - "branch": "mozilla-central", 07:29:39 INFO - "repo_path": "mozilla-central", 07:29:39 INFO - "revision": "36f697d1307c0af010329aad2a0d5f37cad1f67f", 07:29:39 INFO - "stage_platform": "linux64", 07:29:39 INFO - "builduid": "9ad0afd0d2244636a57668aad804acb3", 07:29:39 INFO - "slavebuilddir": "test" 07:29:39 INFO - } 07:29:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 07:29:39 INFO - Running post-action listener: _resource_record_post_action 07:29:39 INFO - ##### 07:29:39 INFO - ##### Running download-and-extract step. 07:29:39 INFO - ##### 07:29:39 INFO - Running pre-action listener: _resource_record_pre_action 07:29:39 INFO - Running main action method: download_and_extract 07:29:39 INFO - mkdir: /builds/slave/test/build/tests 07:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:39 INFO - https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:29:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/test_packages.json 07:29:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/test_packages.json 07:29:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 07:29:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 07:29:39 INFO - Downloaded 1302 bytes. 07:29:39 INFO - Reading from file /builds/slave/test/build/test_packages.json 07:29:39 INFO - Using the following test package requirements: 07:29:39 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:29:39 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:29:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:29:39 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:29:39 INFO - u'jsshell-linux-x86_64.zip'], 07:29:39 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:29:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:29:39 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:29:39 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:29:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:29:39 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:29:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:29:39 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:29:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:29:39 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:29:39 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:29:39 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:29:39 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:39 INFO - https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:29:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:29:41 INFO - Downloaded 21870817 bytes. 07:29:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 07:29:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:29:42 INFO - Return code: 0 07:29:42 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:42 INFO - https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:29:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:29:45 INFO - Downloaded 21870817 bytes. 07:29:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 07:29:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:29:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:29:46 INFO - Return code: 0 07:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:46 INFO - https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:29:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:29:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:29:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:29:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:29:48 INFO - Downloaded 11132039 bytes. 07:29:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 07:29:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:29:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 07:29:48 INFO - Return code: 0 07:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:48 INFO - https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:29:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:29:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:29:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:29:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:29:51 INFO - Downloaded 53020262 bytes. 07:29:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:29:51 INFO - mkdir: /builds/slave/test/properties 07:29:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:29:51 INFO - Writing to file /builds/slave/test/properties/build_url 07:29:51 INFO - Contents: 07:29:51 INFO - build_url:https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:29:51 INFO - Running post-action listener: _resource_record_post_action 07:29:51 INFO - Running post-action listener: set_extra_try_arguments 07:29:51 INFO - ##### 07:29:51 INFO - ##### Running populate-webroot step. 07:29:51 INFO - ##### 07:29:51 INFO - Running pre-action listener: _resource_record_pre_action 07:29:51 INFO - Running main action method: populate_webroot 07:29:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:29:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:29:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:29:51 INFO - '--tppagecycles', 07:29:51 INFO - '7'], 07:29:51 INFO - 'tests': ['tsvgm']}}, 07:29:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:29:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:29:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:29:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:29:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:29:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:29:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'tests': ['damp', 'tps']}, 07:29:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['damp', 'tps']}, 07:29:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:29:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:29:51 INFO - 'other': {'tests': ['a11yr', 07:29:51 INFO - 'ts_paint', 07:29:51 INFO - 'tpaint', 07:29:51 INFO - 'sessionrestore', 07:29:51 INFO - 'sessionrestore_no_auto_restore']}, 07:29:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['a11yr', 07:29:51 INFO - 'ts_paint', 07:29:51 INFO - 'tpaint', 07:29:51 INFO - 'sessionrestore', 07:29:51 INFO - 'sessionrestore_no_auto_restore']}, 07:29:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['a11yr', 07:29:51 INFO - 'ts_paint', 07:29:51 INFO - 'tpaint', 07:29:51 INFO - 'sessionrestore', 07:29:51 INFO - 'sessionrestore_no_auto_restore']}, 07:29:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['a11yr', 07:29:51 INFO - 'ts_paint', 07:29:51 INFO - 'tpaint', 07:29:51 INFO - 'sessionrestore', 07:29:51 INFO - 'sessionrestore_no_auto_restore']}, 07:29:51 INFO - 'other_l64': {'tests': ['a11yr', 07:29:51 INFO - 'ts_paint', 07:29:51 INFO - 'tpaint', 07:29:51 INFO - 'sessionrestore', 07:29:51 INFO - 'sessionrestore_no_auto_restore']}, 07:29:51 INFO - 'other_nol64': {'tests': ['a11yr', 07:29:51 INFO - 'ts_paint', 07:29:51 INFO - 'tpaint', 07:29:51 INFO - 'sessionrestore', 07:29:51 INFO - 'sessionrestore_no_auto_restore']}, 07:29:51 INFO - 'svgr': {'tests': ['tsvgx', 07:29:51 INFO - 'tsvgr_opacity', 07:29:51 INFO - 'tart', 07:29:51 INFO - 'tscrollx', 07:29:51 INFO - 'cart']}, 07:29:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['tsvgx', 07:29:51 INFO - 'tsvgr_opacity', 07:29:51 INFO - 'tart', 07:29:51 INFO - 'tscrollx', 07:29:51 INFO - 'cart']}, 07:29:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'tests': ['tp5o']}, 07:29:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'talos_options': ['--e10s'], 07:29:51 INFO - 'tests': ['tp5o']}, 07:29:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'talos_options': ['--xperf_path', 07:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:29:51 INFO - 'tests': ['tp5n']}, 07:29:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:29:51 INFO - 'talos_options': ['--e10s', 07:29:51 INFO - '--xperf_path', 07:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:29:51 INFO - 'tests': ['tp5n']}}, 07:29:51 INFO - 'talos.zip': {'path': '', 07:29:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:29:51 INFO - Running post-action listener: _resource_record_post_action 07:29:51 INFO - ##### 07:29:51 INFO - ##### Running create-virtualenv step. 07:29:51 INFO - ##### 07:29:51 INFO - Running pre-action listener: _resource_record_pre_action 07:29:51 INFO - Running main action method: create_virtualenv 07:29:51 INFO - Creating virtualenv /builds/slave/test/build/venv 07:29:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:29:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:29:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:29:52 INFO - Using real prefix '/usr' 07:29:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:29:52 INFO - Installing distribute.............................................................................................................................................................................................done. 07:29:54 INFO - Installing pip.................done. 07:29:54 INFO - Return code: 0 07:29:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:29:54 INFO - Using env: {'DISPLAY': ':0', 07:29:54 INFO - 'HOME': '/home/cltbld', 07:29:54 INFO - 'LANG': 'en_US.UTF-8', 07:29:54 INFO - 'LANGUAGE': 'en_US:en', 07:29:54 INFO - 'LOGNAME': 'cltbld', 07:29:54 INFO - 'MAIL': '/var/mail/cltbld', 07:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:54 INFO - 'MOZ_NO_REMOTE': '1', 07:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:29:54 INFO - 'NO_EM_RESTART': '1', 07:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:54 INFO - 'PWD': '/builds/slave/test', 07:29:54 INFO - 'SHELL': '/bin/bash', 07:29:54 INFO - 'SHLVL': '1', 07:29:54 INFO - 'TERM': 'linux', 07:29:54 INFO - 'TMOUT': '86400', 07:29:54 INFO - 'USER': 'cltbld', 07:29:54 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:54 INFO - '_': '/tools/buildbot/bin/python'} 07:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:54 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 07:29:54 INFO - Cleaning up... 07:29:54 INFO - Return code: 0 07:29:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test/build 07:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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>=0.7.1 07:29:54 INFO - Using env: {'DISPLAY': ':0', 07:29:54 INFO - 'HOME': '/home/cltbld', 07:29:54 INFO - 'LANG': 'en_US.UTF-8', 07:29:54 INFO - 'LANGUAGE': 'en_US:en', 07:29:54 INFO - 'LOGNAME': 'cltbld', 07:29:54 INFO - 'MAIL': '/var/mail/cltbld', 07:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:54 INFO - 'MOZ_NO_REMOTE': '1', 07:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:29:54 INFO - 'NO_EM_RESTART': '1', 07:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:54 INFO - 'PWD': '/builds/slave/test', 07:29:54 INFO - 'SHELL': '/bin/bash', 07:29:54 INFO - 'SHLVL': '1', 07:29:54 INFO - 'TERM': 'linux', 07:29:54 INFO - 'TMOUT': '86400', 07:29:54 INFO - 'USER': 'cltbld', 07:29:54 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:54 INFO - '_': '/tools/buildbot/bin/python'} 07:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:54 INFO - Downloading/unpacking psutil>=0.7.1 07:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:56 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 07:29:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:29:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:29:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:29:56 INFO - Installing collected packages: psutil 07:29:56 INFO - Running setup.py install for psutil 07:29:56 INFO - building 'psutil._psutil_linux' extension 07:29:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:29:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:29:56 INFO - building 'psutil._psutil_posix' extension 07:29:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:29:57 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 07:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:29:57 INFO - Successfully installed psutil 07:29:57 INFO - Cleaning up... 07:29:57 INFO - Return code: 0 07:29:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:29:57 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')]} 07:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:57 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')]} 07:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:29:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0'] in /builds/slave/test/build 07:29:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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.0.0 07:29:57 INFO - Using env: {'DISPLAY': ':0', 07:29:57 INFO - 'HOME': '/home/cltbld', 07:29:57 INFO - 'LANG': 'en_US.UTF-8', 07:29:57 INFO - 'LANGUAGE': 'en_US:en', 07:29:57 INFO - 'LOGNAME': 'cltbld', 07:29:57 INFO - 'MAIL': '/var/mail/cltbld', 07:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:57 INFO - 'MOZ_NO_REMOTE': '1', 07:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:29:57 INFO - 'NO_EM_RESTART': '1', 07:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:57 INFO - 'PWD': '/builds/slave/test', 07:29:57 INFO - 'SHELL': '/bin/bash', 07:29:57 INFO - 'SHLVL': '1', 07:29:57 INFO - 'TERM': 'linux', 07:29:57 INFO - 'TMOUT': '86400', 07:29:57 INFO - 'USER': 'cltbld', 07:29:57 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:57 INFO - '_': '/tools/buildbot/bin/python'} 07:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:29:57 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 07:29:57 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 07:29:57 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 07:29:57 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 07:29:57 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 07:29:57 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 07:29:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:29:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:29:59 INFO - Installing collected packages: mozsystemmonitor 07:29:59 INFO - Running setup.py install for mozsystemmonitor 07:29:59 INFO - Successfully installed mozsystemmonitor 07:29:59 INFO - Cleaning up... 07:29:59 INFO - Return code: 0 07:29:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:29: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')]} 07:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29: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')]} 07:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:29:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:29:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:29:59 INFO - Using env: {'DISPLAY': ':0', 07:29:59 INFO - 'HOME': '/home/cltbld', 07:29:59 INFO - 'LANG': 'en_US.UTF-8', 07:29:59 INFO - 'LANGUAGE': 'en_US:en', 07:29:59 INFO - 'LOGNAME': 'cltbld', 07:29:59 INFO - 'MAIL': '/var/mail/cltbld', 07:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:59 INFO - 'MOZ_NO_REMOTE': '1', 07:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:29:59 INFO - 'NO_EM_RESTART': '1', 07:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:59 INFO - 'PWD': '/builds/slave/test', 07:29:59 INFO - 'SHELL': '/bin/bash', 07:29:59 INFO - 'SHLVL': '1', 07:29:59 INFO - 'TERM': 'linux', 07:29:59 INFO - 'TMOUT': '86400', 07:29:59 INFO - 'USER': 'cltbld', 07:29:59 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:59 INFO - '_': '/tools/buildbot/bin/python'} 07:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:59 INFO - Downloading/unpacking blobuploader==1.2.4 07:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:01 INFO - Downloading blobuploader-1.2.4.tar.gz 07:30:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:30:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:30:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:01 INFO - Downloading docopt-0.6.1.tar.gz 07:30:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:30:01 INFO - Installing collected packages: blobuploader, requests, docopt 07:30:01 INFO - Running setup.py install for blobuploader 07:30:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:30:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:30:02 INFO - Running setup.py install for requests 07:30:02 INFO - Running setup.py install for docopt 07:30:02 INFO - Successfully installed blobuploader requests docopt 07:30:02 INFO - Cleaning up... 07:30:02 INFO - Return code: 0 07:30:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 07:30:02 INFO - Using env: {'DISPLAY': ':0', 07:30:02 INFO - 'HOME': '/home/cltbld', 07:30:02 INFO - 'LANG': 'en_US.UTF-8', 07:30:02 INFO - 'LANGUAGE': 'en_US:en', 07:30:02 INFO - 'LOGNAME': 'cltbld', 07:30:02 INFO - 'MAIL': '/var/mail/cltbld', 07:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:02 INFO - 'MOZ_NO_REMOTE': '1', 07:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:02 INFO - 'NO_EM_RESTART': '1', 07:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:02 INFO - 'PWD': '/builds/slave/test', 07:30:02 INFO - 'SHELL': '/bin/bash', 07:30:02 INFO - 'SHLVL': '1', 07:30:02 INFO - 'TERM': 'linux', 07:30:02 INFO - 'TMOUT': '86400', 07:30:02 INFO - 'USER': 'cltbld', 07:30:02 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:02 INFO - '_': '/tools/buildbot/bin/python'} 07:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:30:02 INFO - Running setup.py (path:/tmp/pip-_I3q71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:30:02 INFO - Running setup.py (path:/tmp/pip-YoPkNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:30:02 INFO - Running setup.py (path:/tmp/pip-9ouhSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:30:02 INFO - Running setup.py (path:/tmp/pip-VCvc9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:30:03 INFO - Running setup.py (path:/tmp/pip-Kf7WFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:30:03 INFO - Running setup.py (path:/tmp/pip-BLK7SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:30:03 INFO - Running setup.py (path:/tmp/pip-VpKw7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:30:03 INFO - Running setup.py (path:/tmp/pip-ek3Q_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:30:03 INFO - Running setup.py (path:/tmp/pip-lQeuut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:30:03 INFO - Running setup.py (path:/tmp/pip-SdvO52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:30:03 INFO - Running setup.py (path:/tmp/pip-BplC95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:30:03 INFO - Running setup.py (path:/tmp/pip-xOM81K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:30:03 INFO - Running setup.py (path:/tmp/pip-dCo9RT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:30:03 INFO - Running setup.py (path:/tmp/pip-CQXAGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:03 INFO - Running setup.py (path:/tmp/pip-LBGOjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:30:03 INFO - Running setup.py (path:/tmp/pip-EaaEVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:30:03 INFO - Running setup.py (path:/tmp/pip-gzzdVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:30:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:30:03 INFO - Running setup.py install for manifestparser 07:30:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Running setup.py install for mozcrash 07:30:04 INFO - Running setup.py install for mozdebug 07:30:04 INFO - Running setup.py install for mozdevice 07:30:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Running setup.py install for mozfile 07:30:04 INFO - Running setup.py install for mozhttpd 07:30:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Running setup.py install for mozinfo 07:30:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Running setup.py install for mozInstall 07:30:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:30:04 INFO - Running setup.py install for mozleak 07:30:04 INFO - Running setup.py install for mozlog 07:30:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:30:05 INFO - Running setup.py install for moznetwork 07:30:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:30:05 INFO - Running setup.py install for mozprocess 07:30:05 INFO - Running setup.py install for mozprofile 07:30:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:30:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:30:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:30:05 INFO - Running setup.py install for mozrunner 07:30:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:30:05 INFO - Running setup.py install for mozscreenshot 07:30:05 INFO - Running setup.py install for moztest 07:30:05 INFO - Running setup.py install for mozversion 07:30:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:30:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:30:05 INFO - Cleaning up... 07:30:05 INFO - Return code: 0 07:30:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30: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')]} 07:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:30:05 INFO - Using env: {'DISPLAY': ':0', 07:30:05 INFO - 'HOME': '/home/cltbld', 07:30:05 INFO - 'LANG': 'en_US.UTF-8', 07:30:05 INFO - 'LANGUAGE': 'en_US:en', 07:30:05 INFO - 'LOGNAME': 'cltbld', 07:30:05 INFO - 'MAIL': '/var/mail/cltbld', 07:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:05 INFO - 'MOZ_NO_REMOTE': '1', 07:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:05 INFO - 'NO_EM_RESTART': '1', 07:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:05 INFO - 'PWD': '/builds/slave/test', 07:30:05 INFO - 'SHELL': '/bin/bash', 07:30:05 INFO - 'SHLVL': '1', 07:30:05 INFO - 'TERM': 'linux', 07:30:05 INFO - 'TMOUT': '86400', 07:30:05 INFO - 'USER': 'cltbld', 07:30:05 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:05 INFO - '_': '/tools/buildbot/bin/python'} 07:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:30:05 INFO - Running setup.py (path:/tmp/pip-d9ZkCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:30:06 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)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:30:06 INFO - Running setup.py (path:/tmp/pip-csIiBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:30:06 INFO - Running setup.py (path:/tmp/pip-0Y3g6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:30:06 INFO - Running setup.py (path:/tmp/pip-znCgrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:30:06 INFO - Running setup.py (path:/tmp/pip-ecXEuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:30:06 INFO - Running setup.py (path:/tmp/pip-zNQl0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:30:06 INFO - Running setup.py (path:/tmp/pip-NU453L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:30:06 INFO - Running setup.py (path:/tmp/pip-ju0QwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:30:06 INFO - Running setup.py (path:/tmp/pip-lT99MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:30:06 INFO - Running setup.py (path:/tmp/pip-U8SL5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:30:06 INFO - Running setup.py (path:/tmp/pip-Qqc0tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:30:06 INFO - Running setup.py (path:/tmp/pip-9IJ5zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:30:06 INFO - Running setup.py (path:/tmp/pip-5QqWtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:30:06 INFO - Running setup.py (path:/tmp/pip-CDQMpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:07 INFO - Running setup.py (path:/tmp/pip-E5nKbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:07 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)) 07:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:30:07 INFO - Running setup.py (path:/tmp/pip-Zh2D9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:30:07 INFO - Running setup.py (path:/tmp/pip-ok0RhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:30:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:08 INFO - Downloading blessings-1.5.1.tar.gz 07:30:08 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 07:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:30:08 INFO - Installing collected packages: blessings 07:30:08 INFO - Running setup.py install for blessings 07:30:09 INFO - Successfully installed blessings 07:30:09 INFO - Cleaning up... 07:30:09 INFO - Return code: 0 07:30:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:30:09 INFO - Reading from file tmpfile_stdout 07:30:09 INFO - Current package versions: 07:30:09 INFO - argparse == 1.2.1 07:30:09 INFO - blessings == 1.5.1 07:30:09 INFO - blobuploader == 1.2.4 07:30:09 INFO - docopt == 0.6.1 07:30:09 INFO - manifestparser == 1.1 07:30:09 INFO - mozInstall == 1.12 07:30:09 INFO - mozcrash == 0.16 07:30:09 INFO - mozdebug == 0.1 07:30:09 INFO - mozdevice == 0.47 07:30:09 INFO - mozfile == 1.2 07:30:09 INFO - mozhttpd == 0.7 07:30:09 INFO - mozinfo == 0.9 07:30:09 INFO - mozleak == 0.1 07:30:09 INFO - mozlog == 3.1 07:30:09 INFO - moznetwork == 0.27 07:30:09 INFO - mozprocess == 0.22 07:30:09 INFO - mozprofile == 0.27 07:30:09 INFO - mozrunner == 6.11 07:30:09 INFO - mozscreenshot == 0.1 07:30:09 INFO - mozsystemmonitor == 0.0 07:30:09 INFO - moztest == 0.7 07:30:09 INFO - mozversion == 1.4 07:30:09 INFO - psutil == 3.1.1 07:30:09 INFO - requests == 1.2.3 07:30:09 INFO - wsgiref == 0.1.2 07:30:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:30:09 INFO - Using env: {'DISPLAY': ':0', 07:30:09 INFO - 'HOME': '/home/cltbld', 07:30:09 INFO - 'LANG': 'en_US.UTF-8', 07:30:09 INFO - 'LANGUAGE': 'en_US:en', 07:30:09 INFO - 'LOGNAME': 'cltbld', 07:30:09 INFO - 'MAIL': '/var/mail/cltbld', 07:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:09 INFO - 'MOZ_NO_REMOTE': '1', 07:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:09 INFO - 'NO_EM_RESTART': '1', 07:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:09 INFO - 'PWD': '/builds/slave/test', 07:30:09 INFO - 'SHELL': '/bin/bash', 07:30:09 INFO - 'SHLVL': '1', 07:30:09 INFO - 'TERM': 'linux', 07:30:09 INFO - 'TMOUT': '86400', 07:30:09 INFO - 'USER': 'cltbld', 07:30:09 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:09 INFO - '_': '/tools/buildbot/bin/python'} 07:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:30:09 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:30:09 INFO - Cleaning up... 07:30:09 INFO - Return code: 0 07:30:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:30:09 INFO - Using env: {'DISPLAY': ':0', 07:30:09 INFO - 'HOME': '/home/cltbld', 07:30:09 INFO - 'LANG': 'en_US.UTF-8', 07:30:09 INFO - 'LANGUAGE': 'en_US:en', 07:30:09 INFO - 'LOGNAME': 'cltbld', 07:30:09 INFO - 'MAIL': '/var/mail/cltbld', 07:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:09 INFO - 'MOZ_NO_REMOTE': '1', 07:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:09 INFO - 'NO_EM_RESTART': '1', 07:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:09 INFO - 'PWD': '/builds/slave/test', 07:30:09 INFO - 'SHELL': '/bin/bash', 07:30:09 INFO - 'SHLVL': '1', 07:30:09 INFO - 'TERM': 'linux', 07:30:09 INFO - 'TMOUT': '86400', 07:30:09 INFO - 'USER': 'cltbld', 07:30:09 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:09 INFO - '_': '/tools/buildbot/bin/python'} 07:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:09 INFO - Downloading/unpacking jsonschema 07:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:30:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 07:30:11 INFO - Downloading/unpacking functools32 (from jsonschema) 07:30: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 07:30: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 07:30: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 07:30: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 07:30: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 07:30: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 07:30:11 INFO - Downloading functools32-3.2.3-2.tar.gz 07:30:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 07:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:30:11 INFO - warning: no files found matching '*.txt' 07:30:11 INFO - no previously-included directories found matching 'build' 07:30:11 INFO - no previously-included directories found matching 'dist' 07:30:11 INFO - no previously-included directories found matching '.git*' 07:30:11 INFO - Installing collected packages: jsonschema, functools32 07:30:11 INFO - Running setup.py install for functools32 07:30:11 INFO - warning: no files found matching '*.txt' 07:30:11 INFO - no previously-included directories found matching 'build' 07:30:11 INFO - no previously-included directories found matching 'dist' 07:30:11 INFO - no previously-included directories found matching '.git*' 07:30:11 INFO - Successfully installed jsonschema functools32 07:30:11 INFO - Cleaning up... 07:30:11 INFO - Return code: 0 07:30:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30: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')]} 07:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7faeab1ebf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c43240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', '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 07:30:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:30:11 INFO - Using env: {'DISPLAY': ':0', 07:30:11 INFO - 'HOME': '/home/cltbld', 07:30:11 INFO - 'LANG': 'en_US.UTF-8', 07:30:11 INFO - 'LANGUAGE': 'en_US:en', 07:30:11 INFO - 'LOGNAME': 'cltbld', 07:30:11 INFO - 'MAIL': '/var/mail/cltbld', 07:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:11 INFO - 'MOZ_NO_REMOTE': '1', 07:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:11 INFO - 'NO_EM_RESTART': '1', 07:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:11 INFO - 'PWD': '/builds/slave/test', 07:30:11 INFO - 'SHELL': '/bin/bash', 07:30:11 INFO - 'SHLVL': '1', 07:30:11 INFO - 'TERM': 'linux', 07:30:11 INFO - 'TMOUT': '86400', 07:30:11 INFO - 'USER': 'cltbld', 07:30:11 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:11 INFO - '_': '/tools/buildbot/bin/python'} 07:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:12 INFO - Downloading/unpacking flake8 07:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:30:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 07:30:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:30:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 07:30:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:30: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 07:30: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 07:30: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 07:30: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 07:30: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 07:30: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 07:30:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 07:30:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:30: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 07:30: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 07:30: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 07:30: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 07:30: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 07:30: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 07:30:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 07:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:30:14 INFO - no previously-included directories found matching 'docs/_build' 07:30:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:30:14 INFO - Running setup.py install for pep8 07:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:30:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:30:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:30:14 INFO - no previously-included directories found matching 'docs/_build' 07:30:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:30:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:30:14 INFO - Cleaning up... 07:30:14 INFO - Return code: 0 07:30:14 INFO - Running post-action listener: _resource_record_post_action 07:30:14 INFO - Running post-action listener: _start_resource_monitoring 07:30:14 INFO - Starting resource monitoring. 07:30:14 INFO - ##### 07:30:14 INFO - ##### Running install step. 07:30:14 INFO - ##### 07:30:14 INFO - Running pre-action listener: _resource_record_pre_action 07:30:14 INFO - Running main action method: install 07:30:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:30:14 INFO - Reading from file tmpfile_stdout 07:30:14 INFO - Detecting whether we're running mozinstall >=1.0... 07:30:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:30:15 INFO - Reading from file tmpfile_stdout 07:30:15 INFO - Output received: 07:30:15 INFO - Usage: mozinstall [options] installer 07:30:15 INFO - Options: 07:30:15 INFO - -h, --help show this help message and exit 07:30:15 INFO - -d DEST, --destination=DEST 07:30:15 INFO - Directory to install application into. [default: 07:30:15 INFO - "/builds/slave/test"] 07:30:15 INFO - --app=APP Application being installed. [default: firefox] 07:30:15 INFO - mkdir: /builds/slave/test/build/application 07:30:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:30:30 INFO - Reading from file tmpfile_stdout 07:30:30 INFO - Output received: 07:30:30 INFO - /builds/slave/test/build/application/firefox/firefox 07:30:30 INFO - Running post-action listener: _resource_record_post_action 07:30:30 INFO - ##### 07:30:30 INFO - ##### Running run-tests step. 07:30:30 INFO - ##### 07:30:30 INFO - Running pre-action listener: _resource_record_pre_action 07:30:30 INFO - Running main action method: run_tests 07:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:30:30 INFO - Python 2.7.3 07:30:30 INFO - Return code: 0 07:30:30 INFO - grabbing minidump binary from tooltool 07:30: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')]} 07:30:30 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd20b0>, '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 0x2cd2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd3440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:30:30 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 07:30:30 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 07:30:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:30:30 INFO - Return code: 0 07:30:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:30:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:30:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:30:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:30:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:30:31 INFO - Return code: 0 07:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test/build 07:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 07:30:31 INFO - Using env: {'DISPLAY': ':0', 07:30:31 INFO - 'HOME': '/home/cltbld', 07:30:31 INFO - 'LANG': 'en_US.UTF-8', 07:30:31 INFO - 'LANGUAGE': 'en_US:en', 07:30:31 INFO - 'LOGNAME': 'cltbld', 07:30:31 INFO - 'MAIL': '/var/mail/cltbld', 07:30:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:30:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:31 INFO - 'MOZ_NO_REMOTE': '1', 07:30:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:31 INFO - 'NO_EM_RESTART': '1', 07:30:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:31 INFO - 'PWD': '/builds/slave/test', 07:30:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:30:31 INFO - 'SHELL': '/bin/bash', 07:30:31 INFO - 'SHLVL': '1', 07:30:31 INFO - 'TERM': 'linux', 07:30:31 INFO - 'TMOUT': '86400', 07:30:31 INFO - 'USER': 'cltbld', 07:30:31 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545', 07:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:31 INFO - '_': '/tools/buildbot/bin/python'} 07:30:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 07:30:31 INFO - mozversion INFO | application_buildid: 20151218061251 07:30:31 INFO - mozversion INFO | application_changeset: 36f697d1307c0af010329aad2a0d5f37cad1f67f 07:30:31 INFO - mozversion INFO | application_display_name: Nightly 07:30:31 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:30:31 INFO - mozversion INFO | application_name: Firefox 07:30:31 INFO - mozversion INFO | application_remotingname: firefox 07:30:31 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 07:30:31 INFO - mozversion INFO | application_vendor: Mozilla 07:30:31 INFO - mozversion INFO | application_version: 46.0a1 07:30:31 INFO - mozversion INFO | platform_buildid: 20151218061251 07:30:31 INFO - mozversion INFO | platform_changeset: 36f697d1307c0af010329aad2a0d5f37cad1f67f 07:30:31 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 07:30:31 INFO - mozversion INFO | platform_version: 46.0a1 07:30:31 INFO - 2015-12-18 07:30:31,666 DEBUG : using testdate: 1450452631 07:30:31 INFO - 2015-12-18 07:30:31,666 DEBUG : actual date: 1450452631 07:30:31 INFO - 2015-12-18 07:30:31,750 INFO : starting webserver on 'localhost:54173' 07:30:31 INFO - 2015-12-18 07:30:31,751 INFO : Starting test suite talos-linux64-ix-046 07:30:31 INFO - 2015-12-18 07:30:31,751 INFO : Starting test dromaeo_css 07:30:31 INFO - 2015-12-18 07:30:31,751 DEBUG : operating with platform_type : linux_ 07:30:31 INFO - 2015-12-18 07:30:31,752 INFO : Initialising browser for dromaeo_css test... 07:30:31 INFO - 2015-12-18 07:30:31,764 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOwHYYE/profile http://localhost:54173/getInfo.html 07:30:35 INFO - 2015-12-18 07:30:35,988 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:30:35 INFO - 2015-12-18 07:30:35,989 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:30:35 INFO - 2015-12-18 07:30:35,989 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:30:35 INFO - 2015-12-18 07:30:35,989 DEBUG : BROWSER_OUTPUT: __metrics 07:30:36 INFO - 2015-12-18 07:30:36,045 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 07:30:36 INFO - 2015-12-18 07:30:36,121 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:30:37 INFO - 2015-12-18 07:30:37,032 INFO : Browser initialized. 07:30:37 INFO - 2015-12-18 07:30:37,033 INFO : Running cycle 1/1 for dromaeo_css test... 07:30:37 INFO - 2015-12-18 07:30:37,033 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOwHYYE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:30:48 INFO - 2015-12-18 07:30:48,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 149622784 07:30:48 INFO - 2015-12-18 07:30:48,997 DEBUG : BROWSER_OUTPUT: 07:31:40 INFO - 2015-12-18 07:31:40,521 DEBUG : BROWSER_OUTPUT: 1450452700518 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] 07:33:52 INFO - 2015-12-18 07:33:52,469 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54173/tests/dromaeo/cssquery-ext.html) 07:33:53 INFO - 2015-12-18 07:33:53,909 DEBUG : BROWSER_OUTPUT: RSS: Main: 298110976 07:33:53 INFO - 2015-12-18 07:33:53,910 DEBUG : BROWSER_OUTPUT: 07:36:56 INFO - 2015-12-18 07:36:56,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/dromaeo/cssquery-ext.html (next: http://localhost:54173/tests/dromaeo/cssquery-jquery.html) 07:36:57 INFO - 2015-12-18 07:36:57,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 231067648 07:36:57 INFO - 2015-12-18 07:36:57,082 DEBUG : BROWSER_OUTPUT: 07:39:59 INFO - 2015-12-18 07:39:59,061 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54173/tests/dromaeo/cssquery-mootools.html) 07:39:59 INFO - 2015-12-18 07:39:59,635 DEBUG : BROWSER_OUTPUT: RSS: Main: 222273536 07:39:59 INFO - 2015-12-18 07:39:59,635 DEBUG : BROWSER_OUTPUT: 07:43:01 INFO - 2015-12-18 07:43:01,618 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54173/tests/dromaeo/cssquery-prototype.html) 07:43:02 INFO - 2015-12-18 07:43:02,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 212836352 07:43:02 INFO - 2015-12-18 07:43:02,220 DEBUG : BROWSER_OUTPUT: 07:46:04 INFO - 2015-12-18 07:46:04,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54173/tests/dromaeo/cssquery-yui.html) 07:46:05 INFO - 2015-12-18 07:46:05,486 DEBUG : BROWSER_OUTPUT: RSS: Main: 215216128 07:46:05 INFO - 2015-12-18 07:46:05,486 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,248 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/dromaeo/cssquery-yui.html (next: http://localhost:54173/tests/dromaeo/cssquery-dojo.html) 07:49:08 INFO - 2015-12-18 07:49:08,505 DEBUG : BROWSER_OUTPUT: RSS: Main: 257798144 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2646.2987308363286;2711.959320610192;2720.8095433319663;2727.127319257837;2734.7128551502087;4537.659675524341;4685.133250348688;4715.339852420664;4739.1706451371;4742.430908291006;4602.355670206165;4602.884927876805;4709.317149013391;4714.175019371605;4771.165046116933;4394.824207031715;4432.933505997413;4445.755483448409;4452.131834292298;4472.1502914446155;4252.319628859399;4435.179491794012;4443.84446544371;4496.325551167335;4511.278195488722;4262.744235345889;4350.260455722535;4358.150160718661;4358.171947330029;4392.494863090722;4356.629686476654;4363.127374525107;4373.278409062501;4380.5838445347645;4419.911601767946;4349.391085248068;4353.36876152956;4360.302351623725;4400.44994375703;4401.427814384142;4418.226810308183;4536.569025942563;4548.8862778430475;4595.333676616905;4723.149833030053;5165.044466773621;5169.844904652867;5171.37943446785;5185.274061631375;5208.661437006583;5164.25118357836;5185.844424667266;5191.766370513336;5231;5234.7120908350025;4554.066416384633;4649.976750116228;4656.254999200111;4812.398450193761;4813.831515896927;5139.511746384088;5220.634555581149;5227.3465816772905;5278.868028299246;5282.4717528246865;5023;5024.547790698854;5029.924551131736;5031.245313202976;5073.746312684351;4807.975960120247;4917.631177661689;4920.089783390084;5119.897602047939;5132.435432102466;4924.778384972685;5022.296878437021;5026.698398096126;5027.648064635514;5029.044481548494;5049.091163590516;5085.440601533828;5086.821961231413;5091.643584949092;5140.974295128575;4142.730722503027;4244.681648876285;4247.10810729744;4338.47938247406;4340.2187606230555;3693.796841173761;3757.173421847244;3777.3578491656485;3782.735208535431;3788.886333410002;4426.955730442654;4435.157320109169;4438.7114837535455;4443.488998765119;4466.508684044752;4841.975790121027;5145.511176438233;5156.845294641167;5161.81933632321;5162.97418512905;4272.957270427256;4300.634446072057;4318.028443600228;4318.287482565404;4358.912821743547;4602.470715867651;4640.930386044212;4644;4648.372469716613;4685.859424217211;4891.51084891508;4909.214525675946;4909.9263511048075;4914.213725803854;4952.678075925125;4898.461169271306;5023.2214006829;5026.4973502649445;5031.3207716959205;5169.250458683545;4868.926966095582;4881.072596206709;4893.828715994923;4902.3872015998;4921.778519966539;5096.210087436453;5097.92353114711;5103.362079740033;5105.2086926526445;5105.540501354896;4905.386826646669;4993;5040.974795126001;5042.29407882904;5154.253381699199;4848.612111031039;4890.119778439773;4894.0701266760625;4894.82868099629;4899.3385892904;4853.830115945925;4909.288153217834;4946.653734238569;4973.875653108701;4989.126902791924;4901.068796928715;4928.704277743347;4938.506149385033;4941.555260026615;4970.378702662168;4747.192977193817;5035.672681275705;5041.747912604428;5047.268146118866;5087;4751.952480475151;4757.857264282077;4824.831130910401;4843.491433399583;4888.780004899717 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: |1;ext.html;543.2432301106408;569.6468189722373;578.3060327606872;589.5077610195486;596.2010905386783;26267.868660656695;34941.650583494156;35645.82177089111;35778.99999999997;35802.46296305557;39771.80114099433;40299;40352.39471407931;40363.192736145334;40414.59585404145;6993.720251189946;7107.111611048619;7795.571243581587;7958.721444749435;7966.5618390988475;10133.733283339583;10409.11522520583;10420.739481512946;10423.426711530863;10423.947880260588;10085.294029417944;10198.949005255;10213.438260895648;10221.642242521513;10225.846612300822;7159;7326.780196594111;9998.050185232427;10011;10392.272540922138;9981.151602113829;10105.848412273821;10119.443430611313;10124.34191777532;10139.44233067181;37461.62538374608;37958.24083518342;37975.81012094949;37988.62011379879;38040.23919521594;2804.298925268683;2860.8140470869535;2865.41259041898;2878.2372671242138;2890.7976441649093;28319.57520637192;28617.427651447062;28897.855510722522;29177.854110729524;29445.70554294451;3450.4996775467666;3471.1842716961496;3505.91235219119;3589.6410358964154;3617.819109044537;221798.56402872014;222631;222895;223141;223248;44963.775181123885;45851.541484584726;53495.465045349825;53833.1925021125;54287;65049.34950650528;66174;66262.33737662659;66550.66724666346;66818.33181668121;13072.215667059816;13285.73428531424;13289.601311960656;13300.201987880759;13311.93344033293;9886.406815591088;9945.950270248602;9949.40303581773;9953.751156221153;10036.799264014679;18767.90616046911;19021.239150434103;19039.238430462905;19047.904760476107;19067.427977160707;6881.41507971818;7132.072830531998;7165.498415110892;7168.046994415408;7274.563526188445;873.1486800369715;939.5161491831575;1013.8580598716186;1020.9897901021043;1084.4306738962107;25830.741692582833;26548.46903061928;26714.332141696614;26753.464930701666;26898.5965210522;924.3529529329362;994.1319293837331;1013.7719013221966;1017.5421060522647;1022.0699163760942;13877.236751978537;14916.328765205591;15172.317245723969;15236.390544378322;15453.227338633023;272078.954473185;275193;275416;275682;276299;46066;46314;46325.76837115793;46387.536124638995;46464.3030354545;1176.2236923630535;1188.0198835960368;1227.6255741998598;1237.1340061956487;1333.4932725564418;21611.891940540198;25024.37439064038;26037.21888343353;26091.739082608932;27394;36035.27929441344;36414.63585364113;36500.45249321315;36550.8172459136;36555.634443655224;203317;205647;205766;206020;225825;22585.435364115372;28367.007154749484;28843.42313153683;29010.854945725136;29109.41781164407;32926.506102408006;37257;38463.807680961414;38543.42184867173;38967.6103238964;28531.28671783139;28898;30912.381752364377;31276.374472509968;32059;30881;31305;31489.055328340186;32392.676073238967;33724;29467.263318416353;29532;29556.261093472836;29776.85111574428;31676;28054.85972570124;28070.57894131549;28675.13974580766;28944.276393090342;28975.85512072426;22560.5487890238;23095.267854910613;23194.420139496648;23195.76804231936;23334.416639583465 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: |2;jquery.html;332.2126560052675;335.8572606642178;336.784457946914;337.91360775108024;338.18834796488443;12443.253404795718;12873.098883078164;14898.627534311647;15349.079055256678;16701;9550.188234000101;9614.615415383376;9745.074217949283;9763.267754918392;12286.201396909195;4397.84607538737;4510.797014134372;4511.097780443908;4515.322701594754;4515.887102822423;3516.7010804081556;4482.305242687373;4877.975610121945;5260.842174734764;5279.736013199325;5217.113090774577;5238.292830467895;5246.658967167141;5257.7108259045735;5263.1578947368425;5236.581073514129;5476;5773.39379365169;5781.971090144564;5785.421457854223;5172.818951336722;5176.922346164811;5178.97410512945;5181.5077567631015;5183.50756678115;18271.177796999167;18367.448976530726;18420.815791842044;19281.710774338397;19301.613967720707;1818.8090250523637;1861.8510519158435;1936.0029584763859;1940.4663717477665;1964.2732189090127;8087.514749115072;8579.914200858037;9711.465869377182;10135.341202821794;12471.438785254686;1709.4102534625533;1711.4352263752933;1733.237375554752;1750.0899532243163;1791.8297761712747;55209.856336608325;57266.71366643182;57461.42538574644;57623;58053.83892322172;9594.763403787454;9789.21686265097;10003.899961000443;10006.649767258112;10079.69760907174;12553.928601780786;12563.874361256454;12574.685632859251;12584.559540416041;12588.622341329774;6494.512911531559;6629.602223866584;6681.098051763047;6739.730410783513;6759.594424334556;6230.844228894314;6238.500919926395;6295.496360291166;6304.684765761694;6313.210848643919;4063.7764922929523;4169.541350451448;4219.51475580306;4223.113146239324;4277.871663850089;1620.4247492140357;1620.935162593483;1665.3172199398189;1672.2725614357794;1706.3260012295075;868.9782755431038;870.2211520689075;901.3465237702613;946.8485042393185;947.1191791633709;13204.471821127046;14834.777478337834;14932.402703891723;14979.176145312113;15270.770938435933;852.5055467828645;855.0081904990305;876.1457578860687;908.6683360573343;921.1479381572019;7501.28737769911;8926;8995.730128096167;9009.054049324857;9343.205827504738;412633;415853.9207304005;425628;428028;428420.86843486864;33787.83106084454;66090.66954665292;68401.65799170973;68465.31534684589;69174.31559581663;1033.6330602636106;1036.1658864614058;1039.812833689943;1054.7679510507676;1056.4084112896803;13821.447142114605;14275.214863182624;14290.214038227998;14675.48635797742;14809.555713328617;30224.84887575548;30528.542071869382;33751.66248337485;34585.30829383348;35556.111097222776;37532.81233593814;38447.80776096102;38497.6150238494;39269.21461570695;39640.80179599083;8700.695475658322;8960.238379737795;9370.156685898302;9476.905230947603;9481.620735170793;12696.555620553236;16152.434664786677;16415.91792041032;16800.57998550046;17047.744283835505;12933.482660693844;13036.608901732963;13076.738465230832;13590.45638174462;13660.112092713942;13034.804477933028;13135.408906599036;13210.867891320964;13399.33003349809;13693.178409295475;13286.734265314446;13441.260730659907;13632.318384080954;13929.721405572034;13957.41787769266;8580.957095214484;8650.875386199754;8923.15230053144;9102.453852768855;9127.22418594427;9092.454452732858;9182.908170918205;11860.466279017637;12994.740105198032;13341.19952802835 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1420.5383250443608;1594.6093207164242;1687.460012795905;1688.4934519644107;1728.8271172882705;6015.518758499321;6995.650217489124;7105.396041336493;7108.431325493961;7119.038929744483;7093.1842838073635;7215.81960450989;7235.240299768528;7246.23914488979;7253.2021477637545;2066.2561477867957;2080.875147491148;2092.403664955488;2093.769685334612;2097.0772859941576;2094.256538928681;2094.7381577302835;2106.195659206389;2117.3542069668747;2122.5012122151284;2070.9896450517804;2079.2365817869627;2079.6984437256588;2113.682947557871;2117.2483768262277;2070.27540360874;2074.4502706782714;2095.9790402095937;2105.315772373977;2107.093949601678;2031.8374530037709;2077.0860821238757;2078.2206672497814;2080.6982987466886;2090.2684060578827;5917.52659787216;6002.399760024006;6969.919662452328;7045.929540704578;7091.503594748369;4022.7787471689044;4081.1837632473625;4106.178764247133;4112.403701463301;4120.567340429272;2632.8288661236957;2635.736426357368;2646.311958890683;2695.770859476947;2697.5818748094075;858.3733874271817;874.1695389380114;876.4697358098323;883.6332921837494;885.0308911741632;9830.066143716336;9898.505074746306;9904.059114384121;9904.257180711475;9951.054649808257;3931.095847954929;3950.8617198398492;3979.567355751927;3980.8208630611693;4007.599240075969;8541.231289184003;8600;8606.225439710332;8618.956905215558;8625.180607842245;4344.500382455994;4364.869053928323;4367.781610919441;4371.803268852909;4372.672049596229;3932.665723413542;3949.150932549516;3952.4071389291835;3954.406838974177;3958.485396898385;3370.814605196738;3378.273671160712;3386.0349800306794;3408.4035293823977;3409.7272218222483;1018.0989824005808;1019;1021.8211812932707;1023.2223510132353;1027.0499787696351;1190.5237904838134;1196.252342286071;1209.056935590241;1209.171717373602;1223.2721530689225;6734.494912881553;6763.086983257197;6811.182658080963;6811.863762724718;6814.863702725917;397.066892801919;398.2135282816472;399.64431655826337;400.31945692323166;405.84941690308307;4942;5384.246205531229;5406.053940560386;5421.484958928895;5433.4566543345345;7278.308560686726;8059.597020149086;8294.543800090933;8306.584670766437;8314.584270786436;8136.633851476698;8165.836683266301;8172.264496195252;8208.753737387888;8242.464239824392;6335.524835637346;6619.602823830585;6620.404163625297;6663.500237482208;6664.666766661648;6734.292899245705;6820.761273355409;6828.556143850634;6832.145981752281;6846.726130954706;7322;7491.88762168557;7513.62431878393;7620.504667196615;7629.809254768453;7603.315701586884;7781.105172904962;7858.528488290721;7864.370850332074;7895.84208315842;6174.938250617436;6235.469985051322;6239.812805615838;6315.305316415099;6316.399942005502;7057.611831349325;7062.364387205176;7078.539894906815;7084.752033678797;7114.9288507116335;6407.775727849503;6479.7408103675325;6555.147830781968;6636.13730215069;6666.200055993312;6639.402453779183;6643.368879956396;6674;6710.966445167743;6749.898751518633;6759.763408280686;6795.524313298021;6796.184457865088;6808.353206445379;6809.489288303496;6023.879522409615;6186.969065154645;6216.502679785516;6246.937530624636;6259.718312676032;6306.810795676137;6356.904646430215;6358.77744278948;6370.6814659267775;6382.978723404419 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: |4;prototype.html;285.384995335053;288.1312841782027;291.5175384738259;292.3305630107055;299.43108094620226;5325;5396.433374495676;5454.590905682075;5488.670679759212;5551.611387202897;5370;5372.7313634318325;5415.783368665258;5424;5428.809991650293;4138.875833724993;4139.77231252281;4140.047789008529;4141;4149.336106223005;3931.9803400983096;4016.6585840203616;4021.617946295112;4084.611961863618;4086.734362266458;4021.5978402159694;4036.3541833306676;4051.918961620766;4055.918881622366;4076.388541718751;3881.0879443330796;4035.414864844581;4083.4487344208596;4102.0155162760875;4103.610157035077;3465.9653403465895;3541.097020259843;3544.521489598923;3555.875544355961;3558.946615800765;5170.45710200431;5187.351581052369;5187.403448603422;5202;5206.062908676437;4704.623630109583;4706.811727530895;4707.8587642370785;4713.505081966378;4714.787834547454;5529.972350138223;5638.35158956717;5665.348484924204;5673;5673.035583950697;3874.0121269076485;3874.2638898609175;3877.9612203878164;3880.7477513961785;3881.243157584272;5908.4091590841;6000.819975400745;6003.27960644721;6007.549433792483;6008.128821320884;3969.702272329529;3975.6223158799867;3976.4234186042863;3979.6617287530307;3981.124152686404;3925.5878132796215;4010.498687664042;4012.117334186474;4012.37808139733;4013.819378127991;4639.652026098056;4651.86044418668;4658.37111989877;4660.091282199938;4661.137689527448;5658.377578466366;5668.688222147739;5670.829875103754;5673.716314184274;5676.347220069662;1390.1311680199874;1410.8730214280763;1411.3225651687249;1411.724713680843;1413;934.8504239321676;935.5275585829113;939.4128669581512;959.6689142245914;961.4711908450324;4993.575546078624;5007.424146223231;5012.1980483122525;5015.097282489187;5022.573081288131;5917.7928772493615;5922;5922.822315330547;5928.377520360386;5936.109583562414;5002.074616196015;5020.046191223656;5023.447420783711;5023.924641130386;5028.647994640413;3071.7235448809715;3120.095172400024;3183.0928185467033;3209.1014515935576;3210.807351558914;5496.5053145216325;5536.7819079802375;5623.8875222496135;5628.352739435018;5647.040003199466;6041.154238406627;6090.208272924491;6109.75560977556;6117.051856962178;6122.510199184055;5567.888642227133;5585.748641311069;5587.525060369914;5616.522595579339;5625.774969001194;5795.246617939641;5911.793087241926;5936.614120082354;5956.285245770535;5972.5520585955355;5802.245708057925;5808.244928159313;5812.447817457335;5813.215215945793;5813.970930145322;5610;5610.298712660918;5620.269364982526;5621.52217061538;5622.803201887914;3860.4788353571907;3908.5309762828642;3910.2961466936226;3914.041059940333;3927.7054220932973;5913.615614985012;5914.467697907209;5917.822465326047;5919;5921.792737254175;5689;5698.1452782083015;5699.743511542073;5713.228714123707;5713.371529131875;5662.745176466987;5676.9432305678065;5690.459406356389;5699.629524080921;5703.771849125988;5412.945870541244;5562.638428502134;5569.359523654832;5575.7490912909825;5578.60949733531;5625.718714064198;5723.313202415737;5734.770609175586;5736.512396446182;5737.627054241461;5506.944930550803;5554.694491802989;5590.273264475592;5595.104783234663;5600.691961942132 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: |5;yui.html;728.6247582495016;734.562935053643;740.174824134065;765.4985984180361;777.114089937471;10433.530491127898;10600.045995860371;10704.571817127326;11982.520699172022;12433.502659893617;13543.187408755457;13642;13654.453821847113;13663.521776737816;13678.042537022413;633.1547384242042;639.9104125422444;782.6791015683557;795.3796039089519;796.0606484348466;792.6274650914061;794.9761507154766;802.8675268580677;803.0684406088938;804.0632662947688;819.2381085590429;820.9630566624517;826.5454000299841;827.4828232354778;827.8137419080689;654.2345455816682;661.2461793555351;661.3485716569152;661.7716887673745;813.5444151275275;491.06207144354113;610.9053096770008;616.9290531588834;622.3247776162868;623.4638211138417;10664.840027399596;10829.350238985686;10944.288621239593;10953.123750099972;10997.560097596086;10625.362478251329;10669.679909602646;10710.839337409867;10901.236913415985;11300.378479183642;9179.678711245075;9188.402753821047;9325.90674093264;9329.16037556623;9340.3461757677;3081.075677296802;3090.6909309069138;3141.623005239363;3199.8240096794666;3201.487761958099;40706.59293407058;40752.184956300705;40845.97885052868;41068.58931410708;41206;8561.357898157663;8562.828743425096;8595.312375009984;8606.526641034803;8691.782705432415;15280.694386112216;15310.851686123586;15336.616584585252;15340.769888451681;15357.769633455508;6099.878002440015;6188.783392581238;6204.658743769046;6234.9688251558455;6259;3377.59468863733;3454.7063499602814;3470.219200679827;3488.598811136701;3533.7526373153632;2482.4414506736202;2526.507331070424;2536.2898388451263;2595.5068536977915;2600.8309459885427;468.1432977650891;468.8476245220317;476.65442554147967;477.8375352380138;479.04908756119073;388.98249578768457;449.8290649553214;475.3250384454083;490.70312460961316;501.64132645158884;7056.964715176391;7202.315780000997;7445.25547445262;7477.1775104738435;7542.170361260257;434.56543456543454;477.6274505885415;487.92899585410396;545.5935328180531;564.7627996241588;3259.362341890131;3377.1725927148004;3414.163529935132;3493.074335301147;3520.7887526748477;49844;50267;50291.74854125706;50303.24545131826;50399.49600503948;43499.56500435018;43645.127097458506;44238.33642495365;44359;44514.554854451686;39216.6078339213;40208.59791402049;40412.1917561653;40426.79786601107;40683.796581016904;6651.547998441504;6784.86430271382;6894.448444124559;7021.122359705037;7204.4956853019785;31249.687503125588;32148.517772232968;32232.677673222966;33370.165745856546;33524.664753352154;38798.6120138795;39763.204735905696;39850;39898.40152397774;40791.59208407878;9244.583993720164;9480.52597370115;9749.220062395134;9864.92836504342;9875.753106172404;11018.559257629606;11020.5040773167;11050.33697978124;11235.66293011211;11278.60524881658;9890.406575605486;10362.378257304586;10606.310589811637;10955.397453139834;11012.559497620005;10637.095846853104;10860;11256.099512039182;11295.661130166109;11297.26567773125;8544.2310192083;9924.801503969737;10295.918928512398;10358.119559837498;11248.775024499628;8682.131786821268;8716.3462740296;8797.428167169113;8916.31276542593;9128.954355228447;8074.919250807417;8497.320214382711;8690.261327786957;8754.387192896436;8831.779205519913 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:49:08 INFO - 2015-12-18 07:49:08,506 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1284 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: __startTimestamp1450453748499__endTimestamp 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,507 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4699.00 Median:4848.61 stddev:492.13 (10.1%) stddev-sans-first:466.55 07:49:08 INFO - 2015-12-18 07:49:08,508 DEBUG : BROWSER_OUTPUT: Values: 2646.3 2712.0 2720.8 2727.1 2734.7 4537.7 4685.1 4715.3 4739.2 4742.4 4602.4 4602.9 4709.3 4714.2 4771.2 4394.8 4432.9 4445.8 4452.1 4472.2 4252.3 4435.2 4443.8 4496.3 4511.3 4262.7 4350.3 4358.2 4358.2 4392.5 4356.6 4363.1 4373.3 4380.6 4419.9 4349.4 4353.4 4360.3 4400.4 4401.4 4418.2 4536.6 4548.9 4595.3 4723.1 5165.0 5169.8 5171.4 5185.3 5208.7 5164.3 5185.8 5191.8 5231.0 5234.7 4554.1 4650.0 4656.3 4812.4 4813.8 5139.5 5220.6 5227.3 5278.9 5282.5 5023.0 5024.5 5029.9 5031.2 5073.7 4808.0 4917.6 4920.1 5119.9 5132.4 4924.8 5022.3 5026.7 5027.6 5029.0 5049.1 5085.4 5086.8 5091.6 5141.0 4142.7 4244.7 4247.1 4338.5 4340.2 3693.8 3757.2 3777.4 3782.7 3788.9 4427.0 4435.2 4438.7 4443.5 4466.5 4842.0 5145.5 5156.8 5161.8 5163.0 4273.0 4300.6 4318.0 4318.3 4358.9 4602.5 4640.9 4644.0 4648.4 4685.9 4891.5 4909.2 4909.9 4914.2 4952.7 4898.5 5023.2 5026.5 5031.3 5169.3 4868.9 4881.1 4893.8 4902.4 4921.8 5096.2 5097.9 5103.4 5105.2 5105.5 4905.4 4993.0 5041.0 5042.3 5154.3 4848.6 4890.1 4894.1 4894.8 4899.3 4853.8 4909.3 4946.7 4973.9 4989.1 4901.1 4928.7 4938.5 4941.6 4970.4 4747.2 5035.7 5041.7 5047.3 5087.0 4752.0 4757.9 4824.8 4843.5 4888.8 07:49:08 INFO - 2015-12-18 07:49:08,508 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,508 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39700.01 Median:28960.07 stddev:61835.39 (213.5%) stddev-sans-first:61938.95 07:49:08 INFO - 2015-12-18 07:49:08,509 DEBUG : BROWSER_OUTPUT: Values: 543.2 569.6 578.3 589.5 596.2 26267.9 34941.7 35645.8 35779.0 35802.5 39771.8 40299.0 40352.4 40363.2 40414.6 6993.7 7107.1 7795.6 7958.7 7966.6 10133.7 10409.1 10420.7 10423.4 10423.9 10085.3 10198.9 10213.4 10221.6 10225.8 7159.0 7326.8 9998.1 10011.0 10392.3 9981.2 10105.8 10119.4 10124.3 10139.4 37461.6 37958.2 37975.8 37988.6 38040.2 2804.3 2860.8 2865.4 2878.2 2890.8 28319.6 28617.4 28897.9 29177.9 29445.7 3450.5 3471.2 3505.9 3589.6 3617.8 221798.6 222631.0 222895.0 223141.0 223248.0 44963.8 45851.5 53495.5 53833.2 54287.0 65049.3 66174.0 66262.3 66550.7 66818.3 13072.2 13285.7 13289.6 13300.2 13311.9 9886.4 9946.0 9949.4 9953.8 10036.8 18767.9 19021.2 19039.2 19047.9 19067.4 6881.4 7132.1 7165.5 7168.0 7274.6 873.1 939.5 1013.9 1021.0 1084.4 25830.7 26548.5 26714.3 26753.5 26898.6 924.4 994.1 1013.8 1017.5 1022.1 13877.2 14916.3 15172.3 15236.4 15453.2 272079.0 275193.0 275416.0 275682.0 276299.0 46066.0 46314.0 46325.8 46387.5 46464.3 1176.2 1188.0 1227.6 1237.1 1333.5 21611.9 25024.4 26037.2 26091.7 27394.0 36035.3 36414.6 36500.5 36550.8 36555.6 203317.0 205647.0 205766.0 206020.0 225825.0 22585.4 28367.0 28843.4 29010.9 29109.4 32926.5 37257.0 38463.8 38543.4 38967.6 28531.3 28898.0 30912.4 31276.4 32059.0 30881.0 31305.0 31489.1 32392.7 33724.0 29467.3 29532.0 29556.3 29776.9 31676.0 28054.9 28070.6 28675.1 28944.3 28975.9 22560.5 23095.3 23194.4 23195.8 23334.4 07:49:08 INFO - 2015-12-18 07:49:08,509 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,509 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:23934.47 Median:21852.29 stddev:68956.73 (315.6%) stddev-sans-first:69127.40 07:49:08 INFO - 2015-12-18 07:49:08,510 DEBUG : BROWSER_OUTPUT: Values: 332.2 335.9 336.8 337.9 338.2 12443.3 12873.1 14898.6 15349.1 16701.0 9550.2 9614.6 9745.1 9763.3 12286.2 4397.8 4510.8 4511.1 4515.3 4515.9 3516.7 4482.3 4878.0 5260.8 5279.7 5217.1 5238.3 5246.7 5257.7 5263.2 5236.6 5476.0 5773.4 5782.0 5785.4 5172.8 5176.9 5179.0 5181.5 5183.5 18271.2 18367.4 18420.8 19281.7 19301.6 1818.8 1861.9 1936.0 1940.5 1964.3 8087.5 8579.9 9711.5 10135.3 12471.4 1709.4 1711.4 1733.2 1750.1 1791.8 55209.9 57266.7 57461.4 57623.0 58053.8 9594.8 9789.2 10003.9 10006.6 10079.7 12553.9 12563.9 12574.7 12584.6 12588.6 6494.5 6629.6 6681.1 6739.7 6759.6 6230.8 6238.5 6295.5 6304.7 6313.2 4063.8 4169.5 4219.5 4223.1 4277.9 1620.4 1620.9 1665.3 1672.3 1706.3 869.0 870.2 901.3 946.8 947.1 13204.5 14834.8 14932.4 14979.2 15270.8 852.5 855.0 876.1 908.7 921.1 7501.3 8926.0 8995.7 9009.1 9343.2 412633.0 415853.9 425628.0 428028.0 428420.9 33787.8 66090.7 68401.7 68465.3 69174.3 1033.6 1036.2 1039.8 1054.8 1056.4 13821.4 14275.2 14290.2 14675.5 14809.6 30224.8 30528.5 33751.7 34585.3 35556.1 37532.8 38447.8 38497.6 39269.2 39640.8 8700.7 8960.2 9370.2 9476.9 9481.6 12696.6 16152.4 16415.9 16800.6 17047.7 12933.5 13036.6 13076.7 13590.5 13660.1 13034.8 13135.4 13210.9 13399.3 13693.2 13286.7 13441.3 13632.3 13929.7 13957.4 8581.0 8650.9 8923.2 9102.5 9127.2 9092.5 9182.9 11860.5 12994.7 13341.2 07:49:08 INFO - 2015-12-18 07:49:08,510 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,510 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5003.68 Median:6283.26 stddev:2602.82 (41.4%) stddev-sans-first:2596.19 07:49:08 INFO - 2015-12-18 07:49:08,510 DEBUG : BROWSER_OUTPUT: Values: 1420.5 1594.6 1687.5 1688.5 1728.8 6015.5 6995.7 7105.4 7108.4 7119.0 7093.2 7215.8 7235.2 7246.2 7253.2 2066.3 2080.9 2092.4 2093.8 2097.1 2094.3 2094.7 2106.2 2117.4 2122.5 2071.0 2079.2 2079.7 2113.7 2117.2 2070.3 2074.5 2096.0 2105.3 2107.1 2031.8 2077.1 2078.2 2080.7 2090.3 5917.5 6002.4 6969.9 7045.9 7091.5 4022.8 4081.2 4106.2 4112.4 4120.6 2632.8 2635.7 2646.3 2695.8 2697.6 858.4 874.2 876.5 883.6 885.0 9830.1 9898.5 9904.1 9904.3 9951.1 3931.1 3950.9 3979.6 3980.8 4007.6 8541.2 8600.0 8606.2 8619.0 8625.2 4344.5 4364.9 4367.8 4371.8 4372.7 3932.7 3949.2 3952.4 3954.4 3958.5 3370.8 3378.3 3386.0 3408.4 3409.7 1018.1 1019.0 1021.8 1023.2 1027.0 1190.5 1196.3 1209.1 1209.2 1223.3 6734.5 6763.1 6811.2 6811.9 6814.9 397.1 398.2 399.6 400.3 405.8 4942.0 5384.2 5406.1 5421.5 5433.5 7278.3 8059.6 8294.5 8306.6 8314.6 8136.6 8165.8 8172.3 8208.8 8242.5 6335.5 6619.6 6620.4 6663.5 6664.7 6734.3 6820.8 6828.6 6832.1 6846.7 7322.0 7491.9 7513.6 7620.5 7629.8 7603.3 7781.1 7858.5 7864.4 7895.8 6174.9 6235.5 6239.8 6315.3 6316.4 7057.6 7062.4 7078.5 7084.8 7114.9 6407.8 6479.7 6555.1 6636.1 6666.2 6639.4 6643.4 6674.0 6711.0 6749.9 6759.8 6795.5 6796.2 6808.4 6809.5 6023.9 6187.0 6216.5 6246.9 6259.7 6306.8 6356.9 6358.8 6370.7 6383.0 07:49:08 INFO - 2015-12-18 07:49:08,510 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,510 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4687.36 Median:5419.89 stddev:1414.07 (26.1%) stddev-sans-first:1378.90 07:49:08 INFO - 2015-12-18 07:49:08,510 DEBUG : BROWSER_OUTPUT: Values: 285.4 288.1 291.5 292.3 299.4 5325.0 5396.4 5454.6 5488.7 5551.6 5370.0 5372.7 5415.8 5424.0 5428.8 4138.9 4139.8 4140.0 4141.0 4149.3 3932.0 4016.7 4021.6 4084.6 4086.7 4021.6 4036.4 4051.9 4055.9 4076.4 3881.1 4035.4 4083.4 4102.0 4103.6 3466.0 3541.1 3544.5 3555.9 3558.9 5170.5 5187.4 5187.4 5202.0 5206.1 4704.6 4706.8 4707.9 4713.5 4714.8 5530.0 5638.4 5665.3 5673.0 5673.0 3874.0 3874.3 3878.0 3880.7 3881.2 5908.4 6000.8 6003.3 6007.5 6008.1 3969.7 3975.6 3976.4 3979.7 3981.1 3925.6 4010.5 4012.1 4012.4 4013.8 4639.7 4651.9 4658.4 4660.1 4661.1 5658.4 5668.7 5670.8 5673.7 5676.3 1390.1 1410.9 1411.3 1411.7 1413.0 934.9 935.5 939.4 959.7 961.5 4993.6 5007.4 5012.2 5015.1 5022.6 5917.8 5922.0 5922.8 5928.4 5936.1 5002.1 5020.0 5023.4 5023.9 5028.6 3071.7 3120.1 3183.1 3209.1 3210.8 5496.5 5536.8 5623.9 5628.4 5647.0 6041.2 6090.2 6109.8 6117.1 6122.5 5567.9 5585.7 5587.5 5616.5 5625.8 5795.2 5911.8 5936.6 5956.3 5972.6 5802.2 5808.2 5812.4 5813.2 5814.0 5610.0 5610.3 5620.3 5621.5 5622.8 3860.5 3908.5 3910.3 3914.0 3927.7 5913.6 5914.5 5917.8 5919.0 5921.8 5689.0 5698.1 5699.7 5713.2 5713.4 5662.7 5676.9 5690.5 5699.6 5703.8 5412.9 5562.6 5569.4 5575.7 5578.6 5625.7 5723.3 5734.8 5736.5 5737.6 5506.9 5554.7 5590.3 5595.1 5600.7 07:49:08 INFO - 2015-12-18 07:49:08,511 DEBUG : BROWSER_OUTPUT: 07:49:08 INFO - 2015-12-18 07:49:08,511 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12117.45 Median:468.50 stddev:13926.03 (2972.5%) stddev-sans-first:13938.83 07:49:08 INFO - 2015-12-18 07:49:08,512 DEBUG : BROWSER_OUTPUT: Values: 728.6 734.6 740.2 765.5 777.1 10433.5 10600.0 10704.6 11982.5 12433.5 13543.2 13642.0 13654.5 13663.5 13678.0 633.2 639.9 782.7 795.4 796.1 792.6 795.0 802.9 803.1 804.1 819.2 821.0 826.5 827.5 827.8 654.2 661.2 661.3 661.8 813.5 491.1 610.9 616.9 622.3 623.5 10664.8 10829.4 10944.3 10953.1 10997.6 10625.4 10669.7 10710.8 10901.2 11300.4 9179.7 9188.4 9325.9 9329.2 9340.3 3081.1 3090.7 3141.6 3199.8 3201.5 40706.6 40752.2 40846.0 41068.6 41206.0 8561.4 8562.8 8595.3 8606.5 8691.8 15280.7 15310.9 15336.6 15340.8 15357.8 6099.9 6188.8 6204.7 6235.0 6259.0 3377.6 3454.7 3470.2 3488.6 3533.8 2482.4 2526.5 2536.3 2595.5 2600.8 468.1 468.8 476.7 477.8 479.0 389.0 449.8 475.3 490.7 501.6 7057.0 7202.3 7445.3 7477.2 7542.2 434.6 477.6 487.9 545.6 564.8 3259.4 3377.2 3414.2 3493.1 3520.8 49844.0 50267.0 50291.7 50303.2 50399.5 43499.6 43645.1 44238.3 44359.0 44514.6 39216.6 40208.6 40412.2 40426.8 40683.8 6651.5 6784.9 6894.4 7021.1 7204.5 31249.7 32148.5 32232.7 33370.2 33524.7 38798.6 39763.2 39850.0 39898.4 40791.6 9244.6 9480.5 9749.2 9864.9 9875.8 11018.6 11020.5 11050.3 11235.7 11278.6 9890.4 10362.4 10606.3 10955.4 11012.6 10637.1 10860.0 11256.1 11295.7 11297.3 8544.2 9924.8 10295.9 10358.1 11248.8 8682.1 8716.3 8797.4 8916.3 9129.0 8074.9 8497.3 8690.3 8754.4 8831.8 07:49:08 INFO - 2015-12-18 07:49:08,512 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:49:08 INFO - 2015-12-18 07:49:08,512 DEBUG : BROWSER_OUTPUT: 07:49:09 INFO - 2015-12-18 07:49:09,205 INFO : Browser exited with error code: 0 07:49:09 INFO - 2015-12-18 07:49:09,229 INFO : Completed test dromaeo_css (00:18:37) 07:49:09 INFO - 2015-12-18 07:49:09,229 INFO : Starting test kraken 07:49:09 INFO - 2015-12-18 07:49:09,229 DEBUG : operating with platform_type : linux_ 07:49:09 INFO - 2015-12-18 07:49:09,229 INFO : Initialising browser for kraken test... 07:49:09 INFO - 2015-12-18 07:49:09,235 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppu448I/profile http://localhost:54173/getInfo.html 07:49:13 INFO - 2015-12-18 07:49:13,403 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:49:13 INFO - 2015-12-18 07:49:13,403 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:49:13 INFO - 2015-12-18 07:49:13,403 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:49:13 INFO - 2015-12-18 07:49:13,403 DEBUG : BROWSER_OUTPUT: __metrics 07:49:13 INFO - 2015-12-18 07:49:13,480 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 07:49:13 INFO - 2015-12-18 07:49:13,556 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:49:13 ERROR - 2015-12-18 07:49:13,571 DEBUG : BROWSER_OUTPUT: JavaScript error: chrome://browser/content/tab-content.js, line 408: TypeError: content is null 07:49:14 INFO - 2015-12-18 07:49:14,354 INFO : Browser initialized. 07:49:14 INFO - 2015-12-18 07:49:14,355 INFO : Running cycle 1/1 for kraken test... 07:49:14 INFO - 2015-12-18 07:49:14,355 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppu448I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:49:26 INFO - 2015-12-18 07:49:26,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 150106112 07:49:26 INFO - 2015-12-18 07:49:26,353 DEBUG : BROWSER_OUTPUT: 07:49:28 INFO - 2015-12-18 07:49:28,936 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54173/tests/kraken/driver.html?testName=audio-beat-detection) 07:49:29 INFO - 2015-12-18 07:49:29,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 186359808 07:49:29 INFO - 2015-12-18 07:49:29,525 DEBUG : BROWSER_OUTPUT: 07:49:32 INFO - 2015-12-18 07:49:32,250 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54173/tests/kraken/driver.html?testName=audio-dft) 07:49:32 INFO - 2015-12-18 07:49:32,831 DEBUG : BROWSER_OUTPUT: RSS: Main: 176746496 07:49:32 INFO - 2015-12-18 07:49:32,832 DEBUG : BROWSER_OUTPUT: 07:49:35 INFO - 2015-12-18 07:49:35,843 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54173/tests/kraken/driver.html?testName=audio-fft) 07:49:36 INFO - 2015-12-18 07:49:36,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 161869824 07:49:36 INFO - 2015-12-18 07:49:36,424 DEBUG : BROWSER_OUTPUT: 07:49:38 INFO - 2015-12-18 07:49:38,477 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54173/tests/kraken/driver.html?testName=audio-oscillator) 07:49:39 INFO - 2015-12-18 07:49:39,063 DEBUG : BROWSER_OUTPUT: RSS: Main: 163377152 07:49:39 INFO - 2015-12-18 07:49:39,064 DEBUG : BROWSER_OUTPUT: 07:49:41 INFO - 2015-12-18 07:49:41,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54173/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:49:41 INFO - 2015-12-18 07:49:41,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 165113856 07:49:41 INFO - 2015-12-18 07:49:41,905 DEBUG : BROWSER_OUTPUT: 07:49:44 INFO - 2015-12-18 07:49:44,931 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54173/tests/kraken/driver.html?testName=imaging-darkroom) 07:49:45 INFO - 2015-12-18 07:49:45,505 DEBUG : BROWSER_OUTPUT: RSS: Main: 164904960 07:49:45 INFO - 2015-12-18 07:49:45,505 DEBUG : BROWSER_OUTPUT: 07:49:48 INFO - 2015-12-18 07:49:48,878 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54173/tests/kraken/driver.html?testName=imaging-desaturate) 07:49:49 INFO - 2015-12-18 07:49:49,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 165011456 07:49:49 INFO - 2015-12-18 07:49:49,451 DEBUG : BROWSER_OUTPUT: 07:49:52 INFO - 2015-12-18 07:49:52,281 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54173/tests/kraken/driver.html?testName=json-parse-financial) 07:49:52 INFO - 2015-12-18 07:49:52,863 DEBUG : BROWSER_OUTPUT: RSS: Main: 166064128 07:49:52 INFO - 2015-12-18 07:49:52,863 DEBUG : BROWSER_OUTPUT: 07:49:54 INFO - 2015-12-18 07:49:54,621 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54173/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:49:55 INFO - 2015-12-18 07:49:55,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 175181824 07:49:55 INFO - 2015-12-18 07:49:55,187 DEBUG : BROWSER_OUTPUT: 07:49:57 INFO - 2015-12-18 07:49:57,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:49:57 INFO - 2015-12-18 07:49:57,646 DEBUG : BROWSER_OUTPUT: RSS: Main: 174137344 07:49:57 INFO - 2015-12-18 07:49:57,647 DEBUG : BROWSER_OUTPUT: 07:49:59 INFO - 2015-12-18 07:49:59,738 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:50:00 INFO - 2015-12-18 07:50:00,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 182734848 07:50:00 INFO - 2015-12-18 07:50:00,319 DEBUG : BROWSER_OUTPUT: 07:50:02 INFO - 2015-12-18 07:50:02,882 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:50:03 INFO - 2015-12-18 07:50:03,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 189521920 07:50:03 INFO - 2015-12-18 07:50:03,469 DEBUG : BROWSER_OUTPUT: 07:50:06 INFO - 2015-12-18 07:50:06,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:50:06 INFO - 2015-12-18 07:50:06,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 185114624 07:50:06 INFO - 2015-12-18 07:50:06,658 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,399 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54173/tests/kraken/driver.html?testName=ai-astar) 07:50:08 INFO - 2015-12-18 07:50:08,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 244174848 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;113;106;113;113;112;104;112;114;119 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;161;129;152;167;135;157;151;135;168;139 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;162;161;162;162;163;169;162;162 07:50:08 INFO - 2015-12-18 07:50:08,657 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;80;94;72;71;72;109;90;72 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;95;130;95;96;95;130;95;95;134 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;110;110;110;110;110;109 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;147;147;147;147;146;147;148;148 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;95;96;95;96;95;95 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;71;71;71;70;70;70;79;68;68 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;68;69;68;69;68;69;69;69 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;82;84;81;81;82;82;82;82;82 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;127;129;137;119;126;122;129;129;129;122 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;148;152;146;145;134;150;148;147;134 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;73;69;70;69;70;68;69;68;69;69 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,999 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: __startTimestamp1450453808652__endTimestamp 07:50:08 INFO - 2015-12-18 07:50:08,658 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:4.16 (3.7%) stddev-sans-first:4.41 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: Values: 111.0 113.0 106.0 113.0 113.0 112.0 104.0 112.0 114.0 119.0 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.40 Median:154.50 stddev:14.13 (9.1%) stddev-sans-first:14.35 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: Values: 161.0 129.0 152.0 167.0 135.0 157.0 151.0 135.0 168.0 139.0 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.60 Median:162.00 stddev:2.32 (1.4%) stddev-sans-first:2.39 07:50:08 INFO - 2015-12-18 07:50:08,660 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 162.0 161.0 162.0 162.0 163.0 169.0 162.0 162.0 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.60 Median:73.00 stddev:12.93 (17.7%) stddev-sans-first:13.42 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 80.0 94.0 72.0 71.0 72.0 109.0 90.0 72.0 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:95.00 stddev:17.52 (18.4%) stddev-sans-first:18.12 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 130.0 95.0 96.0 95.0 130.0 95.0 95.0 134.0 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 110.0 110.0 110.0 110.0 110.0 109.0 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 07:50:08 INFO - 2015-12-18 07:50:08,661 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 147.0 147.0 147.0 147.0 146.0 147.0 148.0 148.0 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:71.00 Median:71.00 stddev:3.09 (4.4%) stddev-sans-first:3.26 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 71.0 71.0 70.0 70.0 70.0 79.0 68.0 68.0 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 68.0 69.0 68.0 69.0 68.0 69.0 69.0 69.0 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 07:50:08 INFO - 2015-12-18 07:50:08,662 DEBUG : BROWSER_OUTPUT: Values: 82.0 82.0 84.0 81.0 81.0 82.0 82.0 82.0 82.0 82.0 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:129.00 stddev:5.07 (3.9%) stddev-sans-first:5.37 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: Values: 127.0 129.0 137.0 119.0 126.0 122.0 129.0 129.0 129.0 122.0 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.90 Median:147.50 stddev:6.89 (4.7%) stddev-sans-first:6.51 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: Values: 135.0 148.0 152.0 146.0 145.0 134.0 150.0 148.0 147.0 134.0 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:1.43 (2.1%) stddev-sans-first:0.71 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: Values: 73.0 69.0 70.0 69.0 70.0 68.0 69.0 68.0 69.0 69.0 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:50:08 INFO - 2015-12-18 07:50:08,663 DEBUG : BROWSER_OUTPUT: 07:50:09 INFO - 2015-12-18 07:50:09,574 INFO : Browser exited with error code: 0 07:50:09 INFO - 2015-12-18 07:50:09,625 INFO : Completed test kraken (00:01:00) 07:50:09 INFO - 2015-12-18 07:50:09,625 INFO : Starting test v8_7 07:50:09 INFO - 2015-12-18 07:50:09,625 DEBUG : operating with platform_type : linux_ 07:50:09 INFO - 2015-12-18 07:50:09,625 INFO : Initialising browser for v8_7 test... 07:50:09 INFO - 2015-12-18 07:50:09,631 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpugBGGr/profile http://localhost:54173/getInfo.html 07:50:13 INFO - 2015-12-18 07:50:13,747 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:50:13 INFO - 2015-12-18 07:50:13,747 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:50:13 INFO - 2015-12-18 07:50:13,747 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:50:13 INFO - 2015-12-18 07:50:13,747 DEBUG : BROWSER_OUTPUT: __metrics 07:50:13 INFO - 2015-12-18 07:50:13,843 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 07:50:13 INFO - 2015-12-18 07:50:13,904 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:14 INFO - 2015-12-18 07:50:14,208 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 07:50:14 INFO - 2015-12-18 07:50:14,900 INFO : Browser initialized. 07:50:14 INFO - 2015-12-18 07:50:14,901 INFO : Running cycle 1/1 for v8_7 test... 07:50:14 INFO - 2015-12-18 07:50:14,901 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpugBGGr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:50:26 INFO - 2015-12-18 07:50:26,823 DEBUG : BROWSER_OUTPUT: RSS: Main: 147034112 07:50:26 INFO - 2015-12-18 07:50:26,824 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,234 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54173/tests/v8_7/run.html (next: http://localhost:54173/tests/v8_7/run.html) 07:50:48 INFO - 2015-12-18 07:50:48,485 DEBUG : BROWSER_OUTPUT: RSS: Main: 446242816 07:50:48 INFO - 2015-12-18 07:50:48,485 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,485 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:50:48 INFO - 2015-12-18 07:50:48,485 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:50:48 INFO - 2015-12-18 07:50:48,485 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |0;Richards;169.00456312320432 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.8268414963363 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.97780275358247 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5218.75 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |4;RayTrace;2087.6826722338205 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |5;Earley;798.0845969672786 07:50:48 INFO - 2015-12-18 07:50:48,486 DEBUG : BROWSER_OUTPUT: |6;Boyer;12219.512195121952 07:50:48 INFO - 2015-12-18 07:50:48,487 DEBUG : BROWSER_OUTPUT: |7;RegExp;28027.777777777777 07:50:48 INFO - 2015-12-18 07:50:48,487 DEBUG : BROWSER_OUTPUT: |8;Splay;607.90273556231 07:50:48 INFO - 2015-12-18 07:50:48,487 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 07:50:48 INFO - 2015-12-18 07:50:48,487 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:50:48 INFO - 2015-12-18 07:50:48,487 DEBUG : BROWSER_OUTPUT: __startTimestamp1450453848484__endTimestamp 07:50:48 INFO - 2015-12-18 07:50:48,487 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,487 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:50:48 INFO - 2015-12-18 07:50:48,489 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:50:48 INFO - 2015-12-18 07:50:48,489 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,489 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:169.00 Median:169.00 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,489 DEBUG : BROWSER_OUTPUT: Values: 169.0 07:50:48 INFO - 2015-12-18 07:50:48,489 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.83 Median:192.83 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: Values: 192.8 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.98 Median:280.98 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: Values: 281.0 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5218.75 Median:5218.75 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,490 DEBUG : BROWSER_OUTPUT: Values: 5218.8 07:50:48 INFO - 2015-12-18 07:50:48,491 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,491 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:2087.68 Median:2087.68 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,491 DEBUG : BROWSER_OUTPUT: Values: 2087.7 07:50:48 INFO - 2015-12-18 07:50:48,491 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,491 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:798.08 Median:798.08 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,491 DEBUG : BROWSER_OUTPUT: Values: 798.1 07:50:48 INFO - 2015-12-18 07:50:48,491 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: Values: 12219.5 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:28027.78 Median:28027.78 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: Values: 28027.8 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:607.90 Median:607.90 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,492 DEBUG : BROWSER_OUTPUT: Values: 607.9 07:50:48 INFO - 2015-12-18 07:50:48,493 DEBUG : BROWSER_OUTPUT: 07:50:48 INFO - 2015-12-18 07:50:48,493 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 07:50:48 INFO - 2015-12-18 07:50:48,493 DEBUG : BROWSER_OUTPUT: Values: 4830.9 07:50:48 INFO - 2015-12-18 07:50:48,493 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:50:48 INFO - 2015-12-18 07:50:48,493 DEBUG : BROWSER_OUTPUT: 07:50:49 INFO - 2015-12-18 07:50:49,362 INFO : Browser exited with error code: 0 07:50:49 INFO - 2015-12-18 07:50:49,376 INFO : Completed test v8_7 (00:00:39) 07:50:49 INFO - 2015-12-18 07:50:49,509 INFO : Completed test suite (00:20:17) 07:50:49 INFO - 2015-12-18 07:50:49,510 DEBUG : Working with test: dromaeo_css 07:50:49 INFO - 2015-12-18 07:50:49,510 DEBUG : Generating results file: dromaeo_css 07:50:49 INFO - 2015-12-18 07:50:49,511 DEBUG : Working with test: kraken 07:50:49 INFO - 2015-12-18 07:50:49,511 DEBUG : Generating results file: kraken 07:50:49 INFO - 2015-12-18 07:50:49,511 INFO : javascript benchmark 07:50:49 INFO - 2015-12-18 07:50:49,512 DEBUG : Working with test: v8_7 07:50:49 INFO - 2015-12-18 07:50:49,512 DEBUG : Generating results file: v8_7 07:50:49 INFO - 2015-12-18 07:50:49,512 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:50:49 INFO - 2015-12-18 07:50:49,585 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,35]] 07:50:49 INFO - 2015-12-18 07:50:49,585 DEBUG : process_Request line: dromaeo_css 6252.47 graph.html#tests=[[72,94,35]] 07:50:49 INFO - 2015-12-18 07:50:49,585 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:50:49 INFO - 2015-12-18 07:50:49,707 DEBUG : process_Request line: kraken 1524.30 graph.html#tests=[[232,94,35]] 07:50:49 INFO - 2015-12-18 07:50:49,707 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:50:49 INFO - 2015-12-18 07:50:49,831 DEBUG : process_Request line: v8_7 19458.90 graph.html#tests=[[230,94,35]] 07:50:49 INFO - 2015-12-18 07:50:49,832 INFO : javascript benchmark 07:50:49 INFO - 2015-12-18 07:50:49,836 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2646.2987308363286, 2711.959320610192, 2720.8095433319663, 2727.127319257837, 2734.7128551502087, 4537.659675524341, 4685.133250348688, 4715.339852420664, 4739.1706451371, 4742.430908291006, 4602.355670206165, 4602.884927876805, 4709.317149013391, 4714.175019371605, 4771.165046116933, 4394.824207031715, 4432.933505997413, 4445.755483448409, 4452.131834292298, 4472.1502914446155, 4252.319628859399, 4435.179491794012, 4443.84446544371, 4496.325551167335, 4511.278195488722, 4262.744235345889, 4350.260455722535, 4358.150160718661, 4358.171947330029, 4392.494863090722, 4356.629686476654, 4363.127374525107, 4373.278409062501, 4380.5838445347645, 4419.911601767946, 4349.391085248068, 4353.36876152956, 4360.302351623725, 4400.44994375703, 4401.427814384142, 4418.226810308183, 4536.569025942563, 4548.8862778430475, 4595.333676616905, 4723.149833030053, 5165.044466773621, 5169.844904652867, 5171.37943446785, 5185.274061631375, 5208.661437006583, 5164.25118357836, 5185.844424667266, 5191.766370513336, 5231.0, 5234.7120908350025, 4554.066416384633, 4649.976750116228, 4656.254999200111, 4812.398450193761, 4813.831515896927, 5139.511746384088, 5220.634555581149, 5227.3465816772905, 5278.868028299246, 5282.4717528246865, 5023.0, 5024.547790698854, 5029.924551131736, 5031.245313202976, 5073.746312684351, 4807.975960120247, 4917.631177661689, 4920.089783390084, 5119.897602047939, 5132.435432102466, 4924.778384972685, 5022.296878437021, 5026.698398096126, 5027.648064635514, 5029.044481548494, 5049.091163590516, 5085.440601533828, 5086.821961231413, 5091.643584949092, 5140.974295128575, 4142.730722503027, 4244.681648876285, 4247.10810729744, 4338.47938247406, 4340.2187606230555, 3693.796841173761, 3757.173421847244, 3777.3578491656485, 3782.735208535431, 3788.886333410002, 4426.955730442654, 4435.157320109169, 4438.7114837535455, 4443.488998765119, 4466.508684044752, 4841.975790121027, 5145.511176438233, 5156.845294641167, 5161.81933632321, 5162.97418512905, 4272.957270427256, 4300.634446072057, 4318.028443600228, 4318.287482565404, 4358.912821743547, 4602.470715867651, 4640.930386044212, 4644.0, 4648.372469716613, 4685.859424217211, 4891.51084891508, 4909.214525675946, 4909.9263511048075, 4914.213725803854, 4952.678075925125, 4898.461169271306, 5023.2214006829, 5026.4973502649445, 5031.3207716959205, 5169.250458683545, 4868.926966095582, 4881.072596206709, 4893.828715994923, 4902.3872015998, 4921.778519966539, 5096.210087436453, 5097.92353114711, 5103.362079740033, 5105.2086926526445, 5105.540501354896, 4905.386826646669, 4993.0, 5040.974795126001, 5042.29407882904, 5154.253381699199, 4848.612111031039, 4890.119778439773, 4894.0701266760625, 4894.82868099629, 4899.3385892904, 4853.830115945925, 4909.288153217834, 4946.653734238569, 4973.875653108701, 4989.126902791924, 4901.068796928715, 4928.704277743347, 4938.506149385033, 4941.555260026615, 4970.378702662168, 4747.192977193817, 5035.672681275705, 5041.747912604428, 5047.268146118866, 5087.0, 4751.952480475151, 4757.857264282077, 4824.831130910401, 4843.491433399583, 4888.780004899717], "unit": "score", "name": "dojo.html", "value": 4668.293421568306}, {"lowerIsBetter": false, "replicates": [543.2432301106408, 569.6468189722373, 578.3060327606872, 589.5077610195486, 596.2010905386783, 26267.868660656695, 34941.650583494156, 35645.82177089111, 35778.99999999997, 35802.46296305557, 39771.80114099433, 40299.0, 40352.39471407931, 40363.192736145334, 40414.59585404145, 6993.720251189946, 7107.111611048619, 7795.571243581587, 7958.721444749435, 7966.5618390988475, 10133.733283339583, 10409.11522520583, 10420.739481512946, 10423.426711530863, 10423.947880260588, 10085.294029417944, 10198.949005255, 10213.438260895648, 10221.642242521513, 10225.846612300822, 7159.0, 7326.780196594111, 9998.050185232427, 10011.0, 10392.272540922138, 9981.151602113829, 10105.848412273821, 10119.443430611313, 10124.34191777532, 10139.44233067181, 37461.62538374608, 37958.24083518342, 37975.81012094949, 37988.62011379879, 38040.23919521594, 2804.298925268683, 2860.8140470869535, 2865.41259041898, 2878.2372671242138, 2890.7976441649093, 28319.57520637192, 28617.427651447062, 28897.855510722522, 29177.854110729524, 29445.70554294451, 3450.4996775467666, 3471.1842716961496, 3505.91235219119, 3589.6410358964154, 3617.819109044537, 221798.56402872014, 222631.0, 222895.0, 223141.0, 223248.0, 44963.775181123885, 45851.541484584726, 53495.465045349825, 53833.1925021125, 54287.0, 65049.34950650528, 66174.0, 66262.33737662659, 66550.66724666346, 66818.33181668121, 13072.215667059816, 13285.73428531424, 13289.601311960656, 13300.201987880759, 13311.93344033293, 9886.406815591088, 9945.950270248602, 9949.40303581773, 9953.751156221153, 10036.799264014679, 18767.90616046911, 19021.239150434103, 19039.238430462905, 19047.904760476107, 19067.427977160707, 6881.41507971818, 7132.072830531998, 7165.498415110892, 7168.046994415408, 7274.563526188445, 873.1486800369715, 939.5161491831575, 1013.8580598716186, 1020.9897901021043, 1084.4306738962107, 25830.741692582833, 26548.46903061928, 26714.332141696614, 26753.464930701666, 26898.5965210522, 924.3529529329362, 994.1319293837331, 1013.7719013221966, 1017.5421060522647, 1022.0699163760942, 13877.236751978537, 14916.328765205591, 15172.317245723969, 15236.390544378322, 15453.227338633023, 272078.954473185, 275193.0, 275416.0, 275682.0, 276299.0, 46066.0, 46314.0, 46325.76837115793, 46387.536124638995, 46464.3030354545, 1176.2236923630535, 1188.0198835960368, 1227.6255741998598, 1237.1340061956487, 1333.4932725564418, 21611.891940540198, 25024.37439064038, 26037.21888343353, 26091.739082608932, 27394.0, 36035.27929441344, 36414.63585364113, 36500.45249321315, 36550.8172459136, 36555.634443655224, 203317.0, 205647.0, 205766.0, 206020.0, 225825.0, 22585.435364115372, 28367.007154749484, 28843.42313153683, 29010.854945725136, 29109.41781164407, 32926.506102408006, 37257.0, 38463.807680961414, 38543.42184867173, 38967.6103238964, 28531.28671783139, 28898.0, 30912.381752364377, 31276.374472509968, 32059.0, 30881.0, 31305.0, 31489.055328340186, 32392.676073238967, 33724.0, 29467.263318416353, 29532.0, 29556.261093472836, 29776.85111574428, 31676.0, 28054.85972570124, 28070.57894131549, 28675.13974580766, 28944.276393090342, 28975.85512072426, 22560.5487890238, 23095.267854910613, 23194.420139496648, 23195.76804231936, 23334.416639583465], "unit": "score", "name": "ext.html", "value": 16985.879893336467}, {"lowerIsBetter": false, "replicates": [332.2126560052675, 335.8572606642178, 336.784457946914, 337.91360775108024, 338.18834796488443, 12443.253404795718, 12873.098883078164, 14898.627534311647, 15349.079055256678, 16701.0, 9550.188234000101, 9614.615415383376, 9745.074217949283, 9763.267754918392, 12286.201396909195, 4397.84607538737, 4510.797014134372, 4511.097780443908, 4515.322701594754, 4515.887102822423, 3516.7010804081556, 4482.305242687373, 4877.975610121945, 5260.842174734764, 5279.736013199325, 5217.113090774577, 5238.292830467895, 5246.658967167141, 5257.7108259045735, 5263.1578947368425, 5236.581073514129, 5476.0, 5773.39379365169, 5781.971090144564, 5785.421457854223, 5172.818951336722, 5176.922346164811, 5178.97410512945, 5181.5077567631015, 5183.50756678115, 18271.177796999167, 18367.448976530726, 18420.815791842044, 19281.710774338397, 19301.613967720707, 1818.8090250523637, 1861.8510519158435, 1936.0029584763859, 1940.4663717477665, 1964.2732189090127, 8087.514749115072, 8579.914200858037, 9711.465869377182, 10135.341202821794, 12471.438785254686, 1709.4102534625533, 1711.4352263752933, 1733.237375554752, 1750.0899532243163, 1791.8297761712747, 55209.856336608325, 57266.71366643182, 57461.42538574644, 57623.0, 58053.83892322172, 9594.763403787454, 9789.21686265097, 10003.899961000443, 10006.649767258112, 10079.69760907174, 12553.928601780786, 12563.874361256454, 12574.685632859251, 12584.559540416041, 12588.622341329774, 6494.512911531559, 6629.602223866584, 6681.098051763047, 6739.730410783513, 6759.594424334556, 6230.844228894314, 6238.500919926395, 6295.496360291166, 6304.684765761694, 6313.210848643919, 4063.7764922929523, 4169.541350451448, 4219.51475580306, 4223.113146239324, 4277.871663850089, 1620.4247492140357, 1620.935162593483, 1665.3172199398189, 1672.2725614357794, 1706.3260012295075, 868.9782755431038, 870.2211520689075, 901.3465237702613, 946.8485042393185, 947.1191791633709, 13204.471821127046, 14834.777478337834, 14932.402703891723, 14979.176145312113, 15270.770938435933, 852.5055467828645, 855.0081904990305, 876.1457578860687, 908.6683360573343, 921.1479381572019, 7501.28737769911, 8926.0, 8995.730128096167, 9009.054049324857, 9343.205827504738, 412633.0, 415853.9207304005, 425628.0, 428028.0, 428420.86843486864, 33787.83106084454, 66090.66954665292, 68401.65799170973, 68465.31534684589, 69174.31559581663, 1033.6330602636106, 1036.1658864614058, 1039.812833689943, 1054.7679510507676, 1056.4084112896803, 13821.447142114605, 14275.214863182624, 14290.214038227998, 14675.48635797742, 14809.555713328617, 30224.84887575548, 30528.542071869382, 33751.66248337485, 34585.30829383348, 35556.111097222776, 37532.81233593814, 38447.80776096102, 38497.6150238494, 39269.21461570695, 39640.80179599083, 8700.695475658322, 8960.238379737795, 9370.156685898302, 9476.905230947603, 9481.620735170793, 12696.555620553236, 16152.434664786677, 16415.91792041032, 16800.57998550046, 17047.744283835505, 12933.482660693844, 13036.608901732963, 13076.738465230832, 13590.45638174462, 13660.112092713942, 13034.804477933028, 13135.408906599036, 13210.867891320964, 13399.33003349809, 13693.178409295475, 13286.734265314446, 13441.260730659907, 13632.318384080954, 13929.721405572034, 13957.41787769266, 8580.957095214484, 8650.875386199754, 8923.15230053144, 9102.453852768855, 9127.22418594427, 9092.454452732858, 9182.908170918205, 11860.466279017637, 12994.740105198032, 13341.19952802835], "unit": "score", "name": "jquery.html", "value": 8020.76143423357}, {"lowerIsBetter": false, "replicates": [1420.5383250443608, 1594.6093207164242, 1687.460012795905, 1688.4934519644107, 1728.8271172882705, 6015.518758499321, 6995.650217489124, 7105.396041336493, 7108.431325493961, 7119.038929744483, 7093.1842838073635, 7215.81960450989, 7235.240299768528, 7246.23914488979, 7253.2021477637545, 2066.2561477867957, 2080.875147491148, 2092.403664955488, 2093.769685334612, 2097.0772859941576, 2094.256538928681, 2094.7381577302835, 2106.195659206389, 2117.3542069668747, 2122.5012122151284, 2070.9896450517804, 2079.2365817869627, 2079.6984437256588, 2113.682947557871, 2117.2483768262277, 2070.27540360874, 2074.4502706782714, 2095.9790402095937, 2105.315772373977, 2107.093949601678, 2031.8374530037709, 2077.0860821238757, 2078.2206672497814, 2080.6982987466886, 2090.2684060578827, 5917.52659787216, 6002.399760024006, 6969.919662452328, 7045.929540704578, 7091.503594748369, 4022.7787471689044, 4081.1837632473625, 4106.178764247133, 4112.403701463301, 4120.567340429272, 2632.8288661236957, 2635.736426357368, 2646.311958890683, 2695.770859476947, 2697.5818748094075, 858.3733874271817, 874.1695389380114, 876.4697358098323, 883.6332921837494, 885.0308911741632, 9830.066143716336, 9898.505074746306, 9904.059114384121, 9904.257180711475, 9951.054649808257, 3931.095847954929, 3950.8617198398492, 3979.567355751927, 3980.8208630611693, 4007.599240075969, 8541.231289184003, 8600.0, 8606.225439710332, 8618.956905215558, 8625.180607842245, 4344.500382455994, 4364.869053928323, 4367.781610919441, 4371.803268852909, 4372.672049596229, 3932.665723413542, 3949.150932549516, 3952.4071389291835, 3954.406838974177, 3958.485396898385, 3370.814605196738, 3378.273671160712, 3386.0349800306794, 3408.4035293823977, 3409.7272218222483, 1018.0989824005808, 1019.0, 1021.8211812932707, 1023.2223510132353, 1027.0499787696351, 1190.5237904838134, 1196.252342286071, 1209.056935590241, 1209.171717373602, 1223.2721530689225, 6734.494912881553, 6763.086983257197, 6811.182658080963, 6811.863762724718, 6814.863702725917, 397.066892801919, 398.2135282816472, 399.64431655826337, 400.31945692323166, 405.84941690308307, 4942.0, 5384.246205531229, 5406.053940560386, 5421.484958928895, 5433.4566543345345, 7278.308560686726, 8059.597020149086, 8294.543800090933, 8306.584670766437, 8314.584270786436, 8136.633851476698, 8165.836683266301, 8172.264496195252, 8208.753737387888, 8242.464239824392, 6335.524835637346, 6619.602823830585, 6620.404163625297, 6663.500237482208, 6664.666766661648, 6734.292899245705, 6820.761273355409, 6828.556143850634, 6832.145981752281, 6846.726130954706, 7322.0, 7491.88762168557, 7513.62431878393, 7620.504667196615, 7629.809254768453, 7603.315701586884, 7781.105172904962, 7858.528488290721, 7864.370850332074, 7895.84208315842, 6174.938250617436, 6235.469985051322, 6239.812805615838, 6315.305316415099, 6316.399942005502, 7057.611831349325, 7062.364387205176, 7078.539894906815, 7084.752033678797, 7114.9288507116335, 6407.775727849503, 6479.7408103675325, 6555.147830781968, 6636.13730215069, 6666.200055993312, 6639.402453779183, 6643.368879956396, 6674.0, 6710.966445167743, 6749.898751518633, 6759.763408280686, 6795.524313298021, 6796.184457865088, 6808.353206445379, 6809.489288303496, 6023.879522409615, 6186.969065154645, 6216.502679785516, 6246.937530624636, 6259.718312676032, 6306.810795676137, 6356.904646430215, 6358.77744278948, 6370.6814659267775, 6382.978723404419], "unit": "score", "name": "mootools.html", "value": 4033.5334864406436}, {"lowerIsBetter": false, "replicates": [285.384995335053, 288.1312841782027, 291.5175384738259, 292.3305630107055, 299.43108094620226, 5325.0, 5396.433374495676, 5454.590905682075, 5488.670679759212, 5551.611387202897, 5370.0, 5372.7313634318325, 5415.783368665258, 5424.0, 5428.809991650293, 4138.875833724993, 4139.77231252281, 4140.047789008529, 4141.0, 4149.336106223005, 3931.9803400983096, 4016.6585840203616, 4021.617946295112, 4084.611961863618, 4086.734362266458, 4021.5978402159694, 4036.3541833306676, 4051.918961620766, 4055.918881622366, 4076.388541718751, 3881.0879443330796, 4035.414864844581, 4083.4487344208596, 4102.0155162760875, 4103.610157035077, 3465.9653403465895, 3541.097020259843, 3544.521489598923, 3555.875544355961, 3558.946615800765, 5170.45710200431, 5187.351581052369, 5187.403448603422, 5202.0, 5206.062908676437, 4704.623630109583, 4706.811727530895, 4707.8587642370785, 4713.505081966378, 4714.787834547454, 5529.972350138223, 5638.35158956717, 5665.348484924204, 5673.0, 5673.035583950697, 3874.0121269076485, 3874.2638898609175, 3877.9612203878164, 3880.7477513961785, 3881.243157584272, 5908.4091590841, 6000.819975400745, 6003.27960644721, 6007.549433792483, 6008.128821320884, 3969.702272329529, 3975.6223158799867, 3976.4234186042863, 3979.6617287530307, 3981.124152686404, 3925.5878132796215, 4010.498687664042, 4012.117334186474, 4012.37808139733, 4013.819378127991, 4639.652026098056, 4651.86044418668, 4658.37111989877, 4660.091282199938, 4661.137689527448, 5658.377578466366, 5668.688222147739, 5670.829875103754, 5673.716314184274, 5676.347220069662, 1390.1311680199874, 1410.8730214280763, 1411.3225651687249, 1411.724713680843, 1413.0, 934.8504239321676, 935.5275585829113, 939.4128669581512, 959.6689142245914, 961.4711908450324, 4993.575546078624, 5007.424146223231, 5012.1980483122525, 5015.097282489187, 5022.573081288131, 5917.7928772493615, 5922.0, 5922.822315330547, 5928.377520360386, 5936.109583562414, 5002.074616196015, 5020.046191223656, 5023.447420783711, 5023.924641130386, 5028.647994640413, 3071.7235448809715, 3120.095172400024, 3183.0928185467033, 3209.1014515935576, 3210.807351558914, 5496.5053145216325, 5536.7819079802375, 5623.8875222496135, 5628.352739435018, 5647.040003199466, 6041.154238406627, 6090.208272924491, 6109.75560977556, 6117.051856962178, 6122.510199184055, 5567.888642227133, 5585.748641311069, 5587.525060369914, 5616.522595579339, 5625.774969001194, 5795.246617939641, 5911.793087241926, 5936.614120082354, 5956.285245770535, 5972.5520585955355, 5802.245708057925, 5808.244928159313, 5812.447817457335, 5813.215215945793, 5813.970930145322, 5610.0, 5610.298712660918, 5620.269364982526, 5621.52217061538, 5622.803201887914, 3860.4788353571907, 3908.5309762828642, 3910.2961466936226, 3914.041059940333, 3927.7054220932973, 5913.615614985012, 5914.467697907209, 5917.822465326047, 5919.0, 5921.792737254175, 5689.0, 5698.1452782083015, 5699.743511542073, 5713.228714123707, 5713.371529131875, 5662.745176466987, 5676.9432305678065, 5690.459406356389, 5699.629524080921, 5703.771849125988, 5412.945870541244, 5562.638428502134, 5569.359523654832, 5575.7490912909825, 5578.60949733531, 5625.718714064198, 5723.313202415737, 5734.770609175586, 5736.512396446182, 5737.627054241461, 5506.944930550803, 5554.694491802989, 5590.273264475592, 5595.104783234663, 5600.691961942132], "unit": "score", "name": "prototype.html", "value": 4224.92277256673}, {"lowerIsBetter": false, "replicates": [728.6247582495016, 734.562935053643, 740.174824134065, 765.4985984180361, 777.114089937471, 10433.530491127898, 10600.045995860371, 10704.571817127326, 11982.520699172022, 12433.502659893617, 13543.187408755457, 13642.0, 13654.453821847113, 13663.521776737816, 13678.042537022413, 633.1547384242042, 639.9104125422444, 782.6791015683557, 795.3796039089519, 796.0606484348466, 792.6274650914061, 794.9761507154766, 802.8675268580677, 803.0684406088938, 804.0632662947688, 819.2381085590429, 820.9630566624517, 826.5454000299841, 827.4828232354778, 827.8137419080689, 654.2345455816682, 661.2461793555351, 661.3485716569152, 661.7716887673745, 813.5444151275275, 491.06207144354113, 610.9053096770008, 616.9290531588834, 622.3247776162868, 623.4638211138417, 10664.840027399596, 10829.350238985686, 10944.288621239593, 10953.123750099972, 10997.560097596086, 10625.362478251329, 10669.679909602646, 10710.839337409867, 10901.236913415985, 11300.378479183642, 9179.678711245075, 9188.402753821047, 9325.90674093264, 9329.16037556623, 9340.3461757677, 3081.075677296802, 3090.6909309069138, 3141.623005239363, 3199.8240096794666, 3201.487761958099, 40706.59293407058, 40752.184956300705, 40845.97885052868, 41068.58931410708, 41206.0, 8561.357898157663, 8562.828743425096, 8595.312375009984, 8606.526641034803, 8691.782705432415, 15280.694386112216, 15310.851686123586, 15336.616584585252, 15340.769888451681, 15357.769633455508, 6099.878002440015, 6188.783392581238, 6204.658743769046, 6234.9688251558455, 6259.0, 3377.59468863733, 3454.7063499602814, 3470.219200679827, 3488.598811136701, 3533.7526373153632, 2482.4414506736202, 2526.507331070424, 2536.2898388451263, 2595.5068536977915, 2600.8309459885427, 468.1432977650891, 468.8476245220317, 476.65442554147967, 477.8375352380138, 479.04908756119073, 388.98249578768457, 449.8290649553214, 475.3250384454083, 490.70312460961316, 501.64132645158884, 7056.964715176391, 7202.315780000997, 7445.25547445262, 7477.1775104738435, 7542.170361260257, 434.56543456543454, 477.6274505885415, 487.92899585410396, 545.5935328180531, 564.7627996241588, 3259.362341890131, 3377.1725927148004, 3414.163529935132, 3493.074335301147, 3520.7887526748477, 49844.0, 50267.0, 50291.74854125706, 50303.24545131826, 50399.49600503948, 43499.56500435018, 43645.127097458506, 44238.33642495365, 44359.0, 44514.554854451686, 39216.6078339213, 40208.59791402049, 40412.1917561653, 40426.79786601107, 40683.796581016904, 6651.547998441504, 6784.86430271382, 6894.448444124559, 7021.122359705037, 7204.4956853019785, 31249.687503125588, 32148.517772232968, 32232.677673222966, 33370.165745856546, 33524.664753352154, 38798.6120138795, 39763.204735905696, 39850.0, 39898.40152397774, 40791.59208407878, 9244.583993720164, 9480.52597370115, 9749.220062395134, 9864.92836504342, 9875.753106172404, 11018.559257629606, 11020.5040773167, 11050.33697978124, 11235.66293011211, 11278.60524881658, 9890.406575605486, 10362.378257304586, 10606.310589811637, 10955.397453139834, 11012.559497620005, 10637.095846853104, 10860.0, 11256.099512039182, 11295.661130166109, 11297.26567773125, 8544.2310192083, 9924.801503969737, 10295.918928512398, 10358.119559837498, 11248.775024499628, 8682.131786821268, 8716.3462740296, 8797.428167169113, 8916.31276542593, 9128.954355228447, 8074.919250807417, 8497.320214382711, 8690.261327786957, 8754.387192896436, 8831.779205519913], "unit": "score", "name": "yui.html", "value": 5511.851619991714}], "name": "dromaeo_css", "value": 6252.468281617732}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 113.0, 106.0, 113.0, 113.0, 112.0, 104.0, 112.0, 114.0, 119.0], "unit": "score", "name": "ai-astar", "value": 111.7}, {"lowerIsBetter": true, "replicates": [161.0, 129.0, 152.0, 167.0, 135.0, 157.0, 151.0, 135.0, 168.0, 139.0], "unit": "score", "name": "audio-beat-detection", "value": 149.4}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 162.0, 161.0, 162.0, 162.0, 163.0, 169.0, 162.0, 162.0], "unit": "score", "name": "audio-dft", "value": 162.6}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 80.0, 94.0, 72.0, 71.0, 72.0, 109.0, 90.0, 72.0], "unit": "score", "name": "audio-fft", "value": 80.6}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 130.0, 95.0, 96.0, 95.0, 130.0, 95.0, 95.0, 134.0], "unit": "score", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.2}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 71.0, 71.0, 70.0, 70.0, 70.0, 79.0, 68.0, 68.0], "unit": "score", "name": "json-parse-financial", "value": 71.0}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0, 69.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.5}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 84.0, 81.0, 81.0, 82.0, 82.0, 82.0, 82.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.0}, {"lowerIsBetter": true, "replicates": [127.0, 129.0, 137.0, 119.0, 126.0, 122.0, 129.0, 129.0, 129.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.9}, {"lowerIsBetter": true, "replicates": [135.0, 148.0, 152.0, 146.0, 145.0, 134.0, 150.0, 148.0, 147.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.9}, {"lowerIsBetter": true, "replicates": [73.0, 69.0, 70.0, 69.0, 70.0, 68.0, 69.0, 68.0, 69.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.4}], "name": "kraken", "value": 1524.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [169.00456312320432], "unit": "score", "name": "Richards", "value": 208.88193400000003}, {"lowerIsBetter": false, "replicates": [192.8268414963363], "unit": "score", "name": "DeltaBlue", "value": 342.8879479999999}, {"lowerIsBetter": false, "replicates": [280.97780275358247], "unit": "score", "name": "Encrypt", "value": 947.3381789999996}, {"lowerIsBetter": false, "replicates": [5218.75], "unit": "score", "name": "Decrypt", "value": 51.00474251497002}, {"lowerIsBetter": false, "replicates": [2087.6826722338205], "unit": "score", "name": "RayTrace", "value": 354.454731}, {"lowerIsBetter": false, "replicates": [798.0845969672786], "unit": "score", "name": "Earley", "value": 835.078139}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [28027.777777777777], "unit": "score", "name": "RegExp", "value": 32.502933597621436}, {"lowerIsBetter": false, "replicates": [607.90273556231], "unit": "score", "name": "Splay", "value": 134.052695}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19458.898688623187}]} 07:50:49 INFO - RETURN: dromaeo_css: 6252.47 07:50:49 INFO - RETURN: kraken: 1524.30 07:50:49 INFO - RETURN: v8_7: 19458.90 07:50:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19458.90"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1524.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6252.47"}}} 07:50:49 INFO - Return code: 0 07:50:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:50:49 ERROR - # TBPL SUCCESS # 07:50:49 INFO - Running post-action listener: _resource_record_post_action 07:50:49 INFO - Running post-run listener: _resource_record_post_run 07:50:51 INFO - Total resource usage - Wall time: 1235s; CPU: 13.0%; Read bytes: 2453504; Write bytes: 555081728; Read time: 1168; Write time: 393952 07:50:51 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234098688; Read time: 0; Write time: 216348 07:50:51 INFO - run-tests - Wall time: 1220s; CPU: 13.0%; Read bytes: 2453504; Write bytes: 320983040; Read time: 1168; Write time: 177604 07:50:51 INFO - Running post-run listener: _upload_blobber_files 07:50:51 INFO - Blob upload gear active. 07:50:51 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:50:51 INFO - Copying logs to upload dir... 07:50:51 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1272.491740 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-18 07:50:50.661005) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:50:50.664856) ========= 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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009806 build_url: 'https://queue.taskcluster.net/v1/task/gT50-F44RAaEMczUnLMBww/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:50:50.715339) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:50:50.715664) ========= 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=fce1c2174958ee92775f70ae000001a3-1450452450.868576-2002686545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004616 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:50:50.764977) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 07:50:50.765254) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 07:50:50.765566) ========= ========= Total master_lag: 0.23 =========