builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-058 starttime: 1450453080.07 results: success (0) buildid: 20151218061452 builduid: 49a0a250f9584b9da9cf1c6107627c17 revision: 8e54ec9a12f2af42dca48b957a88d2ce13e11d09 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.072936) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.073412) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.073734) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006195 basedir: '/builds/slave/test' ========= master_lag: 0.10 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.183090) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.183365) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.248208) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.248498) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.297689) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.298029) ========= 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:38:00.298372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.298695) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-18 07:38:00-- 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% 77.3M=0s 2015-12-18 07:38:00 (77.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116418 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.428872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.429207) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020860 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.480568) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:00.480878) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8e54ec9a12f2af42dca48b957a88d2ce13e11d09 --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 8e54ec9a12f2af42dca48b957a88d2ce13e11d09 --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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-18 07:38:00,703 truncating revision to first 12 chars 2015-12-18 07:38:00,703 Setting DEBUG logging. 2015-12-18 07:38:00,703 attempt 1/10 2015-12-18 07:38:00,703 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8e54ec9a12f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-18 07:38:01,231 unpacking tar archive at: mozilla-central-8e54ec9a12f2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.820227 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:01.313389) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:01.313674) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:01.320925) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 07:38:01.321227) ========= 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:38:01.321627) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-18 07:38:01.321915) ========= /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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:38:01 INFO - MultiFileLogger online at 20151218 07:38:01 in /builds/slave/test 07:38:01 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:38:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:38:01 INFO - {'append_to_log': False, 07:38:01 INFO - 'base_work_dir': '/builds/slave/test', 07:38:01 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:38:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:38:01 INFO - 'branch': 'Firefox-Non-PGO', 07:38:01 INFO - 'buildbot_json_path': 'buildprops.json', 07:38:01 INFO - 'config_files': ('talos/linux_config.py',), 07:38:01 INFO - 'default_actions': ('clobber', 07:38:01 INFO - 'read-buildbot-config', 07:38:01 INFO - 'download-and-extract', 07:38:01 INFO - 'populate-webroot', 07:38:01 INFO - 'create-virtualenv', 07:38:01 INFO - 'install', 07:38:01 INFO - 'run-tests'), 07:38:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:38:01 INFO - 'download_minidump_stackwalk': True, 07:38:01 INFO - 'download_symbols': 'ondemand', 07:38:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:38:01 INFO - 'tooltool.py': '/tools/tooltool.py', 07:38:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:38:01 INFO - '/tools/misc-python/virtualenv.py')}, 07:38:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:38:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:38:01 INFO - 'installer_path': 'installer.exe', 07:38:01 INFO - 'log_level': 'info', 07:38:01 INFO - 'log_name': 'talos', 07:38:01 INFO - 'log_to_console': True, 07:38:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:38:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:38:01 INFO - 'opt_config_files': (), 07:38:01 INFO - 'pip_index': False, 07:38:01 INFO - 'sps_profile': False, 07:38:01 INFO - 'sps_profile_interval': 0, 07:38:01 INFO - 'suite': 'dromaeojs', 07:38:01 INFO - 'system_bits': '64', 07:38:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:38:01 INFO - 'title': 'talos-linux64-ix-058', 07:38:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:38:01 INFO - 'use_talos_json': True, 07:38:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:38:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:38:01 INFO - 'work_dir': 'build'} 07:38:01 INFO - ##### 07:38:01 INFO - ##### Running clobber step. 07:38:01 INFO - ##### 07:38:01 INFO - Running pre-action listener: _resource_record_pre_action 07:38:01 INFO - Running main action method: clobber 07:38:01 INFO - rmtree: /builds/slave/test/build 07:38:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:38:02 INFO - Running post-action listener: _resource_record_post_action 07:38:02 INFO - ##### 07:38:02 INFO - ##### Running read-buildbot-config step. 07:38:02 INFO - ##### 07:38:02 INFO - Running pre-action listener: _resource_record_pre_action 07:38:02 INFO - Running main action method: read_buildbot_config 07:38:02 INFO - Using buildbot properties: 07:38:02 INFO - { 07:38:02 INFO - "project": "", 07:38:02 INFO - "product": "firefox", 07:38:02 INFO - "script_repo_revision": "production", 07:38:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:38:02 INFO - "repository": "", 07:38:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:38:02 INFO - "buildid": "20151218061452", 07:38:02 INFO - "pgo_build": "False", 07:38:02 INFO - "basedir": "/builds/slave/test", 07:38:02 INFO - "buildnumber": 411, 07:38:02 INFO - "slavename": "talos-linux64-ix-058", 07:38:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:38:02 INFO - "platform": "ubuntu64_hw", 07:38:02 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:38:02 INFO - "branch": "mozilla-central", 07:38:02 INFO - "repo_path": "mozilla-central", 07:38:02 INFO - "revision": "8e54ec9a12f2af42dca48b957a88d2ce13e11d09", 07:38:02 INFO - "stage_platform": "linux64", 07:38:02 INFO - "builduid": "49a0a250f9584b9da9cf1c6107627c17", 07:38:02 INFO - "slavebuilddir": "test" 07:38:02 INFO - } 07:38:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 07:38:02 INFO - Running post-action listener: _resource_record_post_action 07:38:02 INFO - ##### 07:38:02 INFO - ##### Running download-and-extract step. 07:38:02 INFO - ##### 07:38:02 INFO - Running pre-action listener: _resource_record_pre_action 07:38:02 INFO - Running main action method: download_and_extract 07:38:02 INFO - mkdir: /builds/slave/test/build/tests 07:38: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:38:02 INFO - https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:38:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/test_packages.json 07:38:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/test_packages.json 07:38:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 07:38:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 07:38:03 INFO - Downloaded 1302 bytes. 07:38:03 INFO - Reading from file /builds/slave/test/build/test_packages.json 07:38:03 INFO - Using the following test package requirements: 07:38:03 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:38:03 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:38:03 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:38:03 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:38:03 INFO - u'jsshell-linux-x86_64.zip'], 07:38:03 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:38:03 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:38:03 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:38:03 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:38:03 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:38:03 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:38:03 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:38:03 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:38:03 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:38:03 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 07:38:03 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 07:38:03 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:38:03 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:03 INFO - https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:38:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:38:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:38:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:05 INFO - Downloaded 21870817 bytes. 07:38:05 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:38:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:38:05 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:38:06 INFO - Return code: 0 07:38:06 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:38:06 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:38:06 INFO - https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:38:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:38:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:38:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:08 INFO - Downloaded 21870817 bytes. 07:38:08 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:38:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 07:38:08 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:38:09 INFO - Return code: 0 07:38: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:38:09 INFO - https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:38:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:38:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:38:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:11 INFO - Downloaded 11132039 bytes. 07:38:11 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:38:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 07:38:11 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:38:11 INFO - Return code: 0 07:38: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:38:11 INFO - https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:38:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:38:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:38:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:38:13 INFO - Downloaded 53018245 bytes. 07:38:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:38:13 INFO - mkdir: /builds/slave/test/properties 07:38:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:38:13 INFO - Writing to file /builds/slave/test/properties/build_url 07:38:13 INFO - Contents: 07:38:13 INFO - build_url:https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 07:38:13 INFO - Running post-action listener: _resource_record_post_action 07:38:13 INFO - Running post-action listener: set_extra_try_arguments 07:38:13 INFO - ##### 07:38:13 INFO - ##### Running populate-webroot step. 07:38:13 INFO - ##### 07:38:13 INFO - Running pre-action listener: _resource_record_pre_action 07:38:13 INFO - Running main action method: populate_webroot 07:38:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:38:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:38:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:38:13 INFO - '--tppagecycles', 07:38:13 INFO - '7'], 07:38:13 INFO - 'tests': ['tsvgm']}}, 07:38:13 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:38:13 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:38:13 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:38:13 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:38:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:38:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:38:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'tests': ['damp', 'tps']}, 07:38:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['damp', 'tps']}, 07:38:13 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:38:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:38:13 INFO - 'other': {'tests': ['a11yr', 07:38:13 INFO - 'ts_paint', 07:38:13 INFO - 'tpaint', 07:38:13 INFO - 'sessionrestore', 07:38:13 INFO - 'sessionrestore_no_auto_restore']}, 07:38:13 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['a11yr', 07:38:13 INFO - 'ts_paint', 07:38:13 INFO - 'tpaint', 07:38:13 INFO - 'sessionrestore', 07:38:13 INFO - 'sessionrestore_no_auto_restore']}, 07:38:13 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['a11yr', 07:38:13 INFO - 'ts_paint', 07:38:13 INFO - 'tpaint', 07:38:13 INFO - 'sessionrestore', 07:38:13 INFO - 'sessionrestore_no_auto_restore']}, 07:38:13 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['a11yr', 07:38:13 INFO - 'ts_paint', 07:38:13 INFO - 'tpaint', 07:38:13 INFO - 'sessionrestore', 07:38:13 INFO - 'sessionrestore_no_auto_restore']}, 07:38:13 INFO - 'other_l64': {'tests': ['a11yr', 07:38:13 INFO - 'ts_paint', 07:38:13 INFO - 'tpaint', 07:38:13 INFO - 'sessionrestore', 07:38:13 INFO - 'sessionrestore_no_auto_restore']}, 07:38:13 INFO - 'other_nol64': {'tests': ['a11yr', 07:38:13 INFO - 'ts_paint', 07:38:13 INFO - 'tpaint', 07:38:13 INFO - 'sessionrestore', 07:38:13 INFO - 'sessionrestore_no_auto_restore']}, 07:38:13 INFO - 'svgr': {'tests': ['tsvgx', 07:38:13 INFO - 'tsvgr_opacity', 07:38:13 INFO - 'tart', 07:38:13 INFO - 'tscrollx', 07:38:13 INFO - 'cart']}, 07:38:13 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['tsvgx', 07:38:13 INFO - 'tsvgr_opacity', 07:38:13 INFO - 'tart', 07:38:13 INFO - 'tscrollx', 07:38:13 INFO - 'cart']}, 07:38:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'tests': ['tp5o']}, 07:38:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'talos_options': ['--e10s'], 07:38:13 INFO - 'tests': ['tp5o']}, 07:38:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'talos_options': ['--xperf_path', 07:38:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:38:13 INFO - 'tests': ['tp5n']}, 07:38:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:13 INFO - 'talos_options': ['--e10s', 07:38:13 INFO - '--xperf_path', 07:38:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:38:13 INFO - 'tests': ['tp5n']}}, 07:38:13 INFO - 'talos.zip': {'path': '', 07:38:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:38:13 INFO - Running post-action listener: _resource_record_post_action 07:38:13 INFO - ##### 07:38:13 INFO - ##### Running create-virtualenv step. 07:38:13 INFO - ##### 07:38:13 INFO - Running pre-action listener: _resource_record_pre_action 07:38:13 INFO - Running main action method: create_virtualenv 07:38:13 INFO - Creating virtualenv /builds/slave/test/build/venv 07:38:13 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:38:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:38:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:38:13 INFO - Using real prefix '/usr' 07:38:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:38:14 INFO - Installing distribute.............................................................................................................................................................................................done. 07:38:16 INFO - Installing pip.................done. 07:38:16 INFO - Return code: 0 07:38:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:16 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:16 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:38:16 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:38:16 INFO - Using env: {'DISPLAY': ':0', 07:38:16 INFO - 'HOME': '/home/cltbld', 07:38:16 INFO - 'LANG': 'en_US.UTF-8', 07:38:16 INFO - 'LANGUAGE': 'en_US:en', 07:38:16 INFO - 'LOGNAME': 'cltbld', 07:38:16 INFO - 'MAIL': '/var/mail/cltbld', 07:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:16 INFO - 'MOZ_NO_REMOTE': '1', 07:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:16 INFO - 'NO_EM_RESTART': '1', 07:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:16 INFO - 'PWD': '/builds/slave/test', 07:38:16 INFO - 'SHELL': '/bin/bash', 07:38:16 INFO - 'SHLVL': '1', 07:38:16 INFO - 'TERM': 'linux', 07:38:16 INFO - 'TMOUT': '86400', 07:38:16 INFO - 'USER': 'cltbld', 07:38:16 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:16 INFO - '_': '/tools/buildbot/bin/python'} 07:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:16 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:38:16 INFO - Cleaning up... 07:38:16 INFO - Return code: 0 07:38:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:16 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:16 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:38:16 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:38:16 INFO - Using env: {'DISPLAY': ':0', 07:38:16 INFO - 'HOME': '/home/cltbld', 07:38:16 INFO - 'LANG': 'en_US.UTF-8', 07:38:16 INFO - 'LANGUAGE': 'en_US:en', 07:38:16 INFO - 'LOGNAME': 'cltbld', 07:38:16 INFO - 'MAIL': '/var/mail/cltbld', 07:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:16 INFO - 'MOZ_NO_REMOTE': '1', 07:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:16 INFO - 'NO_EM_RESTART': '1', 07:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:16 INFO - 'PWD': '/builds/slave/test', 07:38:16 INFO - 'SHELL': '/bin/bash', 07:38:16 INFO - 'SHLVL': '1', 07:38:16 INFO - 'TERM': 'linux', 07:38:16 INFO - 'TMOUT': '86400', 07:38:16 INFO - 'USER': 'cltbld', 07:38:16 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:16 INFO - '_': '/tools/buildbot/bin/python'} 07:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:16 INFO - Downloading/unpacking psutil>=0.7.1 07:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:18 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 07:38:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:38:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:38:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:38:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:38:18 INFO - Installing collected packages: psutil 07:38:18 INFO - Running setup.py install for psutil 07:38:18 INFO - building 'psutil._psutil_linux' extension 07:38:18 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:38:18 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:38:18 INFO - building 'psutil._psutil_posix' extension 07:38:18 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:38:18 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:38:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:38:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:38:18 INFO - Successfully installed psutil 07:38:18 INFO - Cleaning up... 07:38:19 INFO - Return code: 0 07:38:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:19 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:19 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:38:19 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:38:19 INFO - Using env: {'DISPLAY': ':0', 07:38:19 INFO - 'HOME': '/home/cltbld', 07:38:19 INFO - 'LANG': 'en_US.UTF-8', 07:38:19 INFO - 'LANGUAGE': 'en_US:en', 07:38:19 INFO - 'LOGNAME': 'cltbld', 07:38:19 INFO - 'MAIL': '/var/mail/cltbld', 07:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:19 INFO - 'MOZ_NO_REMOTE': '1', 07:38:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:19 INFO - 'NO_EM_RESTART': '1', 07:38:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:19 INFO - 'PWD': '/builds/slave/test', 07:38:19 INFO - 'SHELL': '/bin/bash', 07:38:19 INFO - 'SHLVL': '1', 07:38:19 INFO - 'TERM': 'linux', 07:38:19 INFO - 'TMOUT': '86400', 07:38:19 INFO - 'USER': 'cltbld', 07:38:19 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:19 INFO - '_': '/tools/buildbot/bin/python'} 07:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:38:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:38:21 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:38:21 INFO - Installing collected packages: mozsystemmonitor 07:38:21 INFO - Running setup.py install for mozsystemmonitor 07:38:21 INFO - Successfully installed mozsystemmonitor 07:38:21 INFO - Cleaning up... 07:38:21 INFO - Return code: 0 07:38:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:21 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:21 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:38:21 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:38:21 INFO - Using env: {'DISPLAY': ':0', 07:38:21 INFO - 'HOME': '/home/cltbld', 07:38:21 INFO - 'LANG': 'en_US.UTF-8', 07:38:21 INFO - 'LANGUAGE': 'en_US:en', 07:38:21 INFO - 'LOGNAME': 'cltbld', 07:38:21 INFO - 'MAIL': '/var/mail/cltbld', 07:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:21 INFO - 'MOZ_NO_REMOTE': '1', 07:38:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:21 INFO - 'NO_EM_RESTART': '1', 07:38:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:21 INFO - 'PWD': '/builds/slave/test', 07:38:21 INFO - 'SHELL': '/bin/bash', 07:38:21 INFO - 'SHLVL': '1', 07:38:21 INFO - 'TERM': 'linux', 07:38:21 INFO - 'TMOUT': '86400', 07:38:21 INFO - 'USER': 'cltbld', 07:38:21 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:21 INFO - '_': '/tools/buildbot/bin/python'} 07:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:21 INFO - Downloading/unpacking blobuploader==1.2.4 07:38:21 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:38:21 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:38:21 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:38:21 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:38:21 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:38:21 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:38:23 INFO - Downloading blobuploader-1.2.4.tar.gz 07:38:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:38:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:38:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:38:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:38:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:23 INFO - Downloading docopt-0.6.1.tar.gz 07:38:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:38:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:38:23 INFO - Installing collected packages: blobuploader, requests, docopt 07:38:23 INFO - Running setup.py install for blobuploader 07:38:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:38:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:38:23 INFO - Running setup.py install for requests 07:38:24 INFO - Running setup.py install for docopt 07:38:24 INFO - Successfully installed blobuploader requests docopt 07:38:24 INFO - Cleaning up... 07:38:24 INFO - Return code: 0 07:38:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:24 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:24 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:38:24 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:38:24 INFO - Using env: {'DISPLAY': ':0', 07:38:24 INFO - 'HOME': '/home/cltbld', 07:38:24 INFO - 'LANG': 'en_US.UTF-8', 07:38:24 INFO - 'LANGUAGE': 'en_US:en', 07:38:24 INFO - 'LOGNAME': 'cltbld', 07:38:24 INFO - 'MAIL': '/var/mail/cltbld', 07:38:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:24 INFO - 'MOZ_NO_REMOTE': '1', 07:38:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:24 INFO - 'NO_EM_RESTART': '1', 07:38:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:24 INFO - 'PWD': '/builds/slave/test', 07:38:24 INFO - 'SHELL': '/bin/bash', 07:38:24 INFO - 'SHLVL': '1', 07:38:24 INFO - 'TERM': 'linux', 07:38:24 INFO - 'TMOUT': '86400', 07:38:24 INFO - 'USER': 'cltbld', 07:38:24 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:24 INFO - '_': '/tools/buildbot/bin/python'} 07:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:38:24 INFO - Running setup.py (path:/tmp/pip-V8JVbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:38:24 INFO - Running setup.py (path:/tmp/pip-yPnDjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:38:24 INFO - Running setup.py (path:/tmp/pip-dxA_HL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:38:24 INFO - Running setup.py (path:/tmp/pip-Yy3F15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:38:24 INFO - Running setup.py (path:/tmp/pip-gs57Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:38:24 INFO - Running setup.py (path:/tmp/pip-4xK_0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:38:25 INFO - Running setup.py (path:/tmp/pip-wXKeUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:38:25 INFO - Running setup.py (path:/tmp/pip-OV6PgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:38:25 INFO - Running setup.py (path:/tmp/pip-JgwAPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:38:25 INFO - Running setup.py (path:/tmp/pip-TjAAJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:38:25 INFO - Running setup.py (path:/tmp/pip-ujTJ7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:38:25 INFO - Running setup.py (path:/tmp/pip-8YFXWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:38:25 INFO - Running setup.py (path:/tmp/pip-XvmlRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:38:25 INFO - Running setup.py (path:/tmp/pip-a7uBWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:25 INFO - Running setup.py (path:/tmp/pip-UAEZxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:38:25 INFO - Running setup.py (path:/tmp/pip-efUjAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:38:25 INFO - Running setup.py (path:/tmp/pip-nlIDHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:38:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:38:25 INFO - Running setup.py install for manifestparser 07:38:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:38:25 INFO - Running setup.py install for mozcrash 07:38:26 INFO - Running setup.py install for mozdebug 07:38:26 INFO - Running setup.py install for mozdevice 07:38:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Running setup.py install for mozfile 07:38:26 INFO - Running setup.py install for mozhttpd 07:38:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Running setup.py install for mozinfo 07:38:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Running setup.py install for mozInstall 07:38:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Running setup.py install for mozleak 07:38:26 INFO - Running setup.py install for mozlog 07:38:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Running setup.py install for moznetwork 07:38:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:38:26 INFO - Running setup.py install for mozprocess 07:38:27 INFO - Running setup.py install for mozprofile 07:38:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:38:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:38:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:38:27 INFO - Running setup.py install for mozrunner 07:38:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:38:27 INFO - Running setup.py install for mozscreenshot 07:38:27 INFO - Running setup.py install for moztest 07:38:27 INFO - Running setup.py install for mozversion 07:38:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:38:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:38:27 INFO - Cleaning up... 07:38:27 INFO - Return code: 0 07:38:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:27 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:27 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:38:27 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:38:27 INFO - Using env: {'DISPLAY': ':0', 07:38:27 INFO - 'HOME': '/home/cltbld', 07:38:27 INFO - 'LANG': 'en_US.UTF-8', 07:38:27 INFO - 'LANGUAGE': 'en_US:en', 07:38:27 INFO - 'LOGNAME': 'cltbld', 07:38:27 INFO - 'MAIL': '/var/mail/cltbld', 07:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:27 INFO - 'MOZ_NO_REMOTE': '1', 07:38:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:27 INFO - 'NO_EM_RESTART': '1', 07:38:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:27 INFO - 'PWD': '/builds/slave/test', 07:38:27 INFO - 'SHELL': '/bin/bash', 07:38:27 INFO - 'SHLVL': '1', 07:38:27 INFO - 'TERM': 'linux', 07:38:27 INFO - 'TMOUT': '86400', 07:38:27 INFO - 'USER': 'cltbld', 07:38:27 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:27 INFO - '_': '/tools/buildbot/bin/python'} 07:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:38:27 INFO - Running setup.py (path:/tmp/pip-eVBvWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:38:27 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:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:38:27 INFO - Running setup.py (path:/tmp/pip-S9wcCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:38:28 INFO - Running setup.py (path:/tmp/pip-5Us4iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:38:28 INFO - Running setup.py (path:/tmp/pip-nlRVmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:38:28 INFO - Running setup.py (path:/tmp/pip-lWWK4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:38:28 INFO - Running setup.py (path:/tmp/pip-tjMrCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:38:28 INFO - Running setup.py (path:/tmp/pip-XRLzn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:38:28 INFO - Running setup.py (path:/tmp/pip-BBKQkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:38:28 INFO - Running setup.py (path:/tmp/pip-0Lytfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:38:28 INFO - Running setup.py (path:/tmp/pip-31j1QB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:38:28 INFO - Running setup.py (path:/tmp/pip-5Oe3Lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:38:28 INFO - Running setup.py (path:/tmp/pip-qyQSO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:38:28 INFO - Running setup.py (path:/tmp/pip-GsrorS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:38:28 INFO - Running setup.py (path:/tmp/pip-9J0vOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:28 INFO - Running setup.py (path:/tmp/pip-qRlDbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:28 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:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:38:28 INFO - Running setup.py (path:/tmp/pip-oWvZp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:38:29 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:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:38:29 INFO - Running setup.py (path:/tmp/pip-t2RQ1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:38:29 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:38:29 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:38:29 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:38:29 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:38:29 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:38:29 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:38:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:30 INFO - Downloading blessings-1.5.1.tar.gz 07:38:30 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:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:38:30 INFO - Installing collected packages: blessings 07:38:30 INFO - Running setup.py install for blessings 07:38:30 INFO - Successfully installed blessings 07:38:30 INFO - Cleaning up... 07:38:31 INFO - Return code: 0 07:38:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:38:31 INFO - Reading from file tmpfile_stdout 07:38:31 INFO - Current package versions: 07:38:31 INFO - argparse == 1.2.1 07:38:31 INFO - blessings == 1.5.1 07:38:31 INFO - blobuploader == 1.2.4 07:38:31 INFO - docopt == 0.6.1 07:38:31 INFO - manifestparser == 1.1 07:38:31 INFO - mozInstall == 1.12 07:38:31 INFO - mozcrash == 0.16 07:38:31 INFO - mozdebug == 0.1 07:38:31 INFO - mozdevice == 0.47 07:38:31 INFO - mozfile == 1.2 07:38:31 INFO - mozhttpd == 0.7 07:38:31 INFO - mozinfo == 0.9 07:38:31 INFO - mozleak == 0.1 07:38:31 INFO - mozlog == 3.1 07:38:31 INFO - moznetwork == 0.27 07:38:31 INFO - mozprocess == 0.22 07:38:31 INFO - mozprofile == 0.27 07:38:31 INFO - mozrunner == 6.11 07:38:31 INFO - mozscreenshot == 0.1 07:38:31 INFO - mozsystemmonitor == 0.0 07:38:31 INFO - moztest == 0.7 07:38:31 INFO - mozversion == 1.4 07:38:31 INFO - psutil == 3.1.1 07:38:31 INFO - requests == 1.2.3 07:38:31 INFO - wsgiref == 0.1.2 07:38:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38:31 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:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:31 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:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:31 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:31 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:38:31 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:38:31 INFO - Using env: {'DISPLAY': ':0', 07:38:31 INFO - 'HOME': '/home/cltbld', 07:38:31 INFO - 'LANG': 'en_US.UTF-8', 07:38:31 INFO - 'LANGUAGE': 'en_US:en', 07:38:31 INFO - 'LOGNAME': 'cltbld', 07:38:31 INFO - 'MAIL': '/var/mail/cltbld', 07:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:31 INFO - 'MOZ_NO_REMOTE': '1', 07:38:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:31 INFO - 'NO_EM_RESTART': '1', 07:38:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:31 INFO - 'PWD': '/builds/slave/test', 07:38:31 INFO - 'SHELL': '/bin/bash', 07:38:31 INFO - 'SHLVL': '1', 07:38:31 INFO - 'TERM': 'linux', 07:38:31 INFO - 'TMOUT': '86400', 07:38:31 INFO - 'USER': 'cltbld', 07:38:31 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:31 INFO - '_': '/tools/buildbot/bin/python'} 07:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 INFO - Cleaning up... 07:38:31 INFO - Return code: 0 07:38:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:38:31 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:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:31 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:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:31 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:31 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:38:31 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:38:31 INFO - Using env: {'DISPLAY': ':0', 07:38:31 INFO - 'HOME': '/home/cltbld', 07:38:31 INFO - 'LANG': 'en_US.UTF-8', 07:38:31 INFO - 'LANGUAGE': 'en_US:en', 07:38:31 INFO - 'LOGNAME': 'cltbld', 07:38:31 INFO - 'MAIL': '/var/mail/cltbld', 07:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:31 INFO - 'MOZ_NO_REMOTE': '1', 07:38:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:31 INFO - 'NO_EM_RESTART': '1', 07:38:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:31 INFO - 'PWD': '/builds/slave/test', 07:38:31 INFO - 'SHELL': '/bin/bash', 07:38:31 INFO - 'SHLVL': '1', 07:38:31 INFO - 'TERM': 'linux', 07:38:31 INFO - 'TMOUT': '86400', 07:38:31 INFO - 'USER': 'cltbld', 07:38:31 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:31 INFO - '_': '/tools/buildbot/bin/python'} 07:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:31 INFO - Downloading/unpacking jsonschema 07:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:31 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:38:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:38:33 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:38:33 INFO - Downloading/unpacking functools32 (from jsonschema) 07:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:33 INFO - Downloading functools32-3.2.3-2.tar.gz 07:38:33 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:38:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:38:33 INFO - warning: no files found matching '*.txt' 07:38:33 INFO - no previously-included directories found matching 'build' 07:38:33 INFO - no previously-included directories found matching 'dist' 07:38:33 INFO - no previously-included directories found matching '.git*' 07:38:33 INFO - Installing collected packages: jsonschema, functools32 07:38:33 INFO - Running setup.py install for functools32 07:38:33 INFO - warning: no files found matching '*.txt' 07:38:33 INFO - no previously-included directories found matching 'build' 07:38:33 INFO - no previously-included directories found matching 'dist' 07:38:33 INFO - no previously-included directories found matching '.git*' 07:38:33 INFO - Successfully installed jsonschema functools32 07:38:33 INFO - Cleaning up... 07:38:33 INFO - Return code: 0 07:38:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:33 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 0x7f63d0c24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282b240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, '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': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', '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:38:33 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:38:33 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:38:33 INFO - Using env: {'DISPLAY': ':0', 07:38:33 INFO - 'HOME': '/home/cltbld', 07:38:33 INFO - 'LANG': 'en_US.UTF-8', 07:38:33 INFO - 'LANGUAGE': 'en_US:en', 07:38:33 INFO - 'LOGNAME': 'cltbld', 07:38:33 INFO - 'MAIL': '/var/mail/cltbld', 07:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:33 INFO - 'MOZ_NO_REMOTE': '1', 07:38:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:33 INFO - 'NO_EM_RESTART': '1', 07:38:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:33 INFO - 'PWD': '/builds/slave/test', 07:38:33 INFO - 'SHELL': '/bin/bash', 07:38:33 INFO - 'SHLVL': '1', 07:38:33 INFO - 'TERM': 'linux', 07:38:33 INFO - 'TMOUT': '86400', 07:38:33 INFO - 'USER': 'cltbld', 07:38:33 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:33 INFO - '_': '/tools/buildbot/bin/python'} 07:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:34 INFO - Downloading/unpacking flake8 07:38:34 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:38:34 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:38:34 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:38:34 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:38:34 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:38:34 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:38:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:38:35 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:38:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:38:35 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:38:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:36 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:38:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 07:38:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:38:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:38:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:38:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:38:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:38:36 INFO - no previously-included directories found matching 'docs/_build' 07:38:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:38:36 INFO - Running setup.py install for pep8 07:38:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:38:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:38:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:38:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:38:36 INFO - no previously-included directories found matching 'docs/_build' 07:38:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:38:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:38:36 INFO - Cleaning up... 07:38:36 INFO - Return code: 0 07:38:36 INFO - Running post-action listener: _resource_record_post_action 07:38:36 INFO - Running post-action listener: _start_resource_monitoring 07:38:36 INFO - Starting resource monitoring. 07:38:36 INFO - ##### 07:38:36 INFO - ##### Running install step. 07:38:36 INFO - ##### 07:38:36 INFO - Running pre-action listener: _resource_record_pre_action 07:38:36 INFO - Running main action method: install 07:38:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:38:36 INFO - Reading from file tmpfile_stdout 07:38:36 INFO - Detecting whether we're running mozinstall >=1.0... 07:38:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:38:36 INFO - Reading from file tmpfile_stdout 07:38:36 INFO - Output received: 07:38:36 INFO - Usage: mozinstall [options] installer 07:38:36 INFO - Options: 07:38:36 INFO - -h, --help show this help message and exit 07:38:36 INFO - -d DEST, --destination=DEST 07:38:36 INFO - Directory to install application into. [default: 07:38:36 INFO - "/builds/slave/test"] 07:38:36 INFO - --app=APP Application being installed. [default: firefox] 07:38:36 INFO - mkdir: /builds/slave/test/build/application 07:38:36 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:38:36 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:38:52 INFO - Reading from file tmpfile_stdout 07:38:52 INFO - Output received: 07:38:52 INFO - /builds/slave/test/build/application/firefox/firefox 07:38:52 INFO - Running post-action listener: _resource_record_post_action 07:38:52 INFO - ##### 07:38:52 INFO - ##### Running run-tests step. 07:38:52 INFO - ##### 07:38:52 INFO - Running pre-action listener: _resource_record_pre_action 07:38:52 INFO - Running main action method: run_tests 07:38:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:38:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:38:52 INFO - Python 2.7.3 07:38:52 INFO - Return code: 0 07:38:52 INFO - grabbing minidump binary from tooltool 07:38:52 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:38:52 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 0x28ba0b0>, '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 0x28bafb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bb440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:38:52 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:38:52 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:38:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:38:52 INFO - Return code: 0 07:38:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:38:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:38:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:38:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:38:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:38:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:38:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:38:53 INFO - Return code: 0 07:38:53 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/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] in /builds/slave/test/build 07:38:53 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/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost 07:38:53 INFO - Using env: {'DISPLAY': ':0', 07:38:53 INFO - 'HOME': '/home/cltbld', 07:38:53 INFO - 'LANG': 'en_US.UTF-8', 07:38:53 INFO - 'LANGUAGE': 'en_US:en', 07:38:53 INFO - 'LOGNAME': 'cltbld', 07:38:53 INFO - 'MAIL': '/var/mail/cltbld', 07:38:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:38:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:53 INFO - 'MOZ_NO_REMOTE': '1', 07:38:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:38:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:38:53 INFO - 'NO_EM_RESTART': '1', 07:38:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:53 INFO - 'PWD': '/builds/slave/test', 07:38:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:38:53 INFO - 'SHELL': '/bin/bash', 07:38:53 INFO - 'SHLVL': '1', 07:38:53 INFO - 'TERM': 'linux', 07:38:53 INFO - 'TMOUT': '86400', 07:38:53 INFO - 'USER': 'cltbld', 07:38:53 INFO - 'XDG_SESSION_COOKIE': 'e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529', 07:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:53 INFO - '_': '/tools/buildbot/bin/python'} 07:38:53 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/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] with output_timeout 3600 07:38:53 INFO - mozversion INFO | application_buildid: 20151218061452 07:38:53 INFO - mozversion INFO | application_changeset: 8e54ec9a12f2af42dca48b957a88d2ce13e11d09 07:38:53 INFO - mozversion INFO | application_display_name: Nightly 07:38:53 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:38:53 INFO - mozversion INFO | application_name: Firefox 07:38:53 INFO - mozversion INFO | application_remotingname: firefox 07:38:53 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 07:38:53 INFO - mozversion INFO | application_vendor: Mozilla 07:38:53 INFO - mozversion INFO | application_version: 46.0a1 07:38:53 INFO - mozversion INFO | platform_buildid: 20151218061452 07:38:53 INFO - mozversion INFO | platform_changeset: 8e54ec9a12f2af42dca48b957a88d2ce13e11d09 07:38:53 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 07:38:53 INFO - mozversion INFO | platform_version: 46.0a1 07:38:53 INFO - 2015-12-18 07:38:53,589 DEBUG : using testdate: 1450453133 07:38:53 INFO - 2015-12-18 07:38:53,589 DEBUG : actual date: 1450453133 07:38:53 INFO - 2015-12-18 07:38:53,604 INFO : starting webserver on 'localhost:59093' 07:38:53 INFO - 2015-12-18 07:38:53,605 INFO : Starting test suite talos-linux64-ix-058 07:38:53 INFO - 2015-12-18 07:38:53,606 INFO : Starting test dromaeo_css 07:38:53 INFO - 2015-12-18 07:38:53,606 DEBUG : operating with platform_type : linux_ 07:38:53 INFO - 2015-12-18 07:38:53,607 INFO : Initialising browser for dromaeo_css test... 07:38:53 INFO - 2015-12-18 07:38:53,623 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_HfrJj/profile http://localhost:59093/getInfo.html 07:38:57 INFO - 2015-12-18 07:38:57,732 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:38:57 INFO - 2015-12-18 07:38:57,732 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:38:57 INFO - 2015-12-18 07:38:57,732 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:38:57 INFO - 2015-12-18 07:38:57,732 DEBUG : BROWSER_OUTPUT: __metrics 07:38:57 INFO - 2015-12-18 07:38:57,895 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:38:57 INFO - 2015-12-18 07:38:57,963 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:38:59 INFO - 2015-12-18 07:38:59,243 INFO : Browser initialized. 07:38:59 INFO - 2015-12-18 07:38:59,244 INFO : Running cycle 1/1 for dromaeo_css test... 07:38:59 INFO - 2015-12-18 07:38:59,244 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_HfrJj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:39:11 INFO - 2015-12-18 07:39:11,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 149577728 07:39:11 INFO - 2015-12-18 07:39:11,176 DEBUG : BROWSER_OUTPUT: 07:40:02 INFO - 2015-12-18 07:40:02,780 DEBUG : BROWSER_OUTPUT: 1450453202778 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:42:15 INFO - 2015-12-18 07:42:15,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59093/tests/dromaeo/cssquery-ext.html) 07:42:16 INFO - 2015-12-18 07:42:16,159 DEBUG : BROWSER_OUTPUT: RSS: Main: 256176128 07:42:16 INFO - 2015-12-18 07:42:16,159 DEBUG : BROWSER_OUTPUT: 07:45:18 INFO - 2015-12-18 07:45:18,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/dromaeo/cssquery-ext.html (next: http://localhost:59093/tests/dromaeo/cssquery-jquery.html) 07:45:19 INFO - 2015-12-18 07:45:19,219 DEBUG : BROWSER_OUTPUT: RSS: Main: 227667968 07:45:19 INFO - 2015-12-18 07:45:19,219 DEBUG : BROWSER_OUTPUT: 07:48:21 INFO - 2015-12-18 07:48:21,291 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59093/tests/dromaeo/cssquery-mootools.html) 07:48:21 INFO - 2015-12-18 07:48:21,860 DEBUG : BROWSER_OUTPUT: RSS: Main: 226275328 07:48:21 INFO - 2015-12-18 07:48:21,860 DEBUG : BROWSER_OUTPUT: 07:51:23 INFO - 2015-12-18 07:51:23,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59093/tests/dromaeo/cssquery-prototype.html) 07:51:24 INFO - 2015-12-18 07:51:24,417 DEBUG : BROWSER_OUTPUT: RSS: Main: 211161088 07:51:24 INFO - 2015-12-18 07:51:24,417 DEBUG : BROWSER_OUTPUT: 07:54:27 INFO - 2015-12-18 07:54:27,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59093/tests/dromaeo/cssquery-yui.html) 07:54:27 INFO - 2015-12-18 07:54:27,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 217460736 07:54:27 INFO - 2015-12-18 07:54:27,785 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,343 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/dromaeo/cssquery-yui.html (next: http://localhost:59093/tests/dromaeo/cssquery-dojo.html) 07:57:30 INFO - 2015-12-18 07:57:30,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 261263360 07:57:30 INFO - 2015-12-18 07:57:30,593 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,593 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:57:30 INFO - 2015-12-18 07:57:30,593 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:57:30 INFO - 2015-12-18 07:57:30,594 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:57:30 INFO - 2015-12-18 07:57:30,595 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:57:30 INFO - 2015-12-18 07:57:30,595 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2630.8553029583372;2721.9863900680507;2728.2633688904007;2738.479688859117;2752.807303488755;4563.954360456394;4712.764361781914;4729.834455794044;4782.497837727041;4803.66374353795;4648.163330600491;4658;4751.12104260712;4753.857384278477;4772.379590653219;4384.868453946387;4424.424824772775;4446.7776611169475;4455.353973673816;4477.10457908418;4266.06146647737;4429.623482004034;4435.246008178618;4490;4507.00845813922;4356.368326592658;4373.343998400233;4388.868333949987;4445.355423463612;4464.955350446519;4387.210302145612;4393.428854248959;4394.274944634131;4399.6480281577715;4431.8448854289945;4215.704900656955;4386.035072284092;4395.648348132142;4414.381986521889;4441.133978874121;4602.769861506911;4661.557152070548;4690.413698287714;4718.9292160617615;4762.618990480753;5138.897222055575;5192.532672059533;5194.558462530689;5204.583633309327;5239.973800131013;4900.950990490085;5216.035033518811;5240.68555886648;5247.212918062283;5263.605229607795;4714.858554243378;4740.2889566564945;4767.713937163781;4797.520247975244;4823.56587907083;5307.708076055854;5334.359876814807;5340.893182136336;5342.3856256531;5378.838634840961;4817.397825271841;5092.10888094582;5103.8724031898755;5116.488351164928;5116.641835071509;5004.099262132851;5056.747162641853;5064.46823083571;5106.744662766847;5111.846644600668;4970.925436118461;5033.194688849767;5044.571211446994;5053.444121146671;5059.544640982255;4958.132326842788;4961.330220420269;4963.528464795839;4968.056069346812;5019;4084.5098588169017;4161.9375709364385;4241.491021077429;4251.192273468031;4256.6807489438415;3783.0353259918543;3791.090138366772;3815.7328986970647;3835.6739677127757;3836;4398.8020539075815;4451.844185453494;4454.26504626733;4485.372047913294;4491.416115904912;4955.5044495550155;5068.315777370008;5070.808360035388;5072.391313042459;5190.662606930538;4409.669274804402;4411.669124815588;4412.602865742098;4423.40284061654;4433.445819272591;4603.930941035887;4617.238155704338;4619.122366750306;4621.815127394933;4668.1130585189385;4642.2340313847835;4774.069056533943;4784.330193772944;4823.975880120577;4875.439324477659;5166.173412254021;5172.043172013189;5180.922286165635;5196.584273258206;5204.843854684365;4885.291632713165;4886.193778026728;4886.3403440536495;4907.092187945242;4933.876653083558;4873.805047798049;4980.327655766425;5100.362454693163;5111.028904508132;5147.124988751846;4974.776135073858;5028.773705183203;5078.5175408336145;5079.949200508096;5133.845984620468;4907.533784290486;4916.311716359784;4934.481879402754;4950.405951285725;4988.775505102207;4751.192297309397;4875.1712208923855;4923.852284431473;4951.653384263211;4999.850004499871;4746.382970213988;4819.638527110411;4862.17343051675;4866.416030076413;4871.780769865293;4971;5011.899762004812;5015.523525265094;5028.522290382408;5052.368453943257;4632.073585282889;4741.767140543415;4918.754062296799;4924.4090709115135;4941.332920055746 07:57:30 INFO - 2015-12-18 07:57:30,596 DEBUG : BROWSER_OUTPUT: |1;ext.html;573.498189084551;573.7475510775262;581.2153592649922;582.7261187241995;594.4412252482663;26690.065847695336;27416.86291568542;34084.31831363375;34931.4760278596;35028.649713502855;39637.603623963754;39828;39839;39851.40222896659;39993.60006399935;6904.516683832134;7833.960830195855;8039.718609848656;8089.716859909904;8097.878531822027;7051.365377116059;10160.288779785418;10429.530671119817;10442.791144177112;10444.216683748711;9902.752431189207;9988.600455981752;9996.2502812289;10004.49977501122;10008.749781255454;7127.82180445493;7310.52481588695;9836.458994755285;9962.352447090952;10057.290260655705;9311.115444032806;9478.146966772954;9491.477968711719;9501.952490237574;9526.9523652382;32603;32920.506192407134;32983.83508082468;32984.83507582447;33016;2843.9146825595267;2851.900183493568;3390.9830450847835;3425.6403077676982;3456.2569047654624;27513;28035.158945231677;28199;28220.717792822015;28394.148175554765;3439.3121375724704;3501.334746398202;3515.156362473014;3571.6606922342594;3629.709623230135;211585;213364;214264;214491;214601.9269903656;51609;53372;54291;54735;56392;48339.51660483421;48505.272420913716;48815.02369952581;48851.51148488541;48855;12814.80777788334;13128.081034327506;13153.802692959613;13158.14472059313;13173.077884547989;9531.38046027006;9553.187979021723;9574.377665451724;9577.616895324249;9592.08875156859;18720.25118995225;18920.62158756817;19140.234390624497;19148.138333775012;19150.138243779063;7170.139583249939;7411.5553066816165;7435.702571897172;7448.739294124788;7528.360089392353;873.3449912565576;899.2760827533767;917.9632814687471;922.5064590444081;932.9830484771632;26145.346366340993;26353;26549;26848.32879177997;26850.5972410414;1004.0963133180196;1025.487256371814;1068.5040942680155;1077.8706555213425;1121.4336759936343;13906.930465347608;14066;14167.362468688934;14673.486427975184;14864.405423782928;261137;267099;268198;268989;269125;45754.77122614365;45927.311090333;46110.538894611294;46159.53840461552;46167.76916115398;1204.5603354775444;1206.5475446707485;1223.0276929840797;1238.8079847623453;1253.254313683357;20246.59506809885;25663.615045773953;26865.865670671523;27030.1890943272;27075.323116922234;36631;36995.8150209258;37058.814705927376;37073.258534828616;37099.443508346856;158160;158764;159372;159658;159761;24462.26613201607;26142.73857261403;26489.33776655599;29057.273568160967;29294.41411171722;32995.505067424485;33312.50031249582;33363.165920852174;33562.32875342528;34007.82996085004;28196.85901570479;28549.857250713612;28818.567721484615;29116.27209319784;29652.258693532833;26732.331691707863;30954.226144346572;31436.842815785774;31625.8418707905;31773.205669857514;28922.13233602911;29457.263568410963;29547.85226073856;29786.255343616584;29788.553171702875;24680.629790552797;25862.22413327603;26461.206163815117;26724.732752672226;26812.195634131007;21355.8932205338;22584.322470325915;22726.431839204153;22762.772372276726;22860.54278914379 07:57:30 INFO - 2015-12-18 07:57:30,597 DEBUG : BROWSER_OUTPUT: |2;jquery.html;329.4563969450408;332.9716974057205;335.13033677606603;335.8088472715531;336.89724633986623;12405.131640785126;12960.74078518429;14533.128012319256;15020.474283400083;15861.286242119104;9506;9772.462514561696;9777.804443911118;14871.776923346131;15281.847181528181;4383.1452866690925;4496.348029535716;4496.6627502937245;4501.099780044005;4504.707194032394;3423.6062852771875;3524.6475352464804;4705.811767529295;4943;5150.227465880129;5078.746062696869;5120.513551212629;5126.2054381570915;5136.178211486163;5139.203423469368;4939.456659767441;5023.296738456619;5095.515925987025;5114.974425127869;5117.257997590347;4886.218205087169;4892.877678058041;4893.779779909913;4897.534734200245;4900.117978763821;10776.191785616033;13422.53021144265;16498.917505412395;19489.707654385194;19618.11718472672;1790.7851057873008;1816.8819026763351;1868.3927723489785;1972.891490967996;2003.6092961872296;8547.401681882271;8664.566771661392;8754.3871928965;8858.512781796999;8874.334424918157;1668.8664906807426;1704.1649591700102;1722.6726921884801;1733.1940647598926;1766.0375095572945;54943.45056549463;57407.42592574062;57740.422595773925;57886.99999999958;58102.41897581012;9633.133018028271;9660.227181825438;9729;9752.658656946973;9794.265430092772;12464.875351246554;12474.81287780684;12484.68788280282;12502.81245781314;12520;6503.349665033459;6552.737890484423;6557.770478033246;6573.671316434159;6601.141851559362;5977.163197152402;6079.179310792986;6092.482139018233;6093;6105.297890742621;3817.4464702617965;3893;4006.859759908389;4139.503259608866;4168.374743788456;1601.191398343829;1643.1784107946028;1677.563833403324;1704.8380403861613;1747.9650406991789;831.4512421801581;859.5702148925537;875.913866805157;930.930180236485;945.8061097475002;14319;14544.49094281695;14688.706225875423;14957.401703931939;15006.399744010336;761.9733309334147;792.9008873890764;831.3266254334009;849.1084361420484;916.1067958740175;8415.95792021036;8609.65561377538;8639.265662418766;8697.695580654647;8811.427257228259;451218;454345;459517;462691;463175;71697;72013.6399318;72353.2764672357;72728.27271727321;74440.62779686175;1016.8118898003893;1030.1038096855634;1037.092544023979;1058.491923876559;1059.7403636109202;13333.266670333225;13978.44086236539;14568.562943111723;15017.32422040989;15271.236438177824;33146.83426582852;33336.83331583327;34031.659683402846;34355.82822085873;34625.48061779025;40557.797211013756;42689.786551067045;43035;48002;49314.260286096454;8482.236598705898;8606.354523410644;8708.69519566812;8717.128287171487;8809.471431714117;15643.29605167793;15955.441559545361;16054;16362.836371636608;16482.25829837684;13863.72272554523;13894.166350019032;14027.719445610826;14042.78935815984;14045.789313160516;13160.276184809927;13298.667533311746;13669.453221871014;13775;13806.861931380558;10526.78946421052;13433.596992090253;13732.313384330942;13973.720525589635;14456;8382.580870956306;8752.474851508676;9082.682106126254;9227.261819054325;9229.769255768659;8850.911490885008;8904.554772261232;14087.929560352542;14149.280853889231;14345.784813227601 07:57:30 INFO - 2015-12-18 07:57:30,599 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1425.479699909532;1623.5697540151862;1690.8900921440113;1713.2975480053176;1719.226348143335;6280.528960327976;6819.488538359618;7070.4343652507805;7083.291670832914;7086.503944723871;7043.049188359582;7158.391536719372;7170.318819712132;7182.461315401352;7188;1744.886582372148;1773.0248363400142;2042.356657652842;2099.076406381191;2110.7256056712604;2081.4172031831035;2085.979140208601;2088.0290664840845;2090.9790902090936;2091.9058642361056;2026.8041855305366;2032.908519116636;2068.9275875344365;2070.544480214358;2072.585482903425;2037.368415791102;2042.7650820155723;2058.9794102058936;2063.576966721826;2072.968905466419;2084.781097984705;2111.9577608447744;2112.387407651779;2115.7566879808865;2115.95768084639;7152.928470715278;7164.892526612105;7220.783376498714;7229.819254518627;7230.060092187982;4138.172365526907;4216.873493795191;4221.767802770846;4234.661227101855;4241.660667146621;2613.89544418223;2688.9731102689116;2694.191742477249;2696.8942733479375;2712.308361367858;844.5017439710539;848.452747977551;866.0300463480942;869.956502174895;872.3021582733851;9316.208122309612;9329.44023358594;9365.672201472984;9385.953070234606;9394.107559781809;3694.168812017275;3830.5020347354666;3864.3430616795213;3877.8448862045766;3881.0879443330796;8081.959590202011;8087.272145506815;8092.919070809335;8107.716229931924;8111.310538604167;4243.511996120424;4273.444452221253;4274.871753847389;4278.935815962763;4279.828806847753;3830.8467661293794;3852.171783066654;3857.61423857612;3859.749116307431;3864.9806750966063;2945.322575807533;2986.2982199183175;3379.2903490267317;3428.0915557377316;3437.2438063625964;1053.3469249065474;1068.8824229334766;1074.033369967036;1080.2924084724518;1087.2660953856116;872.9170728780756;880.308957468397;956.7655924298591;1150.442035612727;1152.4986630815622;6494.512911531654;6622.735090596322;6672.232693240242;6685.832854178587;6733.158355205599;1028.619410818002;1052.4264275969615;1055.0188324857802;1056.0442799266675;1058.1957712138676;5294.364676238876;5379.784808607612;5393.865153371197;5412.756425960842;5413.269208656781;8284.047334556482;8412.284955778705;8423.70517031901;8430.199131082538;8431.072582015973;8167.591620418955;8277.95861020691;8303.252707256375;8319.916800832036;8345.791355216168;6545.738170473223;6554.8856694362075;6623.7350505980185;6680.86638267232;6691.498137639696;6697.430718388797;6725.596464212163;6748.595084294958;6749.831254218684;6772.966135169293;7517.361024312776;7665.540067595962;7774.455788095005;7783.260590243918;7787.260210280015;7630.779075347918;7906.327962123284;7917.247861453153;8018.358531317597;8028.076771171156;5756.65460072397;5790.855228619318;5816.1857339973285;5841.18223448709;5925;5726.169705392778;5915.674637894957;6363.649999250086;6460.67696615158;6512.251091124392;6364.427201551883;6465.321141280097;6471.417572418505;6562.048502967138;6605.57063790852;6357.110004599267;6399.200099987502;6460.192475940507;6472.676366181578;6528.118703974903;6299.905501417574;6342.587731797418;6347.301796802441;6354.745810167727;6358.109864618865;5824.184614153937;5853.239078919713;5879.382664820303;5881.441263080001;5886.323072846678;6055.5458340623745;6066.696665166635;6156.5998210116195;6172.043333283348;6175.536834737323 07:57:30 INFO - 2015-12-18 07:57:30,599 DEBUG : BROWSER_OUTPUT: |4;prototype.html;285.57591975915767;293.62855987176226;296.35690551503245;297.7454276593511;298.4538294920296;5286.074936886044;5310.973445132774;5319.20211968205;5338.225957236198;5352.2506849041065;5228.215767634857;5279.076161671711;5303.893922121555;5313.990341835059;5319.20211968205;4145.54399016108;4148.605882441163;4150.190712810995;4163.958360416403;4166.458360413158;3974.3641017437217;4055.634992850643;4077.531083925357;4122.546519882811;4182.769947652878;4133.586641335857;4137.958620413788;4143.8964025899295;4146.8963275918195;4147.523034851;3885.572587015426;4119.011437255052;4130.896727581804;4133.1940271647045;4134.049168691203;3448.2758620689683;3518.753687241894;3561.664375859053;3627.963720362789;3630.1832087780303;5103.209002604565;5118.436971933084;5125.743712814345;5146.279520867081;5149.922751158736;4739.526047395268;4741.8103275868925;4745.335653008581;4746.810127594892;4751.619870410359;5437.564994800407;5619.522340601054;5633.521150702154;5637.210790489294;5639.182318563826;3847.6344747249245;3849.5188101487315;3851.8074096295354;3854.3062248795204;3857.0550215197177;5794.420557944214;5868.09044598088;5876.500497457721;5881.823545293648;5883.411658834082;3919.1377896863214;3920.0787814863484;3920.176762879827;3921.6470517653547;3923.5684074752326;3978.3037968356;3982;3982.984338993596;3984.4222587724616;3987;4513.074819661962;4595.517470665599;4611.76941152941;4639.907201855944;4668.11305851887;5665.773369065274;5673.156224227143;5674.432556744293;5678.971605141948;5684.459976302244;1399.0206855201197;1410.4922227997913;1411.5906387147818;1413.4275618374575;1413.8444771075174;937.0910217089412;940.308872978374;943.8112377524385;956.5743244256379;961.6682244625594;4975.925361119586;4990.675606085593;4991.975040124848;4992.90014199714;4993.151164302005;5885;5891.3814049525035;5897.823065307962;5901.409859014064;5902.380250073767;4972.229304457815;4972.279019542146;4980.7509624518625;4993.6005119590345;4997.200447928314;3053.2519532714628;3090.6136732908385;3143.38703952727;3160.3047329587453;3179.5389668497937;5462.863428414322;5534.363548191929;5590.636608620427;5635.690037048002;5646.57650676201;6041.456268935817;6102.115193296947;6103.176071230327;6105.48103411215;6122.938770612326;5547;5580.414056524088;5598.1805913078415;5605.738708790571;5607.495325420732;5763.164341170591;5941.435563621449;5958.821235362946;5963.403659634176;5970.492508136857;5781.739821707949;5792.594518383673;5797.797077102281;5798.724280658248;5802.042662960733;5604.439556044363;5623.325200975909;5624.493795558419;5632.098864181711;5632.943670563406;3868.8259028343227;3926.842926282917;3942.5071866016747;3946.703997200164;3947.467091942551;5863.442972917566;5870.853228669317;5873.853153671193;5876.6180198286975;5877.500412464818;5644.8024319148635;5656.773729050792;5662.546996240373;5663.1788390682295;5663.263775709132;5649.745761440828;5651.8304450866535;5652.152177173457;5657.236273103078;5661.801836935688;5399.568034557147;5527;5538.723063846872;5540.584456165723;5553.0559804832465;5674.858128546654;5685.317761868602;5687.374388817144;5687.668056097146;5694.544436444991;5546.389897111234;5548.500634942875;5556.333240011225;5559.972200138974;5571.972140139435 07:57:30 INFO - 2015-12-18 07:57:30,600 DEBUG : BROWSER_OUTPUT: |5;yui.html;659.4295934017075;707.5931339479798;738.1769327200174;741.5106030020188;757.2768006553741;10452.16382689385;10889.455527223647;10895.291806032603;10899.618513352025;10906.454677266134;11212.887871121286;11385.658430247086;11389.373584452851;11391.145664075186;11399.601013964513;622.2843729710846;632.178168381105;780.4497829030535;795.1134485049187;813.9104698483192;773.5358784729174;799.0770659887838;800.1078797141175;806.6450761664848;807.0114110214998;663.0948754949484;810.027966440271;838.651959436833;845.5729856422498;846.7713717296326;657.3262406033806;820.1142765812938;832.7543374704477;835.0980940584216;835.9038710548303;603.6891001134419;611.9510439164856;625.7684656677059;625.777848863656;626.4518546272012;11386.601468948547;11444.313341199555;11545.422728863607;11566.190366674336;11577.942110289394;11287.661370158909;11510.30938143714;11721.589744358907;11940.940295298555;11999.400029998465;9595.664151754725;9596.856047159297;9635.759106022335;9656.90343096567;9689.563969621313;3230.789998650104;3261.8858339957987;3286.375588638151;3307.2724000719804;3338.582677165354;37163.442548361796;37532.624673753184;37595.81202093999;37610.62389376099;37706.24587508262;8760.299176065899;8845.380823342304;8869.06874778139;8963.148500892405;9001.054889236666;15197;15268.31292591836;15777.68444631101;15851.603709907344;15934.760978585331;6063.787767428119;6194.101855231056;6335.049742538656;6365.2998170201245;6395.296517383084;3522.2427178156818;3602.8018458985007;3637.0543658648944;3650.580183278904;3651.561812582507;2497.463045445238;2546.17249393944;2549.8980040798533;2561.180422264895;2564.6537717408282;429.8710386883922;441.17720451357985;442.03856611869054;450.0279396503537;450.9391232183613;410.2226281197115;433.1920967395784;450.87150162203613;452.0326501287248;461.4462644826222;6915.162258246617;7212.423006159494;7473.51422157558;7813.765587032398;7857.253560911704;420.7790909772394;450.2862962204942;486.7639194990427;526.7998160698986;578.0519947286454;3196.328770958078;3199.6480387157394;3258.332041931398;3290.6380298167182;3454.3264063507877;44945.775271123435;48383.516164838606;48426;48473.75763121162;48953;39524;39648.60351396449;39828.601713983066;40011.79994100011;41605.583944160775;39642.40536391956;39800.2039959205;40350.59649403527;40363.394549081786;40807.387889181686;7364.3003914628025;7442.702291908263;7445.532332166944;7480.775576732707;7684.193159718149;29440;30137.547936781404;30232.244193895327;30341.39317213599;30484.237894052825;37575;38404.80797595994;38509.614903851725;38703.22593548169;39139.41290880696;9349.766255843659;9370.718878433385;9636.325457218209;9771.12059914611;9851.45816980073;10286.948565257126;10393.480325983519;10882.782344353227;10977.560897564328;11031.724206894893;9969.252306076929;10090.69727908164;10195.898041019494;10368.429736364573;10711.571537138427;10067.647632332833;10248.692539223835;10332.225083118647;10607.734806629896;10624.575016999233;9640.614375424904;9885.752856178653;10042.096211341015;10242.795144097225;10311.793764124526;8474.872876906975;8791.208791208823;8802.691905783267;8972.551372431482;9068.455892646198;8453.830923381622;8579.356548259031;8659.090795466638;8741.650333986569;8931.151540603632 07:57:30 INFO - 2015-12-18 07:57:30,600 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:57:30 INFO - 2015-12-18 07:57:30,600 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:57:30 INFO - 2015-12-18 07:57:30,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,985 07:57:30 INFO - 2015-12-18 07:57:30,600 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: __startTimestamp1450454250592__endTimestamp 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4713.62 Median:4819.64 stddev:490.80 (10.2%) stddev-sans-first:464.32 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: Values: 2630.9 2722.0 2728.3 2738.5 2752.8 4564.0 4712.8 4729.8 4782.5 4803.7 4648.2 4658.0 4751.1 4753.9 4772.4 4384.9 4424.4 4446.8 4455.4 4477.1 4266.1 4429.6 4435.2 4490.0 4507.0 4356.4 4373.3 4388.9 4445.4 4465.0 4387.2 4393.4 4394.3 4399.6 4431.8 4215.7 4386.0 4395.6 4414.4 4441.1 4602.8 4661.6 4690.4 4718.9 4762.6 5138.9 5192.5 5194.6 5204.6 5240.0 4901.0 5216.0 5240.7 5247.2 5263.6 4714.9 4740.3 4767.7 4797.5 4823.6 5307.7 5334.4 5340.9 5342.4 5378.8 4817.4 5092.1 5103.9 5116.5 5116.6 5004.1 5056.7 5064.5 5106.7 5111.8 4970.9 5033.2 5044.6 5053.4 5059.5 4958.1 4961.3 4963.5 4968.1 5019.0 4084.5 4161.9 4241.5 4251.2 4256.7 3783.0 3791.1 3815.7 3835.7 3836.0 4398.8 4451.8 4454.3 4485.4 4491.4 4955.5 5068.3 5070.8 5072.4 5190.7 4409.7 4411.7 4412.6 4423.4 4433.4 4603.9 4617.2 4619.1 4621.8 4668.1 4642.2 4774.1 4784.3 4824.0 4875.4 5166.2 5172.0 5180.9 5196.6 5204.8 4885.3 4886.2 4886.3 4907.1 4933.9 4873.8 4980.3 5100.4 5111.0 5147.1 4974.8 5028.8 5078.5 5079.9 5133.8 4907.5 4916.3 4934.5 4950.4 4988.8 4751.2 4875.2 4923.9 4951.7 4999.9 4746.4 4819.6 4862.2 4866.4 4871.8 4971.0 5011.9 5015.5 5028.5 5052.4 4632.1 4741.8 4918.8 4924.4 4941.3 07:57:30 INFO - 2015-12-18 07:57:30,601 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,602 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:36874.98 Median:29787.40 stddev:56653.64 (190.2%) stddev-sans-first:56747.00 07:57:30 INFO - 2015-12-18 07:57:30,602 DEBUG : BROWSER_OUTPUT: Values: 573.5 573.7 581.2 582.7 594.4 26690.1 27416.9 34084.3 34931.5 35028.6 39637.6 39828.0 39839.0 39851.4 39993.6 6904.5 7834.0 8039.7 8089.7 8097.9 7051.4 10160.3 10429.5 10442.8 10444.2 9902.8 9988.6 9996.3 10004.5 10008.7 7127.8 7310.5 9836.5 9962.4 10057.3 9311.1 9478.1 9491.5 9502.0 9527.0 32603.0 32920.5 32983.8 32984.8 33016.0 2843.9 2851.9 3391.0 3425.6 3456.3 27513.0 28035.2 28199.0 28220.7 28394.1 3439.3 3501.3 3515.2 3571.7 3629.7 211585.0 213364.0 214264.0 214491.0 214601.9 51609.0 53372.0 54291.0 54735.0 56392.0 48339.5 48505.3 48815.0 48851.5 48855.0 12814.8 13128.1 13153.8 13158.1 13173.1 9531.4 9553.2 9574.4 9577.6 9592.1 18720.3 18920.6 19140.2 19148.1 19150.1 7170.1 7411.6 7435.7 7448.7 7528.4 873.3 899.3 918.0 922.5 933.0 26145.3 26353.0 26549.0 26848.3 26850.6 1004.1 1025.5 1068.5 1077.9 1121.4 13906.9 14066.0 14167.4 14673.5 14864.4 261137.0 267099.0 268198.0 268989.0 269125.0 45754.8 45927.3 46110.5 46159.5 46167.8 1204.6 1206.5 1223.0 1238.8 1253.3 20246.6 25663.6 26865.9 27030.2 27075.3 36631.0 36995.8 37058.8 37073.3 37099.4 158160.0 158764.0 159372.0 159658.0 159761.0 24462.3 26142.7 26489.3 29057.3 29294.4 32995.5 33312.5 33363.2 33562.3 34007.8 28196.9 28549.9 28818.6 29116.3 29652.3 26732.3 30954.2 31436.8 31625.8 31773.2 28922.1 29457.3 29547.9 29786.3 29788.6 24680.6 25862.2 26461.2 26724.7 26812.2 21355.9 22584.3 22726.4 22762.8 22860.5 07:57:30 INFO - 2015-12-18 07:57:30,602 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,603 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25354.50 Median:4439.75 stddev:74989.51 (1689.0%) stddev-sans-first:75176.33 07:57:30 INFO - 2015-12-18 07:57:30,603 DEBUG : BROWSER_OUTPUT: Values: 329.5 333.0 335.1 335.8 336.9 12405.1 12960.7 14533.1 15020.5 15861.3 9506.0 9772.5 9777.8 14871.8 15281.8 4383.1 4496.3 4496.7 4501.1 4504.7 3423.6 3524.6 4705.8 4943.0 5150.2 5078.7 5120.5 5126.2 5136.2 5139.2 4939.5 5023.3 5095.5 5115.0 5117.3 4886.2 4892.9 4893.8 4897.5 4900.1 10776.2 13422.5 16498.9 19489.7 19618.1 1790.8 1816.9 1868.4 1972.9 2003.6 8547.4 8664.6 8754.4 8858.5 8874.3 1668.9 1704.2 1722.7 1733.2 1766.0 54943.5 57407.4 57740.4 57887.0 58102.4 9633.1 9660.2 9729.0 9752.7 9794.3 12464.9 12474.8 12484.7 12502.8 12520.0 6503.3 6552.7 6557.8 6573.7 6601.1 5977.2 6079.2 6092.5 6093.0 6105.3 3817.4 3893.0 4006.9 4139.5 4168.4 1601.2 1643.2 1677.6 1704.8 1748.0 831.5 859.6 875.9 930.9 945.8 14319.0 14544.5 14688.7 14957.4 15006.4 762.0 792.9 831.3 849.1 916.1 8416.0 8609.7 8639.3 8697.7 8811.4 451218.0 454345.0 459517.0 462691.0 463175.0 71697.0 72013.6 72353.3 72728.3 74440.6 1016.8 1030.1 1037.1 1058.5 1059.7 13333.3 13978.4 14568.6 15017.3 15271.2 33146.8 33336.8 34031.7 34355.8 34625.5 40557.8 42689.8 43035.0 48002.0 49314.3 8482.2 8606.4 8708.7 8717.1 8809.5 15643.3 15955.4 16054.0 16362.8 16482.3 13863.7 13894.2 14027.7 14042.8 14045.8 13160.3 13298.7 13669.5 13775.0 13806.9 10526.8 13433.6 13732.3 13973.7 14456.0 8382.6 8752.5 9082.7 9227.3 9229.8 8850.9 8904.6 14087.9 14149.3 14345.8 07:57:30 INFO - 2015-12-18 07:57:30,603 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,604 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4932.87 Median:6111.65 stddev:2533.48 (41.5%) stddev-sans-first:2526.87 07:57:30 INFO - 2015-12-18 07:57:30,604 DEBUG : BROWSER_OUTPUT: Values: 1425.5 1623.6 1690.9 1713.3 1719.2 6280.5 6819.5 7070.4 7083.3 7086.5 7043.0 7158.4 7170.3 7182.5 7188.0 1744.9 1773.0 2042.4 2099.1 2110.7 2081.4 2086.0 2088.0 2091.0 2091.9 2026.8 2032.9 2068.9 2070.5 2072.6 2037.4 2042.8 2059.0 2063.6 2073.0 2084.8 2112.0 2112.4 2115.8 2116.0 7152.9 7164.9 7220.8 7229.8 7230.1 4138.2 4216.9 4221.8 4234.7 4241.7 2613.9 2689.0 2694.2 2696.9 2712.3 844.5 848.5 866.0 870.0 872.3 9316.2 9329.4 9365.7 9386.0 9394.1 3694.2 3830.5 3864.3 3877.8 3881.1 8082.0 8087.3 8092.9 8107.7 8111.3 4243.5 4273.4 4274.9 4278.9 4279.8 3830.8 3852.2 3857.6 3859.7 3865.0 2945.3 2986.3 3379.3 3428.1 3437.2 1053.3 1068.9 1074.0 1080.3 1087.3 872.9 880.3 956.8 1150.4 1152.5 6494.5 6622.7 6672.2 6685.8 6733.2 1028.6 1052.4 1055.0 1056.0 1058.2 5294.4 5379.8 5393.9 5412.8 5413.3 8284.0 8412.3 8423.7 8430.2 8431.1 8167.6 8278.0 8303.3 8319.9 8345.8 6545.7 6554.9 6623.7 6680.9 6691.5 6697.4 6725.6 6748.6 6749.8 6773.0 7517.4 7665.5 7774.5 7783.3 7787.3 7630.8 7906.3 7917.2 8018.4 8028.1 5756.7 5790.9 5816.2 5841.2 5925.0 5726.2 5915.7 6363.6 6460.7 6512.3 6364.4 6465.3 6471.4 6562.0 6605.6 6357.1 6399.2 6460.2 6472.7 6528.1 6299.9 6342.6 6347.3 6354.7 6358.1 5824.2 5853.2 5879.4 5881.4 5886.3 6055.5 6066.7 6156.6 6172.0 6175.5 07:57:30 INFO - 2015-12-18 07:57:30,604 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,604 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4669.39 Median:5316.60 stddev:1399.82 (26.3%) stddev-sans-first:1364.53 07:57:30 INFO - 2015-12-18 07:57:30,605 DEBUG : BROWSER_OUTPUT: Values: 285.6 293.6 296.4 297.7 298.5 5286.1 5311.0 5319.2 5338.2 5352.3 5228.2 5279.1 5303.9 5314.0 5319.2 4145.5 4148.6 4150.2 4164.0 4166.5 3974.4 4055.6 4077.5 4122.5 4182.8 4133.6 4138.0 4143.9 4146.9 4147.5 3885.6 4119.0 4130.9 4133.2 4134.0 3448.3 3518.8 3561.7 3628.0 3630.2 5103.2 5118.4 5125.7 5146.3 5149.9 4739.5 4741.8 4745.3 4746.8 4751.6 5437.6 5619.5 5633.5 5637.2 5639.2 3847.6 3849.5 3851.8 3854.3 3857.1 5794.4 5868.1 5876.5 5881.8 5883.4 3919.1 3920.1 3920.2 3921.6 3923.6 3978.3 3982.0 3983.0 3984.4 3987.0 4513.1 4595.5 4611.8 4639.9 4668.1 5665.8 5673.2 5674.4 5679.0 5684.5 1399.0 1410.5 1411.6 1413.4 1413.8 937.1 940.3 943.8 956.6 961.7 4975.9 4990.7 4992.0 4992.9 4993.2 5885.0 5891.4 5897.8 5901.4 5902.4 4972.2 4972.3 4980.8 4993.6 4997.2 3053.3 3090.6 3143.4 3160.3 3179.5 5462.9 5534.4 5590.6 5635.7 5646.6 6041.5 6102.1 6103.2 6105.5 6122.9 5547.0 5580.4 5598.2 5605.7 5607.5 5763.2 5941.4 5958.8 5963.4 5970.5 5781.7 5792.6 5797.8 5798.7 5802.0 5604.4 5623.3 5624.5 5632.1 5632.9 3868.8 3926.8 3942.5 3946.7 3947.5 5863.4 5870.9 5873.9 5876.6 5877.5 5644.8 5656.8 5662.5 5663.2 5663.3 5649.7 5651.8 5652.2 5657.2 5661.8 5399.6 5527.0 5538.7 5540.6 5553.1 5674.9 5685.3 5687.4 5687.7 5694.5 5546.4 5548.5 5556.3 5560.0 5572.0 07:57:30 INFO - 2015-12-18 07:57:30,605 DEBUG : BROWSER_OUTPUT: 07:57:30 INFO - 2015-12-18 07:57:30,605 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11722.23 Median:437.18 stddev:13123.65 (3001.9%) stddev-sans-first:13134.17 07:57:30 INFO - 2015-12-18 07:57:30,605 DEBUG : BROWSER_OUTPUT: Values: 659.4 707.6 738.2 741.5 757.3 10452.2 10889.5 10895.3 10899.6 10906.5 11212.9 11385.7 11389.4 11391.1 11399.6 622.3 632.2 780.4 795.1 813.9 773.5 799.1 800.1 806.6 807.0 663.1 810.0 838.7 845.6 846.8 657.3 820.1 832.8 835.1 835.9 603.7 612.0 625.8 625.8 626.5 11386.6 11444.3 11545.4 11566.2 11577.9 11287.7 11510.3 11721.6 11940.9 11999.4 9595.7 9596.9 9635.8 9656.9 9689.6 3230.8 3261.9 3286.4 3307.3 3338.6 37163.4 37532.6 37595.8 37610.6 37706.2 8760.3 8845.4 8869.1 8963.1 9001.1 15197.0 15268.3 15777.7 15851.6 15934.8 6063.8 6194.1 6335.0 6365.3 6395.3 3522.2 3602.8 3637.1 3650.6 3651.6 2497.5 2546.2 2549.9 2561.2 2564.7 429.9 441.2 442.0 450.0 450.9 410.2 433.2 450.9 452.0 461.4 6915.2 7212.4 7473.5 7813.8 7857.3 420.8 450.3 486.8 526.8 578.1 3196.3 3199.6 3258.3 3290.6 3454.3 44945.8 48383.5 48426.0 48473.8 48953.0 39524.0 39648.6 39828.6 40011.8 41605.6 39642.4 39800.2 40350.6 40363.4 40807.4 7364.3 7442.7 7445.5 7480.8 7684.2 29440.0 30137.5 30232.2 30341.4 30484.2 37575.0 38404.8 38509.6 38703.2 39139.4 9349.8 9370.7 9636.3 9771.1 9851.5 10286.9 10393.5 10882.8 10977.6 11031.7 9969.3 10090.7 10195.9 10368.4 10711.6 10067.6 10248.7 10332.2 10607.7 10624.6 9640.6 9885.8 10042.1 10242.8 10311.8 8474.9 8791.2 8802.7 8972.6 9068.5 8453.8 8579.4 8659.1 8741.7 8931.2 07:57:30 INFO - 2015-12-18 07:57:30,606 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:57:30 INFO - 2015-12-18 07:57:30,606 DEBUG : BROWSER_OUTPUT: 07:57:31 INFO - 2015-12-18 07:57:31,359 INFO : Browser exited with error code: 0 07:57:31 INFO - 2015-12-18 07:57:31,380 INFO : Completed test dromaeo_css (00:18:37) 07:57:31 INFO - 2015-12-18 07:57:31,380 INFO : Starting test kraken 07:57:31 INFO - 2015-12-18 07:57:31,380 DEBUG : operating with platform_type : linux_ 07:57:31 INFO - 2015-12-18 07:57:31,380 INFO : Initialising browser for kraken test... 07:57:31 INFO - 2015-12-18 07:57:31,387 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNqaRO/profile http://localhost:59093/getInfo.html 07:57:35 INFO - 2015-12-18 07:57:35,704 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:57:35 INFO - 2015-12-18 07:57:35,704 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:57:35 INFO - 2015-12-18 07:57:35,705 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:57:35 INFO - 2015-12-18 07:57:35,705 DEBUG : BROWSER_OUTPUT: __metrics 07:57:35 INFO - 2015-12-18 07:57:35,773 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:57:35 INFO - 2015-12-18 07:57:35,837 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:57:36 INFO - 2015-12-18 07:57:36,857 INFO : Browser initialized. 07:57:36 INFO - 2015-12-18 07:57:36,857 INFO : Running cycle 1/1 for kraken test... 07:57:36 INFO - 2015-12-18 07:57:36,857 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNqaRO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:57:48 INFO - 2015-12-18 07:57:48,899 DEBUG : BROWSER_OUTPUT: RSS: Main: 149852160 07:57:48 INFO - 2015-12-18 07:57:48,900 DEBUG : BROWSER_OUTPUT: 07:57:51 INFO - 2015-12-18 07:57:51,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59093/tests/kraken/driver.html?testName=audio-beat-detection) 07:57:52 INFO - 2015-12-18 07:57:52,088 DEBUG : BROWSER_OUTPUT: RSS: Main: 186269696 07:57:52 INFO - 2015-12-18 07:57:52,088 DEBUG : BROWSER_OUTPUT: 07:57:54 INFO - 2015-12-18 07:57:54,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59093/tests/kraken/driver.html?testName=audio-dft) 07:57:55 INFO - 2015-12-18 07:57:55,404 DEBUG : BROWSER_OUTPUT: RSS: Main: 176017408 07:57:55 INFO - 2015-12-18 07:57:55,404 DEBUG : BROWSER_OUTPUT: 07:57:58 INFO - 2015-12-18 07:57:58,410 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59093/tests/kraken/driver.html?testName=audio-fft) 07:57:58 INFO - 2015-12-18 07:57:58,975 DEBUG : BROWSER_OUTPUT: RSS: Main: 161656832 07:57:58 INFO - 2015-12-18 07:57:58,975 DEBUG : BROWSER_OUTPUT: 07:58:01 INFO - 2015-12-18 07:58:01,033 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59093/tests/kraken/driver.html?testName=audio-oscillator) 07:58:01 INFO - 2015-12-18 07:58:01,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 162807808 07:58:01 INFO - 2015-12-18 07:58:01,606 DEBUG : BROWSER_OUTPUT: 07:58:03 INFO - 2015-12-18 07:58:03,847 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59093/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:58:04 INFO - 2015-12-18 07:58:04,416 DEBUG : BROWSER_OUTPUT: RSS: Main: 164818944 07:58:04 INFO - 2015-12-18 07:58:04,416 DEBUG : BROWSER_OUTPUT: 07:58:07 INFO - 2015-12-18 07:58:07,380 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59093/tests/kraken/driver.html?testName=imaging-darkroom) 07:58:07 INFO - 2015-12-18 07:58:07,962 DEBUG : BROWSER_OUTPUT: RSS: Main: 165007360 07:58:07 INFO - 2015-12-18 07:58:07,963 DEBUG : BROWSER_OUTPUT: 07:58:11 INFO - 2015-12-18 07:58:11,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59093/tests/kraken/driver.html?testName=imaging-desaturate) 07:58:11 INFO - 2015-12-18 07:58:11,942 DEBUG : BROWSER_OUTPUT: RSS: Main: 166113280 07:58:11 INFO - 2015-12-18 07:58:11,942 DEBUG : BROWSER_OUTPUT: 07:58:14 INFO - 2015-12-18 07:58:14,766 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59093/tests/kraken/driver.html?testName=json-parse-financial) 07:58:15 INFO - 2015-12-18 07:58:15,348 DEBUG : BROWSER_OUTPUT: RSS: Main: 166187008 07:58:15 INFO - 2015-12-18 07:58:15,348 DEBUG : BROWSER_OUTPUT: 07:58:17 INFO - 2015-12-18 07:58:17,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59093/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:58:17 INFO - 2015-12-18 07:58:17,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 174219264 07:58:17 INFO - 2015-12-18 07:58:17,679 DEBUG : BROWSER_OUTPUT: 07:58:19 INFO - 2015-12-18 07:58:19,562 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:58:20 INFO - 2015-12-18 07:58:20,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 171720704 07:58:20 INFO - 2015-12-18 07:58:20,155 DEBUG : BROWSER_OUTPUT: 07:58:22 INFO - 2015-12-18 07:58:22,252 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:58:22 INFO - 2015-12-18 07:58:22,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 184406016 07:58:22 INFO - 2015-12-18 07:58:22,833 DEBUG : BROWSER_OUTPUT: 07:58:25 INFO - 2015-12-18 07:58:25,353 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:58:25 INFO - 2015-12-18 07:58:25,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 189325312 07:58:25 INFO - 2015-12-18 07:58:25,939 DEBUG : BROWSER_OUTPUT: 07:58:28 INFO - 2015-12-18 07:58:28,494 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:58:29 INFO - 2015-12-18 07:58:29,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 187039744 07:58:29 INFO - 2015-12-18 07:58:29,076 DEBUG : BROWSER_OUTPUT: 07:58:30 INFO - 2015-12-18 07:58:30,825 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59093/tests/kraken/driver.html?testName=ai-astar) 07:58:31 INFO - 2015-12-18 07:58:31,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 245432320 07:58:31 INFO - 2015-12-18 07:58:31,075 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,079 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:58:31 INFO - 2015-12-18 07:58:31,079 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:58:31 INFO - 2015-12-18 07:58:31,079 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:58:31 INFO - 2015-12-18 07:58:31,079 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:58:31 INFO - 2015-12-18 07:58:31,079 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;113;107;111;113;113;103;113;112;119 07:58:31 INFO - 2015-12-18 07:58:31,079 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;129;159;171;135;152;158;134;175;129 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;160;162;162;159;159;161;163;161;161 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;82;95;72;73;72;96;90;73 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;129;93;93;95;143;95;96;132 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;110;109;111;110;110;110 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;147;147;146;148;147;148;146 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;96;95;95;95;95;96 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;72;71;71;71;71;70;79;69;69 07:58:31 INFO - 2015-12-18 07:58:31,080 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;68;67;68;68;68;70;68;70;68 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;80;83;83;83;83;82;82;84;83;82 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;127;123;121;120;127;123;123;126;118;116 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;151;146;147;152;135;147;144;145;133 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;74;71;69;68;70;68;68;68;68;70 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,987 07:58:31 INFO - 2015-12-18 07:58:31,081 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:58:31 INFO - 2015-12-18 07:58:31,082 DEBUG : BROWSER_OUTPUT: __startTimestamp1450454311077__endTimestamp 07:58:31 INFO - 2015-12-18 07:58:31,082 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,089 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:58:31 INFO - 2015-12-18 07:58:31,089 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:4.20 (3.7%) stddev-sans-first:4.45 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: Values: 112.0 113.0 107.0 111.0 113.0 113.0 103.0 113.0 112.0 119.0 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.90 Median:157.50 stddev:17.08 (10.8%) stddev-sans-first:17.93 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: Values: 157.0 129.0 159.0 171.0 135.0 152.0 158.0 134.0 175.0 129.0 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.45 (0.9%) stddev-sans-first:1.36 07:58:31 INFO - 2015-12-18 07:58:31,090 DEBUG : BROWSER_OUTPUT: Values: 163.0 160.0 162.0 162.0 159.0 159.0 161.0 163.0 161.0 161.0 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.80 Median:77.50 stddev:10.13 (13.1%) stddev-sans-first:10.44 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 82.0 95.0 72.0 73.0 72.0 96.0 90.0 73.0 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:94.00 stddev:19.83 (21.1%) stddev-sans-first:20.52 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 129.0 93.0 93.0 95.0 143.0 95.0 96.0 132.0 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,091 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 110.0 109.0 111.0 110.0 110.0 110.0 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 147.0 147.0 146.0 148.0 147.0 148.0 146.0 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 07:58:31 INFO - 2015-12-18 07:58:31,092 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,093 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:71.50 Median:71.00 stddev:2.84 (4.0%) stddev-sans-first:3.00 07:58:31 INFO - 2015-12-18 07:58:31,093 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 71.0 71.0 71.0 71.0 70.0 79.0 69.0 69.0 07:58:31 INFO - 2015-12-18 07:58:31,093 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,093 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.00 07:58:31 INFO - 2015-12-18 07:58:31,093 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 67.0 68.0 68.0 68.0 70.0 68.0 70.0 68.0 07:58:31 INFO - 2015-12-18 07:58:31,093 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,093 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:1.08 (1.3%) stddev-sans-first:0.67 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: Values: 80.0 83.0 83.0 83.0 83.0 82.0 82.0 84.0 83.0 82.0 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:123.00 stddev:3.72 (3.0%) stddev-sans-first:3.55 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: Values: 127.0 123.0 121.0 120.0 127.0 123.0 123.0 126.0 118.0 116.0 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.40 Median:146.50 stddev:6.95 (4.7%) stddev-sans-first:6.48 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: Values: 134.0 151.0 146.0 147.0 152.0 135.0 147.0 144.0 145.0 133.0 07:58:31 INFO - 2015-12-18 07:58:31,094 DEBUG : BROWSER_OUTPUT: 07:58:31 INFO - 2015-12-18 07:58:31,095 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:1.96 (2.8%) stddev-sans-first:1.17 07:58:31 INFO - 2015-12-18 07:58:31,095 DEBUG : BROWSER_OUTPUT: Values: 74.0 71.0 69.0 68.0 70.0 68.0 68.0 68.0 68.0 70.0 07:58:31 INFO - 2015-12-18 07:58:31,095 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:58:31 INFO - 2015-12-18 07:58:31,095 DEBUG : BROWSER_OUTPUT: 07:58:32 INFO - 2015-12-18 07:58:32,011 INFO : Browser exited with error code: 0 07:58:32 INFO - 2015-12-18 07:58:32,062 INFO : Completed test kraken (00:01:00) 07:58:32 INFO - 2015-12-18 07:58:32,062 INFO : Starting test v8_7 07:58:32 INFO - 2015-12-18 07:58:32,062 DEBUG : operating with platform_type : linux_ 07:58:32 INFO - 2015-12-18 07:58:32,062 INFO : Initialising browser for v8_7 test... 07:58:32 INFO - 2015-12-18 07:58:32,068 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT7VfSK/profile http://localhost:59093/getInfo.html 07:58:36 INFO - 2015-12-18 07:58:36,104 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:58:36 INFO - 2015-12-18 07:58:36,104 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:58:36 INFO - 2015-12-18 07:58:36,104 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:58:36 INFO - 2015-12-18 07:58:36,104 DEBUG : BROWSER_OUTPUT: __metrics 07:58:36 INFO - 2015-12-18 07:58:36,213 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:58:36 INFO - 2015-12-18 07:58:36,273 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:58:37 INFO - 2015-12-18 07:58:37,538 INFO : Browser initialized. 07:58:37 INFO - 2015-12-18 07:58:37,538 INFO : Running cycle 1/1 for v8_7 test... 07:58:37 INFO - 2015-12-18 07:58:37,538 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT7VfSK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:58:49 INFO - 2015-12-18 07:58:49,494 DEBUG : BROWSER_OUTPUT: RSS: Main: 149168128 07:58:49 INFO - 2015-12-18 07:58:49,494 DEBUG : BROWSER_OUTPUT: 07:59:10 INFO - 2015-12-18 07:59:10,866 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:59093/tests/v8_7/run.html (next: http://localhost:59093/tests/v8_7/run.html) 07:59:11 INFO - 2015-12-18 07:59:11,115 DEBUG : BROWSER_OUTPUT: RSS: Main: 451059712 07:59:11 INFO - 2015-12-18 07:59:11,116 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,116 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:59:11 INFO - 2015-12-18 07:59:11,116 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:59:11 INFO - 2015-12-18 07:59:11,116 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:59:11 INFO - 2015-12-18 07:59:11,116 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:59:11 INFO - 2015-12-18 07:59:11,116 DEBUG : BROWSER_OUTPUT: |0;Richards;165.97510373443984 07:59:11 INFO - 2015-12-18 07:59:11,116 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.459940652819 07:59:11 INFO - 2015-12-18 07:59:11,117 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 07:59:11 INFO - 2015-12-18 07:59:11,117 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5175.257731958763 07:59:11 INFO - 2015-12-18 07:59:11,117 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1970.4724409448818 07:59:11 INFO - 2015-12-18 07:59:11,117 DEBUG : BROWSER_OUTPUT: |5;Earley;788.0220646178093 07:59:11 INFO - 2015-12-18 07:59:11,117 DEBUG : BROWSER_OUTPUT: |6;Boyer;12000 07:59:11 INFO - 2015-12-18 07:59:11,117 DEBUG : BROWSER_OUTPUT: |7;RegExp;26605.263157894737 07:59:11 INFO - 2015-12-18 07:59:11,117 DEBUG : BROWSER_OUTPUT: |8;Splay;603.1363088057901 07:59:11 INFO - 2015-12-18 07:59:11,118 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4859.223300970873 07:59:11 INFO - 2015-12-18 07:59:11,118 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:59:11 INFO - 2015-12-18 07:59:11,118 DEBUG : BROWSER_OUTPUT: __startTimestamp1450454351114__endTimestamp 07:59:11 INFO - 2015-12-18 07:59:11,118 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,119 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:59:11 INFO - 2015-12-18 07:59:11,119 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:59:11 INFO - 2015-12-18 07:59:11,119 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,119 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.98 Median:165.98 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: Values: 166.0 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.46 Median:185.46 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: Values: 185.5 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: Values: 278.1 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: Values: 5175.3 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1970.47 Median:1970.47 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: Values: 1970.5 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:788.02 Median:788.02 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,120 DEBUG : BROWSER_OUTPUT: Values: 788.0 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12000.00 Median:12000.00 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: Values: 12000.0 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26605.26 Median:26605.26 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: Values: 26605.3 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:603.14 Median:603.14 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: Values: 603.1 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4859.22 Median:4859.22 stddev:0.00 (0.0%) 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: Values: 4859.2 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:59:11 INFO - 2015-12-18 07:59:11,121 DEBUG : BROWSER_OUTPUT: 07:59:11 INFO - 2015-12-18 07:59:11,999 INFO : Browser exited with error code: 0 07:59:12 INFO - 2015-12-18 07:59:12,011 INFO : Completed test v8_7 (00:00:39) 07:59:12 INFO - 2015-12-18 07:59:12,180 INFO : Completed test suite (00:20:18) 07:59:12 INFO - 2015-12-18 07:59:12,180 DEBUG : Working with test: dromaeo_css 07:59:12 INFO - 2015-12-18 07:59:12,180 DEBUG : Generating results file: dromaeo_css 07:59:12 INFO - 2015-12-18 07:59:12,181 DEBUG : Working with test: kraken 07:59:12 INFO - 2015-12-18 07:59:12,181 DEBUG : Generating results file: kraken 07:59:12 INFO - 2015-12-18 07:59:12,182 INFO : javascript benchmark 07:59:12 INFO - 2015-12-18 07:59:12,182 DEBUG : Working with test: v8_7 07:59:12 INFO - 2015-12-18 07:59:12,182 DEBUG : Generating results file: v8_7 07:59:12 INFO - 2015-12-18 07:59:12,182 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:59:12 INFO - 2015-12-18 07:59:12,244 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,35]] 07:59:12 INFO - 2015-12-18 07:59:12,244 DEBUG : process_Request line: dromaeo_css 6210.56 graph.html#tests=[[72,94,35]] 07:59:12 INFO - 2015-12-18 07:59:12,244 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:59:12 INFO - 2015-12-18 07:59:12,280 DEBUG : process_Request line: kraken 1518.20 graph.html#tests=[[232,94,35]] 07:59:12 INFO - 2015-12-18 07:59:12,280 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:59:12 INFO - 2015-12-18 07:59:12,315 DEBUG : process_Request line: v8_7 19886.06 graph.html#tests=[[230,94,35]] 07:59:12 INFO - 2015-12-18 07:59:12,316 INFO : javascript benchmark 07:59:12 INFO - 2015-12-18 07:59:12,319 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2630.8553029583372, 2721.9863900680507, 2728.2633688904007, 2738.479688859117, 2752.807303488755, 4563.954360456394, 4712.764361781914, 4729.834455794044, 4782.497837727041, 4803.66374353795, 4648.163330600491, 4658.0, 4751.12104260712, 4753.857384278477, 4772.379590653219, 4384.868453946387, 4424.424824772775, 4446.7776611169475, 4455.353973673816, 4477.10457908418, 4266.06146647737, 4429.623482004034, 4435.246008178618, 4490.0, 4507.00845813922, 4356.368326592658, 4373.343998400233, 4388.868333949987, 4445.355423463612, 4464.955350446519, 4387.210302145612, 4393.428854248959, 4394.274944634131, 4399.6480281577715, 4431.8448854289945, 4215.704900656955, 4386.035072284092, 4395.648348132142, 4414.381986521889, 4441.133978874121, 4602.769861506911, 4661.557152070548, 4690.413698287714, 4718.9292160617615, 4762.618990480753, 5138.897222055575, 5192.532672059533, 5194.558462530689, 5204.583633309327, 5239.973800131013, 4900.950990490085, 5216.035033518811, 5240.68555886648, 5247.212918062283, 5263.605229607795, 4714.858554243378, 4740.2889566564945, 4767.713937163781, 4797.520247975244, 4823.56587907083, 5307.708076055854, 5334.359876814807, 5340.893182136336, 5342.3856256531, 5378.838634840961, 4817.397825271841, 5092.10888094582, 5103.8724031898755, 5116.488351164928, 5116.641835071509, 5004.099262132851, 5056.747162641853, 5064.46823083571, 5106.744662766847, 5111.846644600668, 4970.925436118461, 5033.194688849767, 5044.571211446994, 5053.444121146671, 5059.544640982255, 4958.132326842788, 4961.330220420269, 4963.528464795839, 4968.056069346812, 5019.0, 4084.5098588169017, 4161.9375709364385, 4241.491021077429, 4251.192273468031, 4256.6807489438415, 3783.0353259918543, 3791.090138366772, 3815.7328986970647, 3835.6739677127757, 3836.0, 4398.8020539075815, 4451.844185453494, 4454.26504626733, 4485.372047913294, 4491.416115904912, 4955.5044495550155, 5068.315777370008, 5070.808360035388, 5072.391313042459, 5190.662606930538, 4409.669274804402, 4411.669124815588, 4412.602865742098, 4423.40284061654, 4433.445819272591, 4603.930941035887, 4617.238155704338, 4619.122366750306, 4621.815127394933, 4668.1130585189385, 4642.2340313847835, 4774.069056533943, 4784.330193772944, 4823.975880120577, 4875.439324477659, 5166.173412254021, 5172.043172013189, 5180.922286165635, 5196.584273258206, 5204.843854684365, 4885.291632713165, 4886.193778026728, 4886.3403440536495, 4907.092187945242, 4933.876653083558, 4873.805047798049, 4980.327655766425, 5100.362454693163, 5111.028904508132, 5147.124988751846, 4974.776135073858, 5028.773705183203, 5078.5175408336145, 5079.949200508096, 5133.845984620468, 4907.533784290486, 4916.311716359784, 4934.481879402754, 4950.405951285725, 4988.775505102207, 4751.192297309397, 4875.1712208923855, 4923.852284431473, 4951.653384263211, 4999.850004499871, 4746.382970213988, 4819.638527110411, 4862.17343051675, 4866.416030076413, 4871.780769865293, 4971.0, 5011.899762004812, 5015.523525265094, 5028.522290382408, 5052.368453943257, 4632.073585282889, 4741.767140543415, 4918.754062296799, 4924.4090709115135, 4941.332920055746], "unit": "score", "name": "dojo.html", "value": 4683.166734572377}, {"lowerIsBetter": false, "replicates": [573.498189084551, 573.7475510775262, 581.2153592649922, 582.7261187241995, 594.4412252482663, 26690.065847695336, 27416.86291568542, 34084.31831363375, 34931.4760278596, 35028.649713502855, 39637.603623963754, 39828.0, 39839.0, 39851.40222896659, 39993.60006399935, 6904.516683832134, 7833.960830195855, 8039.718609848656, 8089.716859909904, 8097.878531822027, 7051.365377116059, 10160.288779785418, 10429.530671119817, 10442.791144177112, 10444.216683748711, 9902.752431189207, 9988.600455981752, 9996.2502812289, 10004.49977501122, 10008.749781255454, 7127.82180445493, 7310.52481588695, 9836.458994755285, 9962.352447090952, 10057.290260655705, 9311.115444032806, 9478.146966772954, 9491.477968711719, 9501.952490237574, 9526.9523652382, 32603.0, 32920.506192407134, 32983.83508082468, 32984.83507582447, 33016.0, 2843.9146825595267, 2851.900183493568, 3390.9830450847835, 3425.6403077676982, 3456.2569047654624, 27513.0, 28035.158945231677, 28199.0, 28220.717792822015, 28394.148175554765, 3439.3121375724704, 3501.334746398202, 3515.156362473014, 3571.6606922342594, 3629.709623230135, 211585.0, 213364.0, 214264.0, 214491.0, 214601.9269903656, 51609.0, 53372.0, 54291.0, 54735.0, 56392.0, 48339.51660483421, 48505.272420913716, 48815.02369952581, 48851.51148488541, 48855.0, 12814.80777788334, 13128.081034327506, 13153.802692959613, 13158.14472059313, 13173.077884547989, 9531.38046027006, 9553.187979021723, 9574.377665451724, 9577.616895324249, 9592.08875156859, 18720.25118995225, 18920.62158756817, 19140.234390624497, 19148.138333775012, 19150.138243779063, 7170.139583249939, 7411.5553066816165, 7435.702571897172, 7448.739294124788, 7528.360089392353, 873.3449912565576, 899.2760827533767, 917.9632814687471, 922.5064590444081, 932.9830484771632, 26145.346366340993, 26353.0, 26549.0, 26848.32879177997, 26850.5972410414, 1004.0963133180196, 1025.487256371814, 1068.5040942680155, 1077.8706555213425, 1121.4336759936343, 13906.930465347608, 14066.0, 14167.362468688934, 14673.486427975184, 14864.405423782928, 261137.0, 267099.0, 268198.0, 268989.0, 269125.0, 45754.77122614365, 45927.311090333, 46110.538894611294, 46159.53840461552, 46167.76916115398, 1204.5603354775444, 1206.5475446707485, 1223.0276929840797, 1238.8079847623453, 1253.254313683357, 20246.59506809885, 25663.615045773953, 26865.865670671523, 27030.1890943272, 27075.323116922234, 36631.0, 36995.8150209258, 37058.814705927376, 37073.258534828616, 37099.443508346856, 158160.0, 158764.0, 159372.0, 159658.0, 159761.0, 24462.26613201607, 26142.73857261403, 26489.33776655599, 29057.273568160967, 29294.41411171722, 32995.505067424485, 33312.50031249582, 33363.165920852174, 33562.32875342528, 34007.82996085004, 28196.85901570479, 28549.857250713612, 28818.567721484615, 29116.27209319784, 29652.258693532833, 26732.331691707863, 30954.226144346572, 31436.842815785774, 31625.8418707905, 31773.205669857514, 28922.13233602911, 29457.263568410963, 29547.85226073856, 29786.255343616584, 29788.553171702875, 24680.629790552797, 25862.22413327603, 26461.206163815117, 26724.732752672226, 26812.195634131007, 21355.8932205338, 22584.322470325915, 22726.431839204153, 22762.772372276726, 22860.54278914379], "unit": "score", "name": "ext.html", "value": 16402.92238592757}, {"lowerIsBetter": false, "replicates": [329.4563969450408, 332.9716974057205, 335.13033677606603, 335.8088472715531, 336.89724633986623, 12405.131640785126, 12960.74078518429, 14533.128012319256, 15020.474283400083, 15861.286242119104, 9506.0, 9772.462514561696, 9777.804443911118, 14871.776923346131, 15281.847181528181, 4383.1452866690925, 4496.348029535716, 4496.6627502937245, 4501.099780044005, 4504.707194032394, 3423.6062852771875, 3524.6475352464804, 4705.811767529295, 4943.0, 5150.227465880129, 5078.746062696869, 5120.513551212629, 5126.2054381570915, 5136.178211486163, 5139.203423469368, 4939.456659767441, 5023.296738456619, 5095.515925987025, 5114.974425127869, 5117.257997590347, 4886.218205087169, 4892.877678058041, 4893.779779909913, 4897.534734200245, 4900.117978763821, 10776.191785616033, 13422.53021144265, 16498.917505412395, 19489.707654385194, 19618.11718472672, 1790.7851057873008, 1816.8819026763351, 1868.3927723489785, 1972.891490967996, 2003.6092961872296, 8547.401681882271, 8664.566771661392, 8754.3871928965, 8858.512781796999, 8874.334424918157, 1668.8664906807426, 1704.1649591700102, 1722.6726921884801, 1733.1940647598926, 1766.0375095572945, 54943.45056549463, 57407.42592574062, 57740.422595773925, 57886.99999999958, 58102.41897581012, 9633.133018028271, 9660.227181825438, 9729.0, 9752.658656946973, 9794.265430092772, 12464.875351246554, 12474.81287780684, 12484.68788280282, 12502.81245781314, 12520.0, 6503.349665033459, 6552.737890484423, 6557.770478033246, 6573.671316434159, 6601.141851559362, 5977.163197152402, 6079.179310792986, 6092.482139018233, 6093.0, 6105.297890742621, 3817.4464702617965, 3893.0, 4006.859759908389, 4139.503259608866, 4168.374743788456, 1601.191398343829, 1643.1784107946028, 1677.563833403324, 1704.8380403861613, 1747.9650406991789, 831.4512421801581, 859.5702148925537, 875.913866805157, 930.930180236485, 945.8061097475002, 14319.0, 14544.49094281695, 14688.706225875423, 14957.401703931939, 15006.399744010336, 761.9733309334147, 792.9008873890764, 831.3266254334009, 849.1084361420484, 916.1067958740175, 8415.95792021036, 8609.65561377538, 8639.265662418766, 8697.695580654647, 8811.427257228259, 451218.0, 454345.0, 459517.0, 462691.0, 463175.0, 71697.0, 72013.6399318, 72353.2764672357, 72728.27271727321, 74440.62779686175, 1016.8118898003893, 1030.1038096855634, 1037.092544023979, 1058.491923876559, 1059.7403636109202, 13333.266670333225, 13978.44086236539, 14568.562943111723, 15017.32422040989, 15271.236438177824, 33146.83426582852, 33336.83331583327, 34031.659683402846, 34355.82822085873, 34625.48061779025, 40557.797211013756, 42689.786551067045, 43035.0, 48002.0, 49314.260286096454, 8482.236598705898, 8606.354523410644, 8708.69519566812, 8717.128287171487, 8809.471431714117, 15643.29605167793, 15955.441559545361, 16054.0, 16362.836371636608, 16482.25829837684, 13863.72272554523, 13894.166350019032, 14027.719445610826, 14042.78935815984, 14045.789313160516, 13160.276184809927, 13298.667533311746, 13669.453221871014, 13775.0, 13806.861931380558, 10526.78946421052, 13433.596992090253, 13732.313384330942, 13973.720525589635, 14456.0, 8382.580870956306, 8752.474851508676, 9082.682106126254, 9227.261819054325, 9229.769255768659, 8850.911490885008, 8904.554772261232, 14087.929560352542, 14149.280853889231, 14345.784813227601], "unit": "score", "name": "jquery.html", "value": 7991.50420149606}, {"lowerIsBetter": false, "replicates": [1425.479699909532, 1623.5697540151862, 1690.8900921440113, 1713.2975480053176, 1719.226348143335, 6280.528960327976, 6819.488538359618, 7070.4343652507805, 7083.291670832914, 7086.503944723871, 7043.049188359582, 7158.391536719372, 7170.318819712132, 7182.461315401352, 7188.0, 1744.886582372148, 1773.0248363400142, 2042.356657652842, 2099.076406381191, 2110.7256056712604, 2081.4172031831035, 2085.979140208601, 2088.0290664840845, 2090.9790902090936, 2091.9058642361056, 2026.8041855305366, 2032.908519116636, 2068.9275875344365, 2070.544480214358, 2072.585482903425, 2037.368415791102, 2042.7650820155723, 2058.9794102058936, 2063.576966721826, 2072.968905466419, 2084.781097984705, 2111.9577608447744, 2112.387407651779, 2115.7566879808865, 2115.95768084639, 7152.928470715278, 7164.892526612105, 7220.783376498714, 7229.819254518627, 7230.060092187982, 4138.172365526907, 4216.873493795191, 4221.767802770846, 4234.661227101855, 4241.660667146621, 2613.89544418223, 2688.9731102689116, 2694.191742477249, 2696.8942733479375, 2712.308361367858, 844.5017439710539, 848.452747977551, 866.0300463480942, 869.956502174895, 872.3021582733851, 9316.208122309612, 9329.44023358594, 9365.672201472984, 9385.953070234606, 9394.107559781809, 3694.168812017275, 3830.5020347354666, 3864.3430616795213, 3877.8448862045766, 3881.0879443330796, 8081.959590202011, 8087.272145506815, 8092.919070809335, 8107.716229931924, 8111.310538604167, 4243.511996120424, 4273.444452221253, 4274.871753847389, 4278.935815962763, 4279.828806847753, 3830.8467661293794, 3852.171783066654, 3857.61423857612, 3859.749116307431, 3864.9806750966063, 2945.322575807533, 2986.2982199183175, 3379.2903490267317, 3428.0915557377316, 3437.2438063625964, 1053.3469249065474, 1068.8824229334766, 1074.033369967036, 1080.2924084724518, 1087.2660953856116, 872.9170728780756, 880.308957468397, 956.7655924298591, 1150.442035612727, 1152.4986630815622, 6494.512911531654, 6622.735090596322, 6672.232693240242, 6685.832854178587, 6733.158355205599, 1028.619410818002, 1052.4264275969615, 1055.0188324857802, 1056.0442799266675, 1058.1957712138676, 5294.364676238876, 5379.784808607612, 5393.865153371197, 5412.756425960842, 5413.269208656781, 8284.047334556482, 8412.284955778705, 8423.70517031901, 8430.199131082538, 8431.072582015973, 8167.591620418955, 8277.95861020691, 8303.252707256375, 8319.916800832036, 8345.791355216168, 6545.738170473223, 6554.8856694362075, 6623.7350505980185, 6680.86638267232, 6691.498137639696, 6697.430718388797, 6725.596464212163, 6748.595084294958, 6749.831254218684, 6772.966135169293, 7517.361024312776, 7665.540067595962, 7774.455788095005, 7783.260590243918, 7787.260210280015, 7630.779075347918, 7906.327962123284, 7917.247861453153, 8018.358531317597, 8028.076771171156, 5756.65460072397, 5790.855228619318, 5816.1857339973285, 5841.18223448709, 5925.0, 5726.169705392778, 5915.674637894957, 6363.649999250086, 6460.67696615158, 6512.251091124392, 6364.427201551883, 6465.321141280097, 6471.417572418505, 6562.048502967138, 6605.57063790852, 6357.110004599267, 6399.200099987502, 6460.192475940507, 6472.676366181578, 6528.118703974903, 6299.905501417574, 6342.587731797418, 6347.301796802441, 6354.745810167727, 6358.109864618865, 5824.184614153937, 5853.239078919713, 5879.382664820303, 5881.441263080001, 5886.323072846678, 6055.5458340623745, 6066.696665166635, 6156.5998210116195, 6172.043333283348, 6175.536834737323], "unit": "score", "name": "mootools.html", "value": 4062.763836235474}, {"lowerIsBetter": false, "replicates": [285.57591975915767, 293.62855987176226, 296.35690551503245, 297.7454276593511, 298.4538294920296, 5286.074936886044, 5310.973445132774, 5319.20211968205, 5338.225957236198, 5352.2506849041065, 5228.215767634857, 5279.076161671711, 5303.893922121555, 5313.990341835059, 5319.20211968205, 4145.54399016108, 4148.605882441163, 4150.190712810995, 4163.958360416403, 4166.458360413158, 3974.3641017437217, 4055.634992850643, 4077.531083925357, 4122.546519882811, 4182.769947652878, 4133.586641335857, 4137.958620413788, 4143.8964025899295, 4146.8963275918195, 4147.523034851, 3885.572587015426, 4119.011437255052, 4130.896727581804, 4133.1940271647045, 4134.049168691203, 3448.2758620689683, 3518.753687241894, 3561.664375859053, 3627.963720362789, 3630.1832087780303, 5103.209002604565, 5118.436971933084, 5125.743712814345, 5146.279520867081, 5149.922751158736, 4739.526047395268, 4741.8103275868925, 4745.335653008581, 4746.810127594892, 4751.619870410359, 5437.564994800407, 5619.522340601054, 5633.521150702154, 5637.210790489294, 5639.182318563826, 3847.6344747249245, 3849.5188101487315, 3851.8074096295354, 3854.3062248795204, 3857.0550215197177, 5794.420557944214, 5868.09044598088, 5876.500497457721, 5881.823545293648, 5883.411658834082, 3919.1377896863214, 3920.0787814863484, 3920.176762879827, 3921.6470517653547, 3923.5684074752326, 3978.3037968356, 3982.0, 3982.984338993596, 3984.4222587724616, 3987.0, 4513.074819661962, 4595.517470665599, 4611.76941152941, 4639.907201855944, 4668.11305851887, 5665.773369065274, 5673.156224227143, 5674.432556744293, 5678.971605141948, 5684.459976302244, 1399.0206855201197, 1410.4922227997913, 1411.5906387147818, 1413.4275618374575, 1413.8444771075174, 937.0910217089412, 940.308872978374, 943.8112377524385, 956.5743244256379, 961.6682244625594, 4975.925361119586, 4990.675606085593, 4991.975040124848, 4992.90014199714, 4993.151164302005, 5885.0, 5891.3814049525035, 5897.823065307962, 5901.409859014064, 5902.380250073767, 4972.229304457815, 4972.279019542146, 4980.7509624518625, 4993.6005119590345, 4997.200447928314, 3053.2519532714628, 3090.6136732908385, 3143.38703952727, 3160.3047329587453, 3179.5389668497937, 5462.863428414322, 5534.363548191929, 5590.636608620427, 5635.690037048002, 5646.57650676201, 6041.456268935817, 6102.115193296947, 6103.176071230327, 6105.48103411215, 6122.938770612326, 5547.0, 5580.414056524088, 5598.1805913078415, 5605.738708790571, 5607.495325420732, 5763.164341170591, 5941.435563621449, 5958.821235362946, 5963.403659634176, 5970.492508136857, 5781.739821707949, 5792.594518383673, 5797.797077102281, 5798.724280658248, 5802.042662960733, 5604.439556044363, 5623.325200975909, 5624.493795558419, 5632.098864181711, 5632.943670563406, 3868.8259028343227, 3926.842926282917, 3942.5071866016747, 3946.703997200164, 3947.467091942551, 5863.442972917566, 5870.853228669317, 5873.853153671193, 5876.6180198286975, 5877.500412464818, 5644.8024319148635, 5656.773729050792, 5662.546996240373, 5663.1788390682295, 5663.263775709132, 5649.745761440828, 5651.8304450866535, 5652.152177173457, 5657.236273103078, 5661.801836935688, 5399.568034557147, 5527.0, 5538.723063846872, 5540.584456165723, 5553.0559804832465, 5674.858128546654, 5685.317761868602, 5687.374388817144, 5687.668056097146, 5694.544436444991, 5546.389897111234, 5548.500634942875, 5556.333240011225, 5559.972200138974, 5571.972140139435], "unit": "score", "name": "prototype.html", "value": 4213.206764631879}, {"lowerIsBetter": false, "replicates": [659.4295934017075, 707.5931339479798, 738.1769327200174, 741.5106030020188, 757.2768006553741, 10452.16382689385, 10889.455527223647, 10895.291806032603, 10899.618513352025, 10906.454677266134, 11212.887871121286, 11385.658430247086, 11389.373584452851, 11391.145664075186, 11399.601013964513, 622.2843729710846, 632.178168381105, 780.4497829030535, 795.1134485049187, 813.9104698483192, 773.5358784729174, 799.0770659887838, 800.1078797141175, 806.6450761664848, 807.0114110214998, 663.0948754949484, 810.027966440271, 838.651959436833, 845.5729856422498, 846.7713717296326, 657.3262406033806, 820.1142765812938, 832.7543374704477, 835.0980940584216, 835.9038710548303, 603.6891001134419, 611.9510439164856, 625.7684656677059, 625.777848863656, 626.4518546272012, 11386.601468948547, 11444.313341199555, 11545.422728863607, 11566.190366674336, 11577.942110289394, 11287.661370158909, 11510.30938143714, 11721.589744358907, 11940.940295298555, 11999.400029998465, 9595.664151754725, 9596.856047159297, 9635.759106022335, 9656.90343096567, 9689.563969621313, 3230.789998650104, 3261.8858339957987, 3286.375588638151, 3307.2724000719804, 3338.582677165354, 37163.442548361796, 37532.624673753184, 37595.81202093999, 37610.62389376099, 37706.24587508262, 8760.299176065899, 8845.380823342304, 8869.06874778139, 8963.148500892405, 9001.054889236666, 15197.0, 15268.31292591836, 15777.68444631101, 15851.603709907344, 15934.760978585331, 6063.787767428119, 6194.101855231056, 6335.049742538656, 6365.2998170201245, 6395.296517383084, 3522.2427178156818, 3602.8018458985007, 3637.0543658648944, 3650.580183278904, 3651.561812582507, 2497.463045445238, 2546.17249393944, 2549.8980040798533, 2561.180422264895, 2564.6537717408282, 429.8710386883922, 441.17720451357985, 442.03856611869054, 450.0279396503537, 450.9391232183613, 410.2226281197115, 433.1920967395784, 450.87150162203613, 452.0326501287248, 461.4462644826222, 6915.162258246617, 7212.423006159494, 7473.51422157558, 7813.765587032398, 7857.253560911704, 420.7790909772394, 450.2862962204942, 486.7639194990427, 526.7998160698986, 578.0519947286454, 3196.328770958078, 3199.6480387157394, 3258.332041931398, 3290.6380298167182, 3454.3264063507877, 44945.775271123435, 48383.516164838606, 48426.0, 48473.75763121162, 48953.0, 39524.0, 39648.60351396449, 39828.601713983066, 40011.79994100011, 41605.583944160775, 39642.40536391956, 39800.2039959205, 40350.59649403527, 40363.394549081786, 40807.387889181686, 7364.3003914628025, 7442.702291908263, 7445.532332166944, 7480.775576732707, 7684.193159718149, 29440.0, 30137.547936781404, 30232.244193895327, 30341.39317213599, 30484.237894052825, 37575.0, 38404.80797595994, 38509.614903851725, 38703.22593548169, 39139.41290880696, 9349.766255843659, 9370.718878433385, 9636.325457218209, 9771.12059914611, 9851.45816980073, 10286.948565257126, 10393.480325983519, 10882.782344353227, 10977.560897564328, 11031.724206894893, 9969.252306076929, 10090.69727908164, 10195.898041019494, 10368.429736364573, 10711.571537138427, 10067.647632332833, 10248.692539223835, 10332.225083118647, 10607.734806629896, 10624.575016999233, 9640.614375424904, 9885.752856178653, 10042.096211341015, 10242.795144097225, 10311.793764124526, 8474.872876906975, 8791.208791208823, 8802.691905783267, 8972.551372431482, 9068.455892646198, 8453.830923381622, 8579.356548259031, 8659.090795466638, 8741.650333986569, 8931.151540603632], "unit": "score", "name": "yui.html", "value": 5460.319557116135}], "name": "dromaeo_css", "value": 6210.561685513044}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 113.0, 107.0, 111.0, 113.0, 113.0, 103.0, 113.0, 112.0, 119.0], "unit": "score", "name": "ai-astar", "value": 111.6}, {"lowerIsBetter": true, "replicates": [157.0, 129.0, 159.0, 171.0, 135.0, 152.0, 158.0, 134.0, 175.0, 129.0], "unit": "score", "name": "audio-beat-detection", "value": 149.9}, {"lowerIsBetter": true, "replicates": [163.0, 160.0, 162.0, 162.0, 159.0, 159.0, 161.0, 163.0, 161.0, 161.0], "unit": "score", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 82.0, 95.0, 72.0, 73.0, 72.0, 96.0, 90.0, 73.0], "unit": "score", "name": "audio-fft", "value": 79.8}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 129.0, 93.0, 93.0, 95.0, 143.0, 95.0, 96.0, 132.0], "unit": "score", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 110.0, 109.0, 111.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 148.0, 147.0, 148.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 71.0, 71.0, 71.0, 71.0, 70.0, 79.0, 69.0, 69.0], "unit": "score", "name": "json-parse-financial", "value": 71.5}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 68.0, 68.0, 68.0, 70.0, 68.0, 70.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.2}, {"lowerIsBetter": true, "replicates": [80.0, 83.0, 83.0, 83.0, 83.0, 82.0, 82.0, 84.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.5}, {"lowerIsBetter": true, "replicates": [127.0, 123.0, 121.0, 120.0, 127.0, 123.0, 123.0, 126.0, 118.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.4}, {"lowerIsBetter": true, "replicates": [134.0, 151.0, 146.0, 147.0, 152.0, 135.0, 147.0, 144.0, 145.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.4}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 69.0, 68.0, 70.0, 68.0, 68.0, 68.0, 68.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.4}], "name": "kraken", "value": 1518.2000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.97510373443984], "unit": "score", "name": "Richards", "value": 212.69455}, {"lowerIsBetter": false, "replicates": [185.459940652819], "unit": "score", "name": "DeltaBlue", "value": 356.508256}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1970.4724409448818], "unit": "score", "name": "RayTrace", "value": 375.5388731268733}, {"lowerIsBetter": false, "replicates": [788.0220646178093], "unit": "score", "name": "Earley", "value": 845.7415470000001}, {"lowerIsBetter": false, "replicates": [12000.0], "unit": "score", "name": "Boyer", "value": 55.53858333333334}, {"lowerIsBetter": false, "replicates": [26605.263157894737], "unit": "score", "name": "RegExp", "value": 34.24078140454997}, {"lowerIsBetter": false, "replicates": [603.1363088057901], "unit": "score", "name": "Splay", "value": 135.11207800000005}, {"lowerIsBetter": false, "replicates": [4859.223300970873], "unit": "score", "name": "NavierStokes", "value": 305.39860139860156}], "name": "v8_7", "value": 19886.061439079305}]} 07:59:12 INFO - RETURN: dromaeo_css: 6210.56 07:59:12 INFO - RETURN: kraken: 1518.20 07:59:12 INFO - RETURN: v8_7: 19886.06 07:59:12 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19886.06"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1518.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6210.56"}}} 07:59:12 INFO - Return code: 0 07:59:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:59:12 INFO - # TBPL SUCCESS # 07:59:12 INFO - Running post-action listener: _resource_record_post_action 07:59:12 INFO - Running post-run listener: _resource_record_post_run 07:59:13 INFO - Total resource usage - Wall time: 1235s; CPU: 13.0%; Read bytes: 28672; Write bytes: 553439232; Read time: 76; Write time: 687420 07:59:13 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 0; Write bytes: 238915584; Read time: 0; Write time: 450872 07:59:13 INFO - run-tests - Wall time: 1220s; CPU: 13.0%; Read bytes: 28672; Write bytes: 314523648; Read time: 76; Write time: 236548 07:59:13 INFO - Running post-run listener: _upload_blobber_files 07:59:13 INFO - Blob upload gear active. 07:59:13 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:59:13 INFO - Copying logs to upload dir... 07:59:13 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1272.072033 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-18 07:59:13.407368) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 07:59:13.410421) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009830 build_url: 'https://queue.taskcluster.net/v1/task/6A_8yqoITg-7k7-G4srxGQ/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:59:13.461293) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:59:13.461626) ========= 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=e25b24362c3bb3dea6e300e4000001c1-1450446823.888332-811920529 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 07:59:13.510889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 07:59:13.511237) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 07:59:13.511529) ========= ========= Total master_lag: 0.30 =========