builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-013 starttime: 1482535598.98 results: success (0) buildid: 20161223143436 builduid: dbe5aa66022644ce8dadcfe0b0638cf3 revision: dd35d18c8e14751cca293db90ea2205e0a0871c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:38.978101) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:38.978483) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:38.978773) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:38.995523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:38.995793) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-23 15:26:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-12-23 15:26:39 (113 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.069634 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:39.078828) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:39.079135) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017040 ========= master_lag: 0.20 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:39.291449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 15:26:39.293323) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd35d18c8e14751cca293db90ea2205e0a0871c7 --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 dd35d18c8e14751cca293db90ea2205e0a0871c7 --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=9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-23 15:26:39,874 truncating revision to first 12 chars 2016-12-23 15:26:39,874 Setting DEBUG logging. 2016-12-23 15:26:39,874 attempt 1/10 2016-12-23 15:26:39,874 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd35d18c8e14?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 15:26:41,167 unpacking tar archive at: mozilla-central-dd35d18c8e14/testing/mozharness/ program finished with exit code 0 elapsedTime=1.664535 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 15:26:40.970938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:40.971276) ========= script_repo_revision: dd35d18c8e14751cca293db90ea2205e0a0871c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:40.971700) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:40.971999) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 15:26:40.981705) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-23 15:26:40.981992) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --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', '--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=9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:26:41 INFO - MultiFileLogger online at 20161223 15:26:41 in /builds/slave/test 15:26:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:26:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:26:41 INFO - {'append_to_log': False, 15:26:41 INFO - 'base_work_dir': '/builds/slave/test', 15:26:41 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:26:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:26:41 INFO - 'branch': 'Firefox-Non-PGO', 15:26:41 INFO - 'buildbot_json_path': 'buildprops.json', 15:26:41 INFO - 'config_files': ('talos/linux_config.py',), 15:26:41 INFO - 'default_actions': ('clobber', 15:26:41 INFO - 'read-buildbot-config', 15:26:41 INFO - 'download-and-extract', 15:26:41 INFO - 'populate-webroot', 15:26:41 INFO - 'create-virtualenv', 15:26:41 INFO - 'install', 15:26:41 INFO - 'run-tests'), 15:26:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:26:41 INFO - 'download_minidump_stackwalk': True, 15:26:41 INFO - 'download_symbols': 'ondemand', 15:26:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:26:41 INFO - 'tooltool.py': '/tools/tooltool.py', 15:26:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:26:41 INFO - '/tools/misc-python/virtualenv.py')}, 15:26:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:26:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:26:41 INFO - 'installer_path': 'installer.exe', 15:26:41 INFO - 'log_level': 'info', 15:26:41 INFO - 'log_name': 'talos', 15:26:41 INFO - 'log_to_console': True, 15:26:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:26:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:26:41 INFO - 'opt_config_files': (), 15:26:41 INFO - 'pip_index': False, 15:26:41 INFO - 'sps_profile': False, 15:26:41 INFO - 'sps_profile_interval': 0, 15:26:41 INFO - 'suite': 'dromaeojs', 15:26:41 INFO - 'system_bits': '32', 15:26:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:26:41 INFO - 'title': 'talos-linux64-ix-013', 15:26:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:26:41 INFO - 'use_talos_json': True, 15:26:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:26:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:26:41 INFO - 'work_dir': 'build'} 15:26:41 INFO - [mozharness: 2016-12-23 23:26:41.662637Z] Running clobber step. 15:26:41 INFO - Running pre-action listener: _resource_record_pre_action 15:26:41 INFO - Running main action method: clobber 15:26:41 INFO - rmtree: /builds/slave/test/build 15:26:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:26:42 INFO - Running post-action listener: _resource_record_post_action 15:26:42 INFO - [mozharness: 2016-12-23 23:26:42.134786Z] Finished clobber step (success) 15:26:42 INFO - [mozharness: 2016-12-23 23:26:42.134887Z] Running read-buildbot-config step. 15:26:42 INFO - Running pre-action listener: _resource_record_pre_action 15:26:42 INFO - Running main action method: read_buildbot_config 15:26:42 INFO - Using buildbot properties: 15:26:42 INFO - { 15:26:42 INFO - "project": "", 15:26:42 INFO - "product": "firefox", 15:26:42 INFO - "script_repo_revision": "production", 15:26:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:26:42 INFO - "repository": "", 15:26:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:26:42 INFO - "buildid": "20161223143436", 15:26:42 INFO - "pgo_build": "False", 15:26:42 INFO - "basedir": "/builds/slave/test", 15:26:42 INFO - "buildnumber": 355, 15:26:42 INFO - "slavename": "talos-linux64-ix-013", 15:26:42 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:26:42 INFO - "platform": "ubuntu64_hw", 15:26:42 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:26:42 INFO - "branch": "mozilla-central", 15:26:42 INFO - "repo_path": "mozilla-central", 15:26:42 INFO - "revision": "dd35d18c8e14751cca293db90ea2205e0a0871c7", 15:26:42 INFO - "stage_platform": "linux64", 15:26:42 INFO - "builduid": "dbe5aa66022644ce8dadcfe0b0638cf3", 15:26:42 INFO - "slavebuilddir": "test" 15:26:42 INFO - } 15:26:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 15:26:42 INFO - Running post-action listener: _resource_record_post_action 15:26:42 INFO - [mozharness: 2016-12-23 23:26:42.137337Z] Finished read-buildbot-config step (success) 15:26:42 INFO - [mozharness: 2016-12-23 23:26:42.137426Z] Running download-and-extract step. 15:26:42 INFO - Running pre-action listener: _resource_record_pre_action 15:26:42 INFO - Running main action method: download_and_extract 15:26:42 INFO - mkdir: /builds/slave/test/build/tests 15:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:42 INFO - trying https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:26:42 INFO - Downloading https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:26:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:26:42 INFO - Downloaded 1361 bytes. 15:26:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:26:42 INFO - Using the following test package requirements: 15:26:42 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 15:26:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:26:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:26:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'jsshell-linux-x86_64.zip'], 15:26:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:26:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 15:26:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:26:42 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:26:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:26:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:26:42 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:26:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:26:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 15:26:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:26:42 INFO - Fetch https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:26:43 INFO - Expected file size: 20236300 15:26:43 INFO - Obtained file size: 20236300 15:26:47 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:26:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 15:26:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:26:47 INFO - Fetch https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:26:48 INFO - Expected file size: 20236300 15:26:48 INFO - Obtained file size: 20236300 15:26:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 15:26:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:26:52 INFO - Fetch https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:26:53 INFO - Expected file size: 14409776 15:26:53 INFO - Obtained file size: 14409776 15:26:53 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:53 INFO - trying https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:26:53 INFO - Downloading https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:26:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:26:54 INFO - Downloaded 55915519 bytes. 15:26:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:26:54 INFO - mkdir: /builds/slave/test/properties 15:26:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:26:54 INFO - Writing to file /builds/slave/test/properties/build_url 15:26:54 INFO - Contents: 15:26:54 INFO - build_url:https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:26:55 INFO - Running post-action listener: _resource_record_post_action 15:26:55 INFO - Running post-action listener: set_extra_try_arguments 15:26:55 INFO - [mozharness: 2016-12-23 23:26:55.351235Z] Finished download-and-extract step (success) 15:26:55 INFO - [mozharness: 2016-12-23 23:26:55.351512Z] Running populate-webroot step. 15:26:55 INFO - Running pre-action listener: _resource_record_pre_action 15:26:55 INFO - Running main action method: populate_webroot 15:26:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:26:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:26:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:26:55 INFO - '--tppagecycles', 15:26:55 INFO - '7'], 15:26:55 INFO - 'tests': ['tsvgm']}}, 15:26:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:26:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:26:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:26:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:26:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:26:55 INFO - 'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:26:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:26:55 INFO - 'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['damp', 'tps']}, 15:26:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:26:55 INFO - 'tests': ['damp', 'tps']}, 15:26:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['dromaeo_dom']}, 15:26:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:26:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['basic_compositor_video']}, 15:26:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:26:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['a11yr', 15:26:55 INFO - 'ts_paint', 15:26:55 INFO - 'tpaint', 15:26:55 INFO - 'sessionrestore', 15:26:55 INFO - 'sessionrestore_no_auto_restore', 15:26:55 INFO - 'tabpaint']}, 15:26:55 INFO - 'other-e10s': {'tests': ['a11yr', 15:26:55 INFO - 'ts_paint', 15:26:55 INFO - 'tpaint', 15:26:55 INFO - 'sessionrestore', 15:26:55 INFO - 'sessionrestore_no_auto_restore', 15:26:55 INFO - 'tabpaint']}, 15:26:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:26:55 INFO - 'ts_paint', 15:26:55 INFO - 'tpaint', 15:26:55 INFO - 'sessionrestore', 15:26:55 INFO - 'sessionrestore_no_auto_restore', 15:26:55 INFO - 'tabpaint']}, 15:26:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:26:55 INFO - 'ts_paint', 15:26:55 INFO - 'tpaint', 15:26:55 INFO - 'sessionrestore', 15:26:55 INFO - 'sessionrestore_no_auto_restore', 15:26:55 INFO - 'tabpaint']}, 15:26:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['a11yr', 15:26:55 INFO - 'ts_paint', 15:26:55 INFO - 'tpaint', 15:26:55 INFO - 'sessionrestore', 15:26:55 INFO - 'sessionrestore_no_auto_restore', 15:26:55 INFO - 'tabpaint']}, 15:26:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['a11yr', 15:26:55 INFO - 'ts_paint', 15:26:55 INFO - 'tpaint', 15:26:55 INFO - 'sessionrestore', 15:26:55 INFO - 'sessionrestore_no_auto_restore', 15:26:55 INFO - 'tabpaint']}, 15:26:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['tsvgx', 15:26:55 INFO - 'tsvgr_opacity', 15:26:55 INFO - 'tart', 15:26:55 INFO - 'tscrollx', 15:26:55 INFO - 'cart', 15:26:55 INFO - 'tsvg_static']}, 15:26:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:26:55 INFO - 'tsvgr_opacity', 15:26:55 INFO - 'tart', 15:26:55 INFO - 'tscrollx', 15:26:55 INFO - 'cart', 15:26:55 INFO - 'tsvg_static']}, 15:26:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:26:55 INFO - 'talos_options': ['--disable-e10s'], 15:26:55 INFO - 'tests': ['tp5o']}, 15:26:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:26:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:26:55 INFO - 'talos_options': ['--disable-e10s', 15:26:55 INFO - '--xperf_path', 15:26:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:55 INFO - 'tests': ['tp5n']}, 15:26:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:26:55 INFO - 'talos_options': ['--xperf_path', 15:26:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:55 INFO - 'tests': ['tp5n']}}, 15:26:55 INFO - 'talos.zip': {'path': '', 15:26:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:26:55 INFO - Running post-action listener: _resource_record_post_action 15:26:55 INFO - [mozharness: 2016-12-23 23:26:55.367060Z] Finished populate-webroot step (success) 15:26:55 INFO - [mozharness: 2016-12-23 23:26:55.367150Z] Running create-virtualenv step. 15:26:55 INFO - Running pre-action listener: _resource_record_pre_action 15:26:55 INFO - Running main action method: create_virtualenv 15:26:55 INFO - Creating virtualenv /builds/slave/test/build/venv 15:26:55 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 15:26:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:26:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:26:55 INFO - Using real prefix '/usr' 15:26:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:26:55 INFO - Installing distribute.............................................................................................................................................................................................done. 15:26:57 INFO - Installing pip.................done. 15:26:57 INFO - Return code: 0 15:26:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:26:58 INFO - Reading from file tmpfile_stdout 15:26:58 INFO - Output received: 15:26:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:26:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:26:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:26:58 INFO - Using env: {'DISPLAY': ':0', 15:26:58 INFO - 'HOME': '/home/cltbld', 15:26:58 INFO - 'LANG': 'en_US.UTF-8', 15:26:58 INFO - 'LANGUAGE': 'en_US:en', 15:26:58 INFO - 'LOGNAME': 'cltbld', 15:26:58 INFO - 'MAIL': '/var/mail/cltbld', 15:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:58 INFO - 'MOZ_NO_REMOTE': '1', 15:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:58 INFO - 'NO_EM_RESTART': '1', 15:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:58 INFO - 'PWD': '/builds/slave/test', 15:26:58 INFO - 'SHELL': '/bin/bash', 15:26:58 INFO - 'SHLVL': '1', 15:26:58 INFO - 'TERM': 'linux', 15:26:58 INFO - 'TMOUT': '86400', 15:26:58 INFO - 'USER': 'cltbld', 15:26:58 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:58 INFO - '_': '/tools/buildbot/bin/python'} 15:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:58 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 15:26:58 INFO - Cleaning up... 15:26:58 INFO - Return code: 0 15:26:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:26:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:26:58 INFO - Using env: {'DISPLAY': ':0', 15:26:58 INFO - 'HOME': '/home/cltbld', 15:26:58 INFO - 'LANG': 'en_US.UTF-8', 15:26:58 INFO - 'LANGUAGE': 'en_US:en', 15:26:58 INFO - 'LOGNAME': 'cltbld', 15:26:58 INFO - 'MAIL': '/var/mail/cltbld', 15:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:58 INFO - 'MOZ_NO_REMOTE': '1', 15:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:58 INFO - 'NO_EM_RESTART': '1', 15:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:58 INFO - 'PWD': '/builds/slave/test', 15:26:58 INFO - 'SHELL': '/bin/bash', 15:26:58 INFO - 'SHLVL': '1', 15:26:58 INFO - 'TERM': 'linux', 15:26:58 INFO - 'TMOUT': '86400', 15:26:58 INFO - 'USER': 'cltbld', 15:26:58 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:58 INFO - '_': '/tools/buildbot/bin/python'} 15:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:58 INFO - Downloading/unpacking psutil>=3.1.1 15:26:58 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 15:26:58 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 15:26:58 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 15:26:58 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 15:26:58 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 15:26:58 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 15:27:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:27:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:27:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:27:00 INFO - Installing collected packages: psutil 15:27:00 INFO - Running setup.py install for psutil 15:27:00 INFO - building 'psutil._psutil_linux' extension 15:27:00 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 15:27:00 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 15:27:00 INFO - building 'psutil._psutil_posix' extension 15:27:00 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 15:27:01 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 15:27:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:27:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:27:01 INFO - Successfully installed psutil 15:27:01 INFO - Cleaning up... 15:27:01 INFO - Return code: 0 15:27:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:27:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:27:01 INFO - Using env: {'DISPLAY': ':0', 15:27:01 INFO - 'HOME': '/home/cltbld', 15:27:01 INFO - 'LANG': 'en_US.UTF-8', 15:27:01 INFO - 'LANGUAGE': 'en_US:en', 15:27:01 INFO - 'LOGNAME': 'cltbld', 15:27:01 INFO - 'MAIL': '/var/mail/cltbld', 15:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:01 INFO - 'MOZ_NO_REMOTE': '1', 15:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:01 INFO - 'NO_EM_RESTART': '1', 15:27:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:01 INFO - 'PWD': '/builds/slave/test', 15:27:01 INFO - 'SHELL': '/bin/bash', 15:27:01 INFO - 'SHLVL': '1', 15:27:01 INFO - 'TERM': 'linux', 15:27:01 INFO - 'TMOUT': '86400', 15:27:01 INFO - 'USER': 'cltbld', 15:27:01 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:01 INFO - '_': '/tools/buildbot/bin/python'} 15:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:27:03 INFO - Installing collected packages: mozsystemmonitor 15:27:03 INFO - Running setup.py install for mozsystemmonitor 15:27:03 INFO - Successfully installed mozsystemmonitor 15:27:03 INFO - Cleaning up... 15:27:03 INFO - Return code: 0 15:27:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:27:03 INFO - Using env: {'DISPLAY': ':0', 15:27:03 INFO - 'HOME': '/home/cltbld', 15:27:03 INFO - 'LANG': 'en_US.UTF-8', 15:27:03 INFO - 'LANGUAGE': 'en_US:en', 15:27:03 INFO - 'LOGNAME': 'cltbld', 15:27:03 INFO - 'MAIL': '/var/mail/cltbld', 15:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:03 INFO - 'MOZ_NO_REMOTE': '1', 15:27:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:03 INFO - 'NO_EM_RESTART': '1', 15:27:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:03 INFO - 'PWD': '/builds/slave/test', 15:27:03 INFO - 'SHELL': '/bin/bash', 15:27:03 INFO - 'SHLVL': '1', 15:27:03 INFO - 'TERM': 'linux', 15:27:03 INFO - 'TMOUT': '86400', 15:27:03 INFO - 'USER': 'cltbld', 15:27:03 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:03 INFO - '_': '/tools/buildbot/bin/python'} 15:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:03 INFO - Downloading/unpacking jsonschema==2.5.1 15:27:03 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 15:27:03 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 15:27:03 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 15:27:03 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 15:27:03 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 15:27:03 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 15:27:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:27:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:05 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 15:27:06 INFO - Downloading functools32-3.2.3-2.tar.gz 15:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:27:06 INFO - warning: no files found matching '*.txt' 15:27:06 INFO - no previously-included directories found matching 'build' 15:27:06 INFO - no previously-included directories found matching 'dist' 15:27:06 INFO - no previously-included directories found matching '.git*' 15:27:06 INFO - Installing collected packages: jsonschema, functools32 15:27:06 INFO - Running setup.py install for functools32 15:27:06 INFO - warning: no files found matching '*.txt' 15:27:06 INFO - no previously-included directories found matching 'build' 15:27:06 INFO - no previously-included directories found matching 'dist' 15:27:06 INFO - no previously-included directories found matching '.git*' 15:27:06 INFO - Successfully installed jsonschema functools32 15:27:06 INFO - Cleaning up... 15:27:06 INFO - Return code: 0 15:27:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:27:06 INFO - Using env: {'DISPLAY': ':0', 15:27:06 INFO - 'HOME': '/home/cltbld', 15:27:06 INFO - 'LANG': 'en_US.UTF-8', 15:27:06 INFO - 'LANGUAGE': 'en_US:en', 15:27:06 INFO - 'LOGNAME': 'cltbld', 15:27:06 INFO - 'MAIL': '/var/mail/cltbld', 15:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:06 INFO - 'MOZ_NO_REMOTE': '1', 15:27:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:06 INFO - 'NO_EM_RESTART': '1', 15:27:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:06 INFO - 'PWD': '/builds/slave/test', 15:27:06 INFO - 'SHELL': '/bin/bash', 15:27:06 INFO - 'SHLVL': '1', 15:27:06 INFO - 'TERM': 'linux', 15:27:06 INFO - 'TMOUT': '86400', 15:27:06 INFO - 'USER': 'cltbld', 15:27:06 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:06 INFO - '_': '/tools/buildbot/bin/python'} 15:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:27:06 INFO - Cleaning up... 15:27:06 INFO - Return code: 0 15:27:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:27:06 INFO - Using env: {'DISPLAY': ':0', 15:27:06 INFO - 'HOME': '/home/cltbld', 15:27:06 INFO - 'LANG': 'en_US.UTF-8', 15:27:06 INFO - 'LANGUAGE': 'en_US:en', 15:27:06 INFO - 'LOGNAME': 'cltbld', 15:27:06 INFO - 'MAIL': '/var/mail/cltbld', 15:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:06 INFO - 'MOZ_NO_REMOTE': '1', 15:27:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:06 INFO - 'NO_EM_RESTART': '1', 15:27:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:06 INFO - 'PWD': '/builds/slave/test', 15:27:06 INFO - 'SHELL': '/bin/bash', 15:27:06 INFO - 'SHLVL': '1', 15:27:06 INFO - 'TERM': 'linux', 15:27:06 INFO - 'TMOUT': '86400', 15:27:06 INFO - 'USER': 'cltbld', 15:27:06 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:06 INFO - '_': '/tools/buildbot/bin/python'} 15:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:27:06 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 15:27:06 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 15:27:06 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 15:27:06 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 15:27:06 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 15:27:06 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 15:27:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:27:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:27:08 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 15:27:08 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 15:27:08 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 15:27:08 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 15:27:08 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 15:27:08 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 15:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:27:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:09 INFO - Downloading docopt-0.6.1.tar.gz 15:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:27:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:27:09 INFO - Running setup.py install for blobuploader 15:27:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:27:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:27:09 INFO - Running setup.py install for requests 15:27:10 INFO - Running setup.py install for docopt 15:27:10 INFO - Successfully installed blobuploader requests docopt 15:27:10 INFO - Cleaning up... 15:27:10 INFO - Return code: 0 15:27:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:27:10 INFO - Using env: {'DISPLAY': ':0', 15:27:10 INFO - 'HOME': '/home/cltbld', 15:27:10 INFO - 'LANG': 'en_US.UTF-8', 15:27:10 INFO - 'LANGUAGE': 'en_US:en', 15:27:10 INFO - 'LOGNAME': 'cltbld', 15:27:10 INFO - 'MAIL': '/var/mail/cltbld', 15:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:10 INFO - 'MOZ_NO_REMOTE': '1', 15:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:10 INFO - 'NO_EM_RESTART': '1', 15:27:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:10 INFO - 'PWD': '/builds/slave/test', 15:27:10 INFO - 'SHELL': '/bin/bash', 15:27:10 INFO - 'SHLVL': '1', 15:27:10 INFO - 'TERM': 'linux', 15:27:10 INFO - 'TMOUT': '86400', 15:27:10 INFO - 'USER': 'cltbld', 15:27:10 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:10 INFO - '_': '/tools/buildbot/bin/python'} 15:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:27:10 INFO - Running setup.py (path:/tmp/pip-OXu_Ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:27:10 INFO - Running setup.py (path:/tmp/pip-lPcToJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:27:10 INFO - Running setup.py (path:/tmp/pip-tZ9LWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:27:10 INFO - Running setup.py (path:/tmp/pip-CaaOKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:27:10 INFO - Running setup.py (path:/tmp/pip-P3rkZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:27:10 INFO - Running setup.py (path:/tmp/pip-nc1PVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:27:10 INFO - Running setup.py (path:/tmp/pip-XsgVw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:27:10 INFO - Running setup.py (path:/tmp/pip-I9dF9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:27:11 INFO - Running setup.py (path:/tmp/pip-CUmi15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:27:11 INFO - Running setup.py (path:/tmp/pip-WNIF6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:27:11 INFO - Running setup.py (path:/tmp/pip-Evy8Mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:27:11 INFO - Running setup.py (path:/tmp/pip-H7ooKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:27:11 INFO - Running setup.py (path:/tmp/pip-ZJZzD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:27:11 INFO - Running setup.py (path:/tmp/pip-C3XQJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:11 INFO - Running setup.py (path:/tmp/pip-dELMau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:11 INFO - Running setup.py (path:/tmp/pip-vKnZT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:11 INFO - Running setup.py (path:/tmp/pip-hT9rtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:27:11 INFO - Running setup.py install for manifestparser 15:27:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:27:11 INFO - Running setup.py install for mozcrash 15:27:11 INFO - Running setup.py install for mozdebug 15:27:11 INFO - Running setup.py install for mozdevice 15:27:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Running setup.py install for mozfile 15:27:12 INFO - Running setup.py install for mozhttpd 15:27:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Running setup.py install for mozinfo 15:27:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Running setup.py install for mozInstall 15:27:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Running setup.py install for mozleak 15:27:12 INFO - Running setup.py install for mozlog 15:27:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Running setup.py install for moznetwork 15:27:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:27:12 INFO - Running setup.py install for mozprocess 15:27:12 INFO - Running setup.py install for mozprofile 15:27:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:27:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:27:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:27:13 INFO - Running setup.py install for mozrunner 15:27:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:27:13 INFO - Running setup.py install for mozscreenshot 15:27:13 INFO - Running setup.py install for moztest 15:27:13 INFO - Running setup.py install for mozversion 15:27:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:27:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:27:13 INFO - Cleaning up... 15:27:13 INFO - Return code: 0 15:27:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:27:13 INFO - Using env: {'DISPLAY': ':0', 15:27:13 INFO - 'HOME': '/home/cltbld', 15:27:13 INFO - 'LANG': 'en_US.UTF-8', 15:27:13 INFO - 'LANGUAGE': 'en_US:en', 15:27:13 INFO - 'LOGNAME': 'cltbld', 15:27:13 INFO - 'MAIL': '/var/mail/cltbld', 15:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:13 INFO - 'MOZ_NO_REMOTE': '1', 15:27:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:13 INFO - 'NO_EM_RESTART': '1', 15:27:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:13 INFO - 'PWD': '/builds/slave/test', 15:27:13 INFO - 'SHELL': '/bin/bash', 15:27:13 INFO - 'SHLVL': '1', 15:27:13 INFO - 'TERM': 'linux', 15:27:13 INFO - 'TMOUT': '86400', 15:27:13 INFO - 'USER': 'cltbld', 15:27:13 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:13 INFO - '_': '/tools/buildbot/bin/python'} 15:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:27:13 INFO - Running setup.py (path:/tmp/pip-6ohVJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:27:13 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)) 15:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:27:13 INFO - Running setup.py (path:/tmp/pip-oWbSEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 15:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:27:13 INFO - Running setup.py (path:/tmp/pip-RhahPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:27:13 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)) 15:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:27:13 INFO - Running setup.py (path:/tmp/pip-C8vzJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:27:14 INFO - Running setup.py (path:/tmp/pip-VHPBLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:27:14 INFO - Running setup.py (path:/tmp/pip-uE51Bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:27:14 INFO - Running setup.py (path:/tmp/pip-cpAole-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:27:14 INFO - Running setup.py (path:/tmp/pip-xoOkZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:27:14 INFO - Running setup.py (path:/tmp/pip-lclftg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:27:14 INFO - Running setup.py (path:/tmp/pip-xiNyfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:27:14 INFO - Running setup.py (path:/tmp/pip-PqiCgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:27:14 INFO - Running setup.py (path:/tmp/pip-6kPLET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:27:14 INFO - Running setup.py (path:/tmp/pip-9Yukjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:27:14 INFO - Running setup.py (path:/tmp/pip-C1rlQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:14 INFO - Running setup.py (path:/tmp/pip-Yj63dF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:14 INFO - Running setup.py (path:/tmp/pip-EE94NZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:14 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)) 15:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:14 INFO - Running setup.py (path:/tmp/pip-kutaoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:14 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)) 15:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:27:14 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)) 15:27:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:27:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:27:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:16 INFO - Downloading blessings-1.6.tar.gz 15:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:27:17 INFO - Installing collected packages: blessings 15:27:17 INFO - Running setup.py install for blessings 15:27:17 INFO - Successfully installed blessings 15:27:17 INFO - Cleaning up... 15:27:17 INFO - Return code: 0 15:27:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:27:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:17 INFO - Reading from file tmpfile_stdout 15:27:17 INFO - Current package versions: 15:27:17 INFO - argparse == 1.2.1 15:27:17 INFO - blessings == 1.6 15:27:17 INFO - blobuploader == 1.2.4 15:27:17 INFO - docopt == 0.6.1 15:27:17 INFO - functools32 == 3.2.3-2 15:27:17 INFO - jsonschema == 2.5.1 15:27:17 INFO - manifestparser == 1.1 15:27:17 INFO - mozInstall == 1.12 15:27:17 INFO - mozcrash == 1.0 15:27:17 INFO - mozdebug == 0.1 15:27:17 INFO - mozdevice == 0.49 15:27:17 INFO - mozfile == 1.2 15:27:17 INFO - mozhttpd == 0.7 15:27:17 INFO - mozinfo == 0.9 15:27:17 INFO - mozleak == 0.1 15:27:17 INFO - mozlog == 3.4 15:27:17 INFO - moznetwork == 0.27 15:27:17 INFO - mozprocess == 0.23 15:27:17 INFO - mozprofile == 0.28 15:27:17 INFO - mozrunner == 6.13 15:27:17 INFO - mozscreenshot == 0.1 15:27:17 INFO - mozsystemmonitor == 0.3 15:27:17 INFO - moztest == 0.7 15:27:17 INFO - mozversion == 1.4 15:27:17 INFO - psutil == 3.1.1 15:27:17 INFO - requests == 1.2.3 15:27:17 INFO - wsgiref == 0.1.2 15:27:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:27:17 INFO - Using env: {'DISPLAY': ':0', 15:27:17 INFO - 'HOME': '/home/cltbld', 15:27:17 INFO - 'LANG': 'en_US.UTF-8', 15:27:17 INFO - 'LANGUAGE': 'en_US:en', 15:27:17 INFO - 'LOGNAME': 'cltbld', 15:27:17 INFO - 'MAIL': '/var/mail/cltbld', 15:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:17 INFO - 'MOZ_NO_REMOTE': '1', 15:27:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:17 INFO - 'NO_EM_RESTART': '1', 15:27:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:17 INFO - 'PWD': '/builds/slave/test', 15:27:17 INFO - 'SHELL': '/bin/bash', 15:27:17 INFO - 'SHLVL': '1', 15:27:17 INFO - 'TERM': 'linux', 15:27:17 INFO - 'TMOUT': '86400', 15:27:17 INFO - 'USER': 'cltbld', 15:27:17 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:17 INFO - '_': '/tools/buildbot/bin/python'} 15:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:27:17 INFO - Cleaning up... 15:27:17 INFO - Return code: 0 15:27:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:27:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f62630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, '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': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', '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 15:27:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:27:17 INFO - Using env: {'DISPLAY': ':0', 15:27:17 INFO - 'HOME': '/home/cltbld', 15:27:17 INFO - 'LANG': 'en_US.UTF-8', 15:27:17 INFO - 'LANGUAGE': 'en_US:en', 15:27:17 INFO - 'LOGNAME': 'cltbld', 15:27:17 INFO - 'MAIL': '/var/mail/cltbld', 15:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:17 INFO - 'MOZ_NO_REMOTE': '1', 15:27:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:17 INFO - 'NO_EM_RESTART': '1', 15:27:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:17 INFO - 'PWD': '/builds/slave/test', 15:27:17 INFO - 'SHELL': '/bin/bash', 15:27:17 INFO - 'SHLVL': '1', 15:27:17 INFO - 'TERM': 'linux', 15:27:17 INFO - 'TMOUT': '86400', 15:27:17 INFO - 'USER': 'cltbld', 15:27:17 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:17 INFO - '_': '/tools/buildbot/bin/python'} 15:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:27:17 INFO - Cleaning up... 15:27:17 INFO - Return code: 0 15:27:17 INFO - Running post-action listener: _resource_record_post_action 15:27:17 INFO - Running post-action listener: _start_resource_monitoring 15:27:17 INFO - Starting resource monitoring. 15:27:17 INFO - [mozharness: 2016-12-23 23:27:17.908649Z] Finished create-virtualenv step (success) 15:27:17 INFO - [mozharness: 2016-12-23 23:27:17.909146Z] Running install step. 15:27:17 INFO - Running pre-action listener: _resource_record_pre_action 15:27:17 INFO - Running main action method: install 15:27:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:18 INFO - Reading from file tmpfile_stdout 15:27:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:27:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:27:18 INFO - Reading from file tmpfile_stdout 15:27:18 INFO - Output received: 15:27:18 INFO - Usage: mozinstall [options] installer 15:27:18 INFO - Options: 15:27:18 INFO - -h, --help show this help message and exit 15:27:18 INFO - -d DEST, --destination=DEST 15:27:18 INFO - Directory to install application into. [default: 15:27:18 INFO - "/builds/slave/test"] 15:27:18 INFO - --app=APP Application being installed. [default: firefox] 15:27:18 INFO - mkdir: /builds/slave/test/build/application 15:27:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:27:34 INFO - Reading from file tmpfile_stdout 15:27:34 INFO - Output received: 15:27:34 INFO - /builds/slave/test/build/application/firefox/firefox 15:27:34 INFO - Running post-action listener: _resource_record_post_action 15:27:34 INFO - [mozharness: 2016-12-23 23:27:34.286345Z] Finished install step (success) 15:27:34 INFO - [mozharness: 2016-12-23 23:27:34.286588Z] Running run-tests step. 15:27:34 INFO - Running pre-action listener: _resource_record_pre_action 15:27:34 INFO - Running main action method: run_tests 15:27:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:27:34 INFO - Python 2.7.3 15:27:34 INFO - Return code: 0 15:27:34 INFO - grabbing minidump binary from tooltool 15:27:34 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:27:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb850>, '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 0x1f606d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:27:34 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 15:27:34 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 15:27:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:27:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:27:34 INFO - Return code: 0 15:27:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:27:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:27:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:27:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:27:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:27:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:27:34 INFO - Using env: {'DISPLAY': ':0', 15:27:34 INFO - 'HOME': '/home/cltbld', 15:27:34 INFO - 'LANG': 'en_US.UTF-8', 15:27:34 INFO - 'LANGUAGE': 'en_US:en', 15:27:34 INFO - 'LOGNAME': 'cltbld', 15:27:34 INFO - 'MAIL': '/var/mail/cltbld', 15:27:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:27:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:34 INFO - 'MOZ_NO_REMOTE': '1', 15:27:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:27:34 INFO - 'NO_EM_RESTART': '1', 15:27:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:34 INFO - 'PWD': '/builds/slave/test', 15:27:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:27:34 INFO - 'SHELL': '/bin/bash', 15:27:34 INFO - 'SHLVL': '1', 15:27:34 INFO - 'TERM': 'linux', 15:27:34 INFO - 'TMOUT': '86400', 15:27:34 INFO - 'USER': 'cltbld', 15:27:34 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826', 15:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:34 INFO - '_': '/tools/buildbot/bin/python'} 15:27:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:27:34 INFO - mozversion application_buildid: 20161223143436 15:27:34 INFO - mozversion application_changeset: dd35d18c8e14751cca293db90ea2205e0a0871c7 15:27:34 INFO - mozversion application_display_name: Nightly 15:27:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:27:34 INFO - mozversion application_name: Firefox 15:27:34 INFO - mozversion application_remotingname: firefox 15:27:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:27:34 INFO - mozversion application_vendor: Mozilla 15:27:34 INFO - mozversion application_version: 53.0a1 15:27:34 INFO - mozversion platform_buildid: 20161223143436 15:27:34 INFO - mozversion platform_changeset: dd35d18c8e14751cca293db90ea2205e0a0871c7 15:27:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:27:34 INFO - mozversion platform_version: 53.0a1 15:27:34 INFO - using testdate: 1482535654 15:27:34 INFO - actual date: 1482535654 15:27:34 INFO - starting webserver on 'localhost:56551' 15:27:34 INFO - SUITE-START | Running 2 tests 15:27:34 INFO - TEST-START | dromaeo_css 15:27:34 INFO - operating with platform_type : linux_ 15:27:34 INFO - Initialising browser for dromaeo_css test... 15:27:34 INFO - TEST-INFO | started process 9064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZJMwLe/profile http://localhost:56551/getInfo.html) 15:27:35 INFO - PROCESS | 9064 | 1482535655054 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:27:37 INFO - PROCESS | 9064 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:27:37 INFO - PROCESS | 9064 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:27:38 INFO - PROCESS | 9064 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:27:38 INFO - PROCESS | 9064 | __metrics Screen width/height:1600/1200 15:27:38 INFO - PROCESS | 9064 | colorDepth:24 15:27:38 INFO - PROCESS | 9064 | Browser inner width/height: 1024/697 15:27:38 INFO - PROCESS | 9064 | __metrics 15:27:39 INFO - PROCESS | 9064 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:27:39 INFO - PROCESS | 9064 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:27:39 INFO - PROCESS | 9064 | console.error: PushService: 15:27:40 INFO - PROCESS | 9064 | stateChangeProcessEnqueue: Error transitioning state 15:27:40 INFO - PROCESS | 9064 | UnknownError 15:27:40 INFO - TEST-INFO | 9064: exit 0 15:27:40 INFO - Browser initialized. 15:27:40 INFO - Running cycle 1/1 for dromaeo_css test... 15:27:40 INFO - TEST-INFO | started process 9147 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZJMwLe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:27:52 INFO - PROCESS | 9147 | RSS: Main: 152117248 15:27:52 INFO - PROCESS | 9147 | 15:28:44 INFO - PROCESS | 9147 | 1482535724364 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] 15:30:57 INFO - PROCESS | 9147 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56551/tests/dromaeo/cssquery-ext.html) 15:30:58 INFO - PROCESS | 9147 | RSS: Main: 266752000 15:30:58 INFO - PROCESS | 9147 | 15:32:41 INFO - PROCESS | 9147 | ************************* 15:32:41 INFO - PROCESS | 9147 | A coding exception was thrown and uncaught in a Task. 15:32:41 INFO - PROCESS | 9147 | 15:32:41 INFO - PROCESS | 9147 | Full message: ReferenceError: fetch is not defined 15:32:41 INFO - PROCESS | 9147 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 15:32:41 INFO - PROCESS | 9147 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 15:32:41 INFO - PROCESS | 9147 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 15:32:41 INFO - PROCESS | 9147 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:32:41 INFO - PROCESS | 9147 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:32:41 INFO - PROCESS | 9147 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:32:41 INFO - PROCESS | 9147 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 15:32:41 INFO - PROCESS | 9147 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:32:41 INFO - PROCESS | 9147 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:32:41 INFO - PROCESS | 9147 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:32:41 INFO - PROCESS | 9147 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 15:32:41 INFO - PROCESS | 9147 | 15:32:41 INFO - PROCESS | 9147 | ************************* 15:34:01 INFO - PROCESS | 9147 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-ext.html (next: http://localhost:56551/tests/dromaeo/cssquery-jquery.html) 15:34:01 INFO - PROCESS | 9147 | RSS: Main: 245002240 15:34:01 INFO - PROCESS | 9147 | 15:37:04 INFO - PROCESS | 9147 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56551/tests/dromaeo/cssquery-mootools.html) 15:37:04 INFO - PROCESS | 9147 | RSS: Main: 208044032 15:37:04 INFO - PROCESS | 9147 | 15:40:07 INFO - PROCESS | 9147 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56551/tests/dromaeo/cssquery-prototype.html) 15:40:07 INFO - PROCESS | 9147 | RSS: Main: 185810944 15:40:07 INFO - PROCESS | 9147 | 15:43:10 INFO - PROCESS | 9147 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56551/tests/dromaeo/cssquery-yui.html) 15:43:11 INFO - PROCESS | 9147 | RSS: Main: 187650048 15:43:11 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-yui.html (next: http://localhost:56551/tests/dromaeo/cssquery-dojo.html) 15:46:14 INFO - PROCESS | 9147 | RSS: Main: 283938816 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | __start_tp_report 15:46:14 INFO - PROCESS | 9147 | _x_x_mozilla_page_load 15:46:14 INFO - PROCESS | 9147 | _x_x_mozilla_page_load_details 15:46:14 INFO - PROCESS | 9147 | |i|pagename|runs| 15:46:14 INFO - PROCESS | 9147 | |0;dojo.html;2557.70586382566;2572.022631400068;2586.4697736963917;2601.414681696617;2603.6224747411625;4501.324801279809;4565.65757568182;4638.118757436089;4689.976550117249;4692.53074692531;4674;4728.172569800288;4756.690815097017;4786.4974177711365;4838.951610483894;4304.741715497064;4334.696571240014;4351.325544540601;4367.934480982788;4414.514403415622;4005.8998525036964;4365.497967733703;4452.97773511132;4503.1894259033525;4530.252508336121;4246.065865509583;4421.977890110529;4433.911321773547;4445.177642136199;4522.072975040109;4285.61429471349;4298.4197133387215;4340.392345071693;4352.499462561815;4369.366441865944;4342.761148136851;4343.9348409773875;4355.6733245006435;4381.561843815625;4400.801963911632;4687.1328804171335;4691.15559199344;4713.575778179946;4720.315554244616;4724.385829842133;4796.784144713496;4839.661223714342;5004.174311238678;5010.849674509771;5066.594672426197;5102.668326558799;5103.438621751604;5109.080365534202;5134.204198349262;5142.023015627019;4770.379850619397;4776.498467660915;4776.546228108394;4777.355057067321;4819.469858315582;5015.598752099823;5070.645054846199;5092.898142037213;5192.4547922468355;5263.421023687391;4957.479464656231;4972.701637901738;4974.850754477371;5019.0965626187635;5030.144875371196;4834.613230941516;4874.317595536628;5045.697258164522;5053.444121146671;5106.872328191751;4891.095147397743;4904.215325547895;4955.72743499111;4966.255061740696;5069.898602027939;4807.134715751198;4845.9757701211265;4952.529509696573;4989.7006179628615;5010.574101201439;4170.895727606773;4175.83296668136;4194.958050419456;4215.578442155821;4253.851115210953;3687.7049836013052;3694.9445758313645;3696.353138200804;3717.275131349361;3718.9442158367647;4215.451991241119;4356.520782713899;4356.586124318184;4361.389405483234;4399.692021558524;4877.902441951142;4948.529889660476;4974.477679843637;5038.622103342264;5042.672226305279;4366.672499562482;4367.126574685076;4368.27923392643;4392.956070439319;4409.603135717801;4534.523874993144;4538.500764915857;4539.251023581072;4540.704854184468;4580.129775342674;4587.334836448763;4625.7918393673035;4695.906081878412;4716.10394025141;4720.362751028567;5085.567726743268;5087.898242035212;5087.898242035212;5109.821156259513;5121.974390128025;4732.100900828831;4742.928856067231;4746.382970213988;4756.928646070242;4782;4843.2735089736825;4858.052679727561;4960.181570041047;4988.997779687415;5054.19132938728;5051.292819005415;5103.464136265638;5105;5142.871428214325;5153.7165455900285;4713.363695901009;4918.409790825124;4956.405231372259;4958.752062396794;5010.198368261061;4725.385699859134;4745.83389581363;4874.317595536699;4919.0407870464915;4928.852134435972;4847.563719265283;4866.1484240257105;4868.634852386014;4908.263760435963;4937.308776771183;4964.255361695775;4968.378952630921;4977.352944117241;4985.326980857682;4986.775595098156;4682.274247491644;4687.273472611751;4688.460827004938;4782.450018247807;4891.437484689306 15:46:14 INFO - PROCESS | 9147 | |1;ext.html;516.6719133350322;525.2724975908367;534.0307342173952;541.4558368839316;541.8482824809053;28575.57136642952;38354.42468362973;40334;40356;44057.55942440574;39970;40398;40487;40557.391639125366;40592.59407405925;7384.815379615499;7571.734989275376;7729.497582657137;7737.032870891138;7739.806504837367;7356.521826081287;9686.418814871131;9861.654842080528;9914.058164474363;10270.7432314192;9969.451680157588;10029.1475224606;10037.849432258485;10040.799184016316;10055.145312648434;9838.950805245964;9902.80194396108;9913.75215619608;9914.107730304308;9924.305298629099;9687.951560242154;9746.853797192976;9747.512624368754;9748.463834489101;9749.171320437772;38228.808855955824;38366.80816595899;38440.80779596112;38441.61558384408;38609.42085868714;3024.1078881729723;3026.8940587079346;3138.8430578470984;3264.722498587623;3328.417526932777;27837.721622783716;27975.860120699468;28116.718832811614;28453.288667783265;28631.856840715664;3207.8235697036653;3254.886078987224;3287.309664970359;3293.143782616513;3317.7345812334956;193661;249287;250575;250644;250680;49800.25299620508;49880.75059624751;50268.49731502711;50325.96088469407;50352.74823625859;51500.742496288025;51540;51545;51605.741971289906;51658.48341516612;14045.859541404456;14537.41850325975;14549;14559.708805823824;14568.198749068903;10082.899171008343;10358.171346292278;10795.676129716121;11080.722981925355;11166.944165279285;15271.923640381949;15875.745472178176;15913.124778137082;15947.730715203355;15964.361425542847;5998.940010599838;6213.440790328892;6333.68331583419;6366.066298618515;6488.572514046902;809.522381794744;842.4439869685978;863.935204859638;877.2499512916493;1009.4195837393528;27479.450410991667;27786.583201251997;27868.860655696593;28262;28277.57583636247;960.6349587156975;1003.6587560229557;1042.9582816687248;1053.3711364349895;1084.3493903657743;14187.432502699776;14718.33767480466;14809.259537023107;14972.326245318987;15393.923030384776;362201;364341;364437;364857;366107;48745.756271218415;49044.26433603499;49231;49312.260316095286;49366.50633493691;1118.6588090632436;1196.3838623108932;1207.1893504385018;1208.86098098718;1228.3612521488953;24956.37609059788;26051.348716282246;26767.464650707265;27091;27280.181594552196;41411;41645.58354416538;41749.58250417457;41894.1621167581;41957.37063944104;304161;304873;305931;306366;306654;25092.74907250977;25459.618105728798;25466;25501;25507.23478295654;40525.79737101414;40692.1861562773;40958;41265;41507.79246103871;34352;34443.311133777686;34754;35199.82400087983;35352.823235883654;34745.826270868485;34841.30317393689;35150.64849351474;35284.82357588298;35342.46986295156;31868.203294916886;33743.4938475928;33919.321613568085;34230.31539369149;34610.82694586511;27247.86376068107;29090.854545727136;29387.55918661179;29673.85163074171;29872.402551948406;22571.774282256967;22725.545489090455;23008.654870177295;23094.307170784232;23299 15:46:14 INFO - PROCESS | 9147 | |2;jquery.html;360.30641016044143;372.82104589796916;378.4588039104081;381.1975790960029;396.7778044295195;12790;15574.22128893555;15819;17110;17273.91363043183;14846.406143754237;15138;17015.82984170158;17090.82909170911;17302;5028.6731362461505;5210.843674689746;5237.319148510688;5410.567154627621;5444.50999410053;3528.964710352889;5433.26650902129;5480.451954804507;5483.561315094803;5484.040292948738;3640.290143422034;3647.7993710345922;4645.024544845586;5848.356680765112;5928.3775203603645;5883.941160588382;5894.115882617599;5898.203742494751;5900.380460051697;5905.055191169392;5057.822976195852;5136.280920671109;5163.457836927106;5165.948340516585;5170.870728231786;12434.751304973941;12503.249805011637;13435.596932091956;19605.21579136833;19973.30093446737;1815.863810214226;1825.1330617956496;1979.2478858033999;1986.2849374225268;2444.4133407982135;9034.277257819424;9361.4383137012;9397.906020939772;11757.235779674294;13174.538891138764;1729.7578339032546;1760.155125539748;1786.3033416967394;1789.7494350790769;1796.8562514998769;61455.69272153655;62364.68817655928;62986.685066574835;63649.68175159094;64790.352096478906;9144.679936202201;9231.892172939157;9346.719598412059;9574.712758617252;9700.369475984038;13565.25391103479;13579.185248885098;13596.660083497794;13601.183928964294;13607.31963401826;6887.20797108339;6977.9409927801935;7101.857962840818;7105.502614816914;7141.9642901785155;6612.834679132964;6621.834454138685;6662.333766623299;6838.384545390939;6857.725690972405;3794.4308353746605;3810.676092532166;3867.6325749053794;3935.1145992151537;3953.248882712333;1742.3727458115068;1789.364775504677;1798.2087881332172;1808.1320965936425;1838.7425760393287;708.3872450330427;778.2373274191211;802.0736049862418;813.7233340664203;841.486693117198;14928.850711492963;15153.242337883063;15595.922020390053;15630.218489075502;16321.428749993693;757.4167890724111;773.97678069658;802.1617409806765;832.2800777327568;834.8580741273877;8222.095569487352;8810.207081362709;8932.776680582907;8947.289682341716;8969.416987895765;327028;329844;330053;331385;334742;65353.34646653568;65950.34049659442;66771.33228667651;67255;68286.65856670684;900.4417261297906;914.4056363363734;918.1002617435084;923.7690577355661;932.911373419524;13637.181769093886;13647.590572282847;13703.520376786764;13754.243516606683;14226.644333891321;33191.336173275915;33443.1639209012;33617.15957101092;34363.969080927614;34838;43692.781536092116;45286.88422696223;46126.53873461222;46646.53353466422;47353;7310.817229569303;7312.19565847767;7652.04349456318;7717.691292348468;7835.020622422197;10690.572377104829;10936.617218397318;10976.176786741185;11075.390853503135;11116.944415278196;9810.411375317504;9852;9873.555689994111;10074.496275186359;10414.687559373231;9864.66045299566;10002.44986525748;10221.182305415401;10399;10437.478126093512;9293.303002274992;9885.258605604753;9946.154576861049;10335.638252661121;10442.791144177227;7339.77980660581;7544.566532358835;7604.581748003913;7834.0599128105;7867.409944254318;8765.60554775024;8772.824543508967;9211.124943130391;9469.857952130586;9707.223422126071 15:46:14 INFO - PROCESS | 9147 | |3;mootools.html;1357.9999999999998;1362.0601784768505;1472.9337179826907;1661.1694152923537;1702.1744453939837;7116.85766284674;7568.16750157483;7569.0160279163765;7571.772846814604;7581.052368453943;6681.331866813316;6849.554778939366;7094.645267736619;7307.050083489139;7415.740449084283;2117.9682304765442;2148.387709502792;2158.169104894619;2159.5680863827297;2162.3945295317335;2196.527746534495;2215.1693115081844;2264.501809601893;2268.0814270220503;2269.341890851651;2153.1064608187585;2156.644153714635;2173.66308222225;2174.2607513445423;2184.388371256051;2106.599746048246;2123.214410668055;2135.231316725977;2140.1118535807777;2144.1637761273114;2141.218455263833;2182.4871155278497;2183.9781602183934;2184.56308738253;2186.497105665706;7471.813204669872;7501.624918754041;7619.542827430372;7623.466357354987;7635.6563954621615;4441.311596702516;4583.679142460029;4586.312053192048;4588.885277868047;4589.632829373642;2787.1638508447586;2935.9853200734074;2987.865546050433;2988.491956367401;3003.6695963444004;826.3595713322163;847.6694089305176;866.2117473099449;868.5787393114334;892.2326798952895;9985.251106166994;10057.698269052013;10058.698239052841;10058.9497052515;10066.496675166212;4084.632383085477;4244.342126970325;4282.507511636201;4310.633596144363;4312.54718254585;8598.398112132216;8599.26906212979;8605.784855378666;8608.698695545625;8614.05245423003;4714.339992401066;4714.38712967312;4717.386739723814;4718.127146477913;4718.433787945469;3748.1379282765115;3785.1861849701895;3897.7661340319105;4255.744655320691;4262.808173632194;3560.7151427885706;3623.8912832614537;3651.7991510467177;3717.070732316921;3740.663340299386;1214.6416807041944;1217.2635555488978;1218.366449446283;1226.8650448450662;1235.1045492018216;805.1465446626478;814.5560669435172;818.1654712193648;823.1274848660439;836.9707060252862;4765.404324459442;4844;4886.755662216946;4927.482614325516;5006.048850718352;392.7741548609561;394.10734685936376;394.1860058978201;397.75339289640607;404.47822309220777;3301.323228738151;3335.31626016666;3347.8828241011934;3359.328134373135;3482.791032538056;8691.478511289217;9091.409058411315;9102.408343457653;9113;9116.4074335168;9087.727368178965;9102;9103.49930753815;9103.544822758835;9104.996900681972;7144.464165187632;7170.426365890717;7203;7220.566765994057;7225.385842203366;4782.33047373361;4791.760411979318;4861.392325959255;4889.535494128052;5065.544101030925;8349.332053435832;8412.327013839;8421.07368189511;8453.746387608382;8457.534835584102;8392.538410387488;8532.914670853212;8544.530050847014;8591.44155629882;8703.956480217812;5968.97015514937;6089.117078023751;6146.231721034871;6635.5355125142705;6718.563293385914;6630.403263706289;7130.786076417716;7189.892151617625;7355.926440735524;7365.447591430557;6460.224773027267;6520.804375868732;6538.4115429609665;6558.508111891531;6727.293634168342;6240.188775459162;6308.779192728416;6376;6437.066625339393;6482.124913136666;5772;5874.177615133799;5879.853003674772;5883.676397798162;5888.705564721661;4298.570142985676;4302.612764851053;4336.674749393745;4355.368471571668;4457;4120.7733574653685;4215.3044747616295;4308.1599088178755;4390.604845563915;4425.380446737524 15:46:14 INFO - PROCESS | 9147 | |4;prototype.html;338.6072156298693;340.53686985699443;343.7971596757913;348.37988380682395;349.90552550811265;4809.831655892044;4866.829660961867;4869.293952376912;4884.731339776311;4898.951010489894;4758.310045043467;4791.832285869995;4801.663883528155;4802.807887684488;4808.447028591713;3711.2763011212824;3815.198808250257;3832.930217682448;3837.3860182370686;3859.1702783901455;3857.8263978121054;3859.7684138951613;3868.40039793831;3876.6317199865966;3895.396213586884;3816.637419445148;3839.6561203578804;3844.2503711776076;3860.5560360558475;3866.845326186949;3685.391910334805;3755.586885442599;3791.279656865187;3804.771713697187;3846.7499612525235;3408.829558522064;3408.960267118528;3415.2486452980306;3420.846061927219;3422.8801991930413;4654.4647365553055;4656.254999200111;4659.138059459011;4667.953320466786;4668.5564871337165;4183.226103170893;4356.542563030899;4361.716488428242;4370.366296886966;4380.912381752347;4938.2839488274;5019.891645508858;5023.2214006829;5053.5957123430035;5054.595632349403;3754.1177823211524;3765.3975363941913;3773.52832395366;3775.528058992626;3782.281366540376;5169.224616307584;5257.553107985826;5295;5314.601404894648;5324.5740340772645;3815.618438156162;3847.3844184930276;3855.267499175148;3870;3872.7095467840027;3922.6077392260545;3923.195744872294;3926.469926559878;3926.9410958835642;3932.6264004919485;4369.453818272716;4385.802638881258;4387.363832244307;4387.55210780448;4389.802458889294;4923.187674033816;5057.468965758616;5064.442911279756;5097.923531147036;5100.132977393852;1300.3758196065942;1305.5573591181796;1308.0189857606795;1309.6725818545365;1312.9146605470614;907.1563445995288;944.8063147054837;955.8088382323563;960.5533426956359;963.2438535749403;4310.7629080400875;4337.804798784062;4420.889477763081;4464.888377790581;4479.529649386833;5174.6804564836475;5177.741112944338;5182;5185.9740701297005;5217.643412712722;4434.423524941737;4459.531749166355;4459.9930031485965;4470.530594287618;4476.798544065525;2580.87095645217;2656.375751698349;2670.492606404777;2680.34331588762;2685.194441667492;4726.196546587088;4728.574428301469;4730.597899178609;4735;4747.371312969895;4971.975140124277;5127.205478082716;5179.326687530597;5203.427622961472;5231.895362092737;4668.579827815445;4693.436787585375;4706.647001474903;4715.363425937455;4754.690945088558;5005.09704371471;5007.549320561021;5028.8433660257615;5033.672811267256;5065.417476990193;4746.7863946121815;4909.288153217692;4937.35814344133;4972.403311602631;4975.328330675313;4682.063587282489;4728.267118596623;4746.2168742157155;4746.905061898812;4749.810007599657;3769.9434508481845;3773.358529050013;3774.905627359338;3778.603246659171;3791.099808646333;5084.593232541314;5094.490550944876;5094.745262736774;5099.719515426687;5120.25756265332;4652.906941861076;4880.536349046835;4893.681910675795;4905.950940490549;4936.901261974812;4893.042782886873;4907.803687852446;4921.803127874988;4923.212286034361;4958.404991401055;4850.345203397496;4874.8537543873745;4876.29293752411;4883.118959813727;4887.218045112765;4626.560476754703;4874.5856602189215;4893.388326459192;4938.38270216223;4971.552560269593;4830.565249127595;4938.75306234694;4942.8764280893265;4961.305417241661;4962 15:46:14 INFO - PROCESS | 9147 | |5;yui.html;634.3561285295425;639.686553588741;672.539310572258;725.0067407651518;733.6625152429883;12424.440900159492;13692.58922232332;13721.93139034306;13756.656083597914;13765.380557874894;12761.170523915938;13021.54424595137;13192.472301107944;13194.802077968814;13198.670033249173;812.4800127918152;821.1255013410728;824.3116997307255;825.2407784837964;826.9421140520166;819.6434550970317;822.9876551851727;823.1850468036661;823.5758584329076;823.9340852731767;859.7678626770768;859.793649524116;863.0290922711949;863.6588547523725;864.1531299326664;853.6670698427556;855.0765173612515;856.2251162697737;856.7686724584358;857.6569372251087;625.799744081894;630.2027934662656;631.671530803984;631.7567736421465;632.3486808587163;11300.378479183642;11570.826437603442;11603.6518904433;11610.941945290304;11649.941750291278;9956.402615843;10170.898291017069;10216.335938163995;10407.271490995634;10427.947860260727;9375.249980001583;9375.437473751528;9382.483963382012;9547.28395370343;9621.663241786504;3187.6979281452427;3227.870245414086;3260.7446133238873;3261.037993791837;3303.9504407433906;56798.7160064197;57811;58676;58817.11774323346;58849;8118.2693557580105;8355.582220888931;8394.328453723687;8488.363372747068;8514.403991720645;17277.65444691124;17328.913355433142;17497.47507574775;17515.73726394105;17532.737008944878;6301.6534090625455;6325.9051114233325;6417.39034791694;6524.869502610016;6550.312217217219;3426.2633533790354;3428.0915557377316;3439.810810405452;3480.7911525308564;3509.8771542995873;2920.2991282092144;2995.5806187133817;3012.5932999045285;3021.9546706799415;3024.1985873743474;448.5536890793681;467.3130498167688;471.4907899468566;473.1318031412397;478.74626447982627;547.4853637580741;570.1305509098657;576.224977405389;596.8448203467129;609.4941198804981;7159.964200178966;7239.276072392719;7424.575424575424;7595.164531901486;7694.884576731353;460.64300167369885;491.67795094213346;521.42382667153;556.6073644196401;580.4398755201252;3537.575490941052;3794.4877441545036;3896.279188350164;3918.118423354779;4031.717779755389;52030.73984630129;52249;65598.34401655922;68618.56082381523;70233.64883175654;49498;52368.73815630897;58855.23434296978;59797;60738;46689.53310466988;48534.27198592024;49877.25184122241;53537.73231133819;57762;8098.797530061795;8110.83778324442;8516.531590762568;8754.562271886507;8834.116588341114;33425;34175.48736769;35174;36115.277694446486;37830.81084594559;41778.95552611209;45635.08729825451;46522.76738616285;46678.766606166755;48678.75660621674;11392.373419462008;11420.657380278604;11464.369459679465;11616.128790340927;11828.32677920825;12522.37388130608;12661.556845510362;12743.617691469271;12967.610971670865;13113.868861311264;12004.639860804189;12021.81967270509;12130.878691213327;12203.877961220274;12229.266244025386;11890.464929078385;11911.880881191077;12287.508499659914;12361;12766.425510851848;11302.604408845651;11485.138614603771;11600.18798684084;11747.530098796295;11767;9958.601655933971;10051.547680354548;10061.547230374505;10337.534810933677;10524;10023.298369114093;10189.411512348888;10251;10376.792464150825;10377.9481102594 15:46:14 INFO - PROCESS | 9147 | __end_tp_report 15:46:14 INFO - PROCESS | 9147 | __start_cc_report 15:46:14 INFO - PROCESS | 9147 | _x_x_mozilla_cycle_collect,1233 15:46:14 INFO - PROCESS | 9147 | __end_cc_report 15:46:14 INFO - PROCESS | 9147 | __startTimestamp1482536774464__endTimestamp 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | ------- Summary: start ------- 15:46:14 INFO - PROCESS | 9147 | Number of tests: 6 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | [#0] dojo.html Cycles:165 Average:4648.58 Median:4756.93 stddev:492.27 (10.3%) stddev-sans-first:465.66 15:46:14 INFO - PROCESS | 9147 | Values: 2557.7 2572.0 2586.5 2601.4 2603.6 4501.3 4565.7 4638.1 4690.0 4692.5 4674.0 4728.2 4756.7 4786.5 4839.0 4304.7 4334.7 4351.3 4367.9 4414.5 4005.9 4365.5 4453.0 4503.2 4530.3 4246.1 4422.0 4433.9 4445.2 4522.1 4285.6 4298.4 4340.4 4352.5 4369.4 4342.8 4343.9 4355.7 4381.6 4400.8 4687.1 4691.2 4713.6 4720.3 4724.4 4796.8 4839.7 5004.2 5010.8 5066.6 5102.7 5103.4 5109.1 5134.2 5142.0 4770.4 4776.5 4776.5 4777.4 4819.5 5015.6 5070.6 5092.9 5192.5 5263.4 4957.5 4972.7 4974.9 5019.1 5030.1 4834.6 4874.3 5045.7 5053.4 5106.9 4891.1 4904.2 4955.7 4966.3 5069.9 4807.1 4846.0 4952.5 4989.7 5010.6 4170.9 4175.8 4195.0 4215.6 4253.9 3687.7 3694.9 3696.4 3717.3 3718.9 4215.5 4356.5 4356.6 4361.4 4399.7 4877.9 4948.5 4974.5 5038.6 5042.7 4366.7 4367.1 4368.3 4393.0 4409.6 4534.5 4538.5 4539.3 4540.7 4580.1 4587.3 4625.8 4695.9 4716.1 4720.4 5085.6 5087.9 5087.9 5109.8 5122.0 4732.1 4742.9 4746.4 4756.9 4782.0 4843.3 4858.1 4960.2 4989.0 5054.2 5051.3 5103.5 5105.0 5142.9 5153.7 4713.4 4918.4 4956.4 4958.8 5010.2 4725.4 4745.8 4874.3 4919.0 4928.9 4847.6 4866.1 4868.6 4908.3 4937.3 4964.3 4968.4 4977.4 4985.3 4986.8 4682.3 4687.3 4688.5 4782.5 4891.4 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | [#1] ext.html Cycles:180 Average:45611.51 Median:34397.66 stddev:80791.71 (234.9%) stddev-sans-first:80947.41 15:46:14 INFO - PROCESS | 9147 | Values: 516.7 525.3 534.0 541.5 541.8 28575.6 38354.4 40334.0 40356.0 44057.6 39970.0 40398.0 40487.0 40557.4 40592.6 7384.8 7571.7 7729.5 7737.0 7739.8 7356.5 9686.4 9861.7 9914.1 10270.7 9969.5 10029.1 10037.8 10040.8 10055.1 9839.0 9902.8 9913.8 9914.1 9924.3 9688.0 9746.9 9747.5 9748.5 9749.2 38228.8 38366.8 38440.8 38441.6 38609.4 3024.1 3026.9 3138.8 3264.7 3328.4 27837.7 27975.9 28116.7 28453.3 28631.9 3207.8 3254.9 3287.3 3293.1 3317.7 193661.0 249287.0 250575.0 250644.0 250680.0 49800.3 49880.8 50268.5 50326.0 50352.7 51500.7 51540.0 51545.0 51605.7 51658.5 14045.9 14537.4 14549.0 14559.7 14568.2 10082.9 10358.2 10795.7 11080.7 11166.9 15271.9 15875.7 15913.1 15947.7 15964.4 5998.9 6213.4 6333.7 6366.1 6488.6 809.5 842.4 863.9 877.2 1009.4 27479.5 27786.6 27868.9 28262.0 28277.6 960.6 1003.7 1043.0 1053.4 1084.3 14187.4 14718.3 14809.3 14972.3 15393.9 362201.0 364341.0 364437.0 364857.0 366107.0 48745.8 49044.3 49231.0 49312.3 49366.5 1118.7 1196.4 1207.2 1208.9 1228.4 24956.4 26051.3 26767.5 27091.0 27280.2 41411.0 41645.6 41749.6 41894.2 41957.4 304161.0 304873.0 305931.0 306366.0 306654.0 25092.7 25459.6 25466.0 25501.0 25507.2 40525.8 40692.2 40958.0 41265.0 41507.8 34352.0 34443.3 34754.0 35199.8 35352.8 34745.8 34841.3 35150.6 35284.8 35342.5 31868.2 33743.5 33919.3 34230.3 34610.8 27247.9 29090.9 29387.6 29673.9 29872.4 22571.8 22725.5 23008.7 23094.3 23299.0 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | [#2] jquery.html Cycles:180 Average:21584.70 Median:5097.05 stddev:54610.96 (1071.4%) stddev-sans-first:54740.91 15:46:14 INFO - PROCESS | 9147 | Values: 360.3 372.8 378.5 381.2 396.8 12790.0 15574.2 15819.0 17110.0 17273.9 14846.4 15138.0 17015.8 17090.8 17302.0 5028.7 5210.8 5237.3 5410.6 5444.5 3529.0 5433.3 5480.5 5483.6 5484.0 3640.3 3647.8 4645.0 5848.4 5928.4 5883.9 5894.1 5898.2 5900.4 5905.1 5057.8 5136.3 5163.5 5165.9 5170.9 12434.8 12503.2 13435.6 19605.2 19973.3 1815.9 1825.1 1979.2 1986.3 2444.4 9034.3 9361.4 9397.9 11757.2 13174.5 1729.8 1760.2 1786.3 1789.7 1796.9 61455.7 62364.7 62986.7 63649.7 64790.4 9144.7 9231.9 9346.7 9574.7 9700.4 13565.3 13579.2 13596.7 13601.2 13607.3 6887.2 6977.9 7101.9 7105.5 7142.0 6612.8 6621.8 6662.3 6838.4 6857.7 3794.4 3810.7 3867.6 3935.1 3953.2 1742.4 1789.4 1798.2 1808.1 1838.7 708.4 778.2 802.1 813.7 841.5 14928.9 15153.2 15595.9 15630.2 16321.4 757.4 774.0 802.2 832.3 834.9 8222.1 8810.2 8932.8 8947.3 8969.4 327028.0 329844.0 330053.0 331385.0 334742.0 65353.3 65950.3 66771.3 67255.0 68286.7 900.4 914.4 918.1 923.8 932.9 13637.2 13647.6 13703.5 13754.2 14226.6 33191.3 33443.2 33617.2 34364.0 34838.0 43692.8 45286.9 46126.5 46646.5 47353.0 7310.8 7312.2 7652.0 7717.7 7835.0 10690.6 10936.6 10976.2 11075.4 11116.9 9810.4 9852.0 9873.6 10074.5 10414.7 9864.7 10002.4 10221.2 10399.0 10437.5 9293.3 9885.3 9946.2 10335.6 10442.8 7339.8 7544.6 7604.6 7834.1 7867.4 8765.6 8772.8 9211.1 9469.9 9707.2 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | [#3] mootools.html Cycles:180 Average:4912.62 Median:4787.05 stddev:2709.76 (56.6%) stddev-sans-first:2704.20 15:46:14 INFO - PROCESS | 9147 | Values: 1358.0 1362.1 1472.9 1661.2 1702.2 7116.9 7568.2 7569.0 7571.8 7581.1 6681.3 6849.6 7094.6 7307.1 7415.7 2118.0 2148.4 2158.2 2159.6 2162.4 2196.5 2215.2 2264.5 2268.1 2269.3 2153.1 2156.6 2173.7 2174.3 2184.4 2106.6 2123.2 2135.2 2140.1 2144.2 2141.2 2182.5 2184.0 2184.6 2186.5 7471.8 7501.6 7619.5 7623.5 7635.7 4441.3 4583.7 4586.3 4588.9 4589.6 2787.2 2936.0 2987.9 2988.5 3003.7 826.4 847.7 866.2 868.6 892.2 9985.3 10057.7 10058.7 10058.9 10066.5 4084.6 4244.3 4282.5 4310.6 4312.5 8598.4 8599.3 8605.8 8608.7 8614.1 4714.3 4714.4 4717.4 4718.1 4718.4 3748.1 3785.2 3897.8 4255.7 4262.8 3560.7 3623.9 3651.8 3717.1 3740.7 1214.6 1217.3 1218.4 1226.9 1235.1 805.1 814.6 818.2 823.1 837.0 4765.4 4844.0 4886.8 4927.5 5006.0 392.8 394.1 394.2 397.8 404.5 3301.3 3335.3 3347.9 3359.3 3482.8 8691.5 9091.4 9102.4 9113.0 9116.4 9087.7 9102.0 9103.5 9103.5 9105.0 7144.5 7170.4 7203.0 7220.6 7225.4 4782.3 4791.8 4861.4 4889.5 5065.5 8349.3 8412.3 8421.1 8453.7 8457.5 8392.5 8532.9 8544.5 8591.4 8704.0 5969.0 6089.1 6146.2 6635.5 6718.6 6630.4 7130.8 7189.9 7355.9 7365.4 6460.2 6520.8 6538.4 6558.5 6727.3 6240.2 6308.8 6376.0 6437.1 6482.1 5772.0 5874.2 5879.9 5883.7 5888.7 4298.6 4302.6 4336.7 4355.4 4457.0 4120.8 4215.3 4308.2 4390.6 4425.4 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | [#4] prototype.html Cycles:180 Average:4180.84 Median:4711.01 stddev:1167.14 (24.8%) stddev-sans-first:1134.22 15:46:14 INFO - PROCESS | 9147 | Values: 338.6 340.5 343.8 348.4 349.9 4809.8 4866.8 4869.3 4884.7 4899.0 4758.3 4791.8 4801.7 4802.8 4808.4 3711.3 3815.2 3832.9 3837.4 3859.2 3857.8 3859.8 3868.4 3876.6 3895.4 3816.6 3839.7 3844.3 3860.6 3866.8 3685.4 3755.6 3791.3 3804.8 3846.7 3408.8 3409.0 3415.2 3420.8 3422.9 4654.5 4656.3 4659.1 4668.0 4668.6 4183.2 4356.5 4361.7 4370.4 4380.9 4938.3 5019.9 5023.2 5053.6 5054.6 3754.1 3765.4 3773.5 3775.5 3782.3 5169.2 5257.6 5295.0 5314.6 5324.6 3815.6 3847.4 3855.3 3870.0 3872.7 3922.6 3923.2 3926.5 3926.9 3932.6 4369.5 4385.8 4387.4 4387.6 4389.8 4923.2 5057.5 5064.4 5097.9 5100.1 1300.4 1305.6 1308.0 1309.7 1312.9 907.2 944.8 955.8 960.6 963.2 4310.8 4337.8 4420.9 4464.9 4479.5 5174.7 5177.7 5182.0 5186.0 5217.6 4434.4 4459.5 4460.0 4470.5 4476.8 2580.9 2656.4 2670.5 2680.3 2685.2 4726.2 4728.6 4730.6 4735.0 4747.4 4972.0 5127.2 5179.3 5203.4 5231.9 4668.6 4693.4 4706.6 4715.4 4754.7 5005.1 5007.5 5028.8 5033.7 5065.4 4746.8 4909.3 4937.4 4972.4 4975.3 4682.1 4728.3 4746.2 4746.9 4749.8 3769.9 3773.4 3774.9 3778.6 3791.1 5084.6 5094.5 5094.7 5099.7 5120.3 4652.9 4880.5 4893.7 4906.0 4936.9 4893.0 4907.8 4921.8 4923.2 4958.4 4850.3 4874.9 4876.3 4883.1 4887.2 4626.6 4874.6 4893.4 4938.4 4971.6 4830.6 4938.8 4942.9 4961.3 4962.0 15:46:14 INFO - PROCESS | 9147 | 15:46:14 INFO - PROCESS | 9147 | [#5] yui.html Cycles:180 Average:14307.59 Median:469.40 stddev:17760.87 (3783.7%) stddev-sans-first:17781.02 15:46:14 INFO - PROCESS | 9147 | Values: 634.4 639.7 672.5 725.0 733.7 12424.4 13692.6 13721.9 13756.7 13765.4 12761.2 13021.5 13192.5 13194.8 13198.7 812.5 821.1 824.3 825.2 826.9 819.6 823.0 823.2 823.6 823.9 859.8 859.8 863.0 863.7 864.2 853.7 855.1 856.2 856.8 857.7 625.8 630.2 631.7 631.8 632.3 11300.4 11570.8 11603.7 11610.9 11649.9 9956.4 10170.9 10216.3 10407.3 10427.9 9375.2 9375.4 9382.5 9547.3 9621.7 3187.7 3227.9 3260.7 3261.0 3304.0 56798.7 57811.0 58676.0 58817.1 58849.0 8118.3 8355.6 8394.3 8488.4 8514.4 17277.7 17328.9 17497.5 17515.7 17532.7 6301.7 6325.9 6417.4 6524.9 6550.3 3426.3 3428.1 3439.8 3480.8 3509.9 2920.3 2995.6 3012.6 3022.0 3024.2 448.6 467.3 471.5 473.1 478.7 547.5 570.1 576.2 596.8 609.5 7160.0 7239.3 7424.6 7595.2 7694.9 460.6 491.7 521.4 556.6 580.4 3537.6 3794.5 3896.3 3918.1 4031.7 52030.7 52249.0 65598.3 68618.6 70233.6 49498.0 52368.7 58855.2 59797.0 60738.0 46689.5 48534.3 49877.3 53537.7 57762.0 8098.8 8110.8 8516.5 8754.6 8834.1 33425.0 34175.5 35174.0 36115.3 37830.8 41779.0 45635.1 46522.8 46678.8 48678.8 11392.4 11420.7 11464.4 11616.1 11828.3 12522.4 12661.6 12743.6 12967.6 13113.9 12004.6 12021.8 12130.9 12203.9 12229.3 11890.5 11911.9 12287.5 12361.0 12766.4 11302.6 11485.1 11600.2 11747.5 11767.0 9958.6 10051.5 10061.5 10337.5 10524.0 10023.3 10189.4 10251.0 10376.8 10377.9 15:46:14 INFO - PROCESS | 9147 | -------- Summary: end -------- 15:46:14 INFO - PROCESS | 9147 | 15:46:15 INFO - TEST-INFO | 9147: exit 0 15:46:15 INFO - TEST-OK | dromaeo_css | took 1120774ms 15:46:15 INFO - TEST-START | kraken 15:46:15 INFO - operating with platform_type : linux_ 15:46:15 INFO - Initialising browser for kraken test... 15:46:15 INFO - TEST-INFO | started process 9447 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaMsBcf/profile http://localhost:56551/getInfo.html) 15:46:15 INFO - PROCESS | 9447 | 1482536775853 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:46:18 INFO - PROCESS | 9447 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:46:18 INFO - PROCESS | 9447 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:46:19 INFO - PROCESS | 9447 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:46:19 INFO - PROCESS | 9447 | __metrics Screen width/height:1600/1200 15:46:19 INFO - PROCESS | 9447 | colorDepth:24 15:46:19 INFO - PROCESS | 9447 | Browser inner width/height: 1024/697 15:46:19 INFO - PROCESS | 9447 | __metrics 15:46:20 INFO - PROCESS | 9447 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:46:20 INFO - PROCESS | 9447 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:46:20 INFO - PROCESS | 9447 | console.error: PushService: 15:46:20 INFO - PROCESS | 9447 | stateChangeProcessEnqueue: Error transitioning state 15:46:20 INFO - PROCESS | 9447 | UnknownError 15:46:21 INFO - TEST-INFO | 9447: exit 0 15:46:21 INFO - Browser initialized. 15:46:21 INFO - Running cycle 1/1 for kraken test... 15:46:21 INFO - TEST-INFO | started process 9532 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaMsBcf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:33 INFO - PROCESS | 9532 | RSS: Main: 151576576 15:46:33 INFO - PROCESS | 9532 | 15:46:36 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-beat-detection) 15:46:37 INFO - PROCESS | 9532 | RSS: Main: 205082624 15:46:37 INFO - PROCESS | 9532 | 15:46:39 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-dft) 15:46:40 INFO - PROCESS | 9532 | RSS: Main: 184930304 15:46:40 INFO - PROCESS | 9532 | 15:46:43 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-fft) 15:46:44 INFO - PROCESS | 9532 | RSS: Main: 267399168 15:46:44 INFO - PROCESS | 9532 | 15:46:46 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-oscillator) 15:46:46 INFO - PROCESS | 9532 | RSS: Main: 179245056 15:46:46 INFO - PROCESS | 9532 | 15:46:49 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56551/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:46:49 INFO - PROCESS | 9532 | RSS: Main: 181833728 15:46:49 INFO - PROCESS | 9532 | 15:46:52 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56551/tests/kraken/driver.html?testName=imaging-darkroom) 15:46:53 INFO - PROCESS | 9532 | RSS: Main: 221368320 15:46:53 INFO - PROCESS | 9532 | 15:46:56 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56551/tests/kraken/driver.html?testName=imaging-desaturate) 15:46:57 INFO - PROCESS | 9532 | RSS: Main: 215273472 15:46:57 INFO - PROCESS | 9532 | 15:47:00 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56551/tests/kraken/driver.html?testName=json-parse-financial) 15:47:01 INFO - PROCESS | 9532 | RSS: Main: 217272320 15:47:01 INFO - PROCESS | 9532 | 15:47:02 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56551/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:47:03 INFO - PROCESS | 9532 | RSS: Main: 180547584 15:47:03 INFO - PROCESS | 9532 | 15:47:05 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:47:05 INFO - PROCESS | 9532 | RSS: Main: 188166144 15:47:05 INFO - PROCESS | 9532 | 15:47:08 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:47:08 INFO - PROCESS | 9532 | RSS: Main: 196304896 15:47:08 INFO - PROCESS | 9532 | 15:47:11 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:47:12 INFO - PROCESS | 9532 | RSS: Main: 205619200 15:47:12 INFO - PROCESS | 9532 | 15:47:15 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:47:15 INFO - PROCESS | 9532 | RSS: Main: 201949184 15:47:15 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56551/tests/kraken/driver.html?testName=ai-astar) 15:47:17 INFO - PROCESS | 9532 | RSS: Main: 252723200 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | __start_tp_report 15:47:17 INFO - PROCESS | 9532 | _x_x_mozilla_page_load 15:47:17 INFO - PROCESS | 9532 | _x_x_mozilla_page_load_details 15:47:17 INFO - PROCESS | 9532 | |i|pagename|runs| 15:47:17 INFO - PROCESS | 9532 | |0;ai-astar;106;115;114;117;115;114;114;115;115;113 15:47:17 INFO - PROCESS | 9532 | |1;audio-beat-detection;163;125;124;168;128;132;125;128;157;127 15:47:17 INFO - PROCESS | 9532 | |2;audio-dft;160;160;161;162;163;161;161;162;169;162 15:47:17 INFO - PROCESS | 9532 | |3;audio-fft;76;75;75;106;76;76;78;78;94;77 15:47:17 INFO - PROCESS | 9532 | |4;audio-oscillator;84;85;104;85;84;83;97;84;85;110 15:47:17 INFO - PROCESS | 9532 | |5;imaging-gaussian-blur;118;117;116;117;117;117;117;117;118;117 15:47:17 INFO - PROCESS | 9532 | |6;imaging-darkroom;150;148;148;148;148;149;149;148;149;148 15:47:17 INFO - PROCESS | 9532 | |7;imaging-desaturate;95;95;95;96;95;95;95;96;95;95 15:47:17 INFO - PROCESS | 9532 | |8;json-parse-financial;59;59;58;59;60;59;60;59;59;60 15:47:17 INFO - PROCESS | 9532 | |9;json-stringify-tinderbox;67;67;67;67;67;68;67;68;68;67 15:47:17 INFO - PROCESS | 9532 | |10;stanford-crypto-aes;85;85;84;85;94;85;96;85;85;84 15:47:17 INFO - PROCESS | 9532 | |11;stanford-crypto-ccm;133;135;133;133;139;132;131;140;189;162 15:47:17 INFO - PROCESS | 9532 | |12;stanford-crypto-pbkdf2;176;174;174;175;173;173;175;173;174;173 15:47:17 INFO - PROCESS | 9532 | |13;stanford-crypto-sha256-iterative;66;66;72;66;67;67;66;66;66;66 15:47:17 INFO - PROCESS | 9532 | __end_tp_report 15:47:17 INFO - PROCESS | 9532 | __start_cc_report 15:47:17 INFO - PROCESS | 9532 | _x_x_mozilla_cycle_collect,1877 15:47:17 INFO - PROCESS | 9532 | __end_cc_report 15:47:17 INFO - PROCESS | 9532 | __startTimestamp1482536837894__endTimestamp 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | ------- Summary: start ------- 15:47:17 INFO - PROCESS | 9532 | Number of tests: 14 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:2.94 (2.6%) stddev-sans-first:1.12 15:47:17 INFO - PROCESS | 9532 | Values: 106.0 115.0 114.0 117.0 115.0 114.0 114.0 115.0 115.0 113.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:130.00 stddev:17.56 (13.5%) stddev-sans-first:16.07 15:47:17 INFO - PROCESS | 9532 | Values: 163.0 125.0 124.0 168.0 128.0 132.0 125.0 128.0 157.0 127.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:2.60 (1.6%) stddev-sans-first:2.65 15:47:17 INFO - PROCESS | 9532 | Values: 160.0 160.0 161.0 162.0 163.0 161.0 161.0 162.0 169.0 162.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.50 stddev:10.41 (13.6%) stddev-sans-first:10.87 15:47:17 INFO - PROCESS | 9532 | Values: 76.0 75.0 75.0 106.0 76.0 76.0 78.0 78.0 94.0 77.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:84.50 stddev:9.87 (11.7%) stddev-sans-first:10.22 15:47:17 INFO - PROCESS | 9532 | Values: 84.0 85.0 104.0 85.0 84.0 83.0 97.0 84.0 85.0 110.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 15:47:17 INFO - PROCESS | 9532 | Values: 118.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:148.50 stddev:0.71 (0.5%) stddev-sans-first:0.50 15:47:17 INFO - PROCESS | 9532 | Values: 150.0 148.0 148.0 148.0 148.0 149.0 149.0 148.0 149.0 148.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:47:17 INFO - PROCESS | 9532 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 15:47:17 INFO - PROCESS | 9532 | Values: 59.0 59.0 58.0 59.0 60.0 59.0 60.0 59.0 59.0 60.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 15:47:17 INFO - PROCESS | 9532 | Values: 67.0 67.0 67.0 67.0 67.0 68.0 67.0 68.0 68.0 67.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:85.00 stddev:4.37 (5.1%) stddev-sans-first:4.58 15:47:17 INFO - PROCESS | 9532 | Values: 85.0 85.0 84.0 85.0 94.0 85.0 96.0 85.0 85.0 84.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:137.00 stddev:18.65 (13.6%) stddev-sans-first:19.45 15:47:17 INFO - PROCESS | 9532 | Values: 133.0 135.0 133.0 133.0 139.0 132.0 131.0 140.0 189.0 162.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:174.00 stddev:1.05 (0.6%) stddev-sans-first:0.83 15:47:17 INFO - PROCESS | 9532 | Values: 176.0 174.0 174.0 175.0 173.0 173.0 175.0 173.0 174.0 173.0 15:47:17 INFO - PROCESS | 9532 | 15:47:17 INFO - PROCESS | 9532 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.00 stddev:1.87 (2.8%) stddev-sans-first:1.96 15:47:17 INFO - PROCESS | 9532 | Values: 66.0 66.0 72.0 66.0 67.0 67.0 66.0 66.0 66.0 66.0 15:47:17 INFO - PROCESS | 9532 | -------- Summary: end -------- 15:47:17 INFO - PROCESS | 9532 | 15:47:18 INFO - TEST-INFO | 9532: exit 0 15:47:19 INFO - TEST-OK | kraken | took 63684ms 15:47:19 INFO - SUITE-END | took 1184s 15:47:19 INFO - Completed test suite (00:19:44) 15:47:19 INFO - javascript benchmark 15:47:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2557.70586382566, 2572.022631400068, 2586.4697736963917, 2601.414681696617, 2603.6224747411625, 4501.324801279809, 4565.65757568182, 4638.118757436089, 4689.976550117249, 4692.53074692531, 4674.0, 4728.172569800288, 4756.690815097017, 4786.4974177711365, 4838.951610483894, 4304.741715497064, 4334.696571240014, 4351.325544540601, 4367.934480982788, 4414.514403415622, 4005.8998525036964, 4365.497967733703, 4452.97773511132, 4503.1894259033525, 4530.252508336121, 4246.065865509583, 4421.977890110529, 4433.911321773547, 4445.177642136199, 4522.072975040109, 4285.61429471349, 4298.4197133387215, 4340.392345071693, 4352.499462561815, 4369.366441865944, 4342.761148136851, 4343.9348409773875, 4355.6733245006435, 4381.561843815625, 4400.801963911632, 4687.1328804171335, 4691.15559199344, 4713.575778179946, 4720.315554244616, 4724.385829842133, 4796.784144713496, 4839.661223714342, 5004.174311238678, 5010.849674509771, 5066.594672426197, 5102.668326558799, 5103.438621751604, 5109.080365534202, 5134.204198349262, 5142.023015627019, 4770.379850619397, 4776.498467660915, 4776.546228108394, 4777.355057067321, 4819.469858315582, 5015.598752099823, 5070.645054846199, 5092.898142037213, 5192.4547922468355, 5263.421023687391, 4957.479464656231, 4972.701637901738, 4974.850754477371, 5019.0965626187635, 5030.144875371196, 4834.613230941516, 4874.317595536628, 5045.697258164522, 5053.444121146671, 5106.872328191751, 4891.095147397743, 4904.215325547895, 4955.72743499111, 4966.255061740696, 5069.898602027939, 4807.134715751198, 4845.9757701211265, 4952.529509696573, 4989.7006179628615, 5010.574101201439, 4170.895727606773, 4175.83296668136, 4194.958050419456, 4215.578442155821, 4253.851115210953, 3687.7049836013052, 3694.9445758313645, 3696.353138200804, 3717.275131349361, 3718.9442158367647, 4215.451991241119, 4356.520782713899, 4356.586124318184, 4361.389405483234, 4399.692021558524, 4877.902441951142, 4948.529889660476, 4974.477679843637, 5038.622103342264, 5042.672226305279, 4366.672499562482, 4367.126574685076, 4368.27923392643, 4392.956070439319, 4409.603135717801, 4534.523874993144, 4538.500764915857, 4539.251023581072, 4540.704854184468, 4580.129775342674, 4587.334836448763, 4625.7918393673035, 4695.906081878412, 4716.10394025141, 4720.362751028567, 5085.567726743268, 5087.898242035212, 5087.898242035212, 5109.821156259513, 5121.974390128025, 4732.100900828831, 4742.928856067231, 4746.382970213988, 4756.928646070242, 4782.0, 4843.2735089736825, 4858.052679727561, 4960.181570041047, 4988.997779687415, 5054.19132938728, 5051.292819005415, 5103.464136265638, 5105.0, 5142.871428214325, 5153.7165455900285, 4713.363695901009, 4918.409790825124, 4956.405231372259, 4958.752062396794, 5010.198368261061, 4725.385699859134, 4745.83389581363, 4874.317595536699, 4919.0407870464915, 4928.852134435972, 4847.563719265283, 4866.1484240257105, 4868.634852386014, 4908.263760435963, 4937.308776771183, 4964.255361695775, 4968.378952630921, 4977.352944117241, 4985.326980857682, 4986.775595098156, 4682.274247491644, 4687.273472611751, 4688.460827004938, 4782.450018247807, 4891.437484689306], "value": 4616.7227952504245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.6719133350322, 525.2724975908367, 534.0307342173952, 541.4558368839316, 541.8482824809053, 28575.57136642952, 38354.42468362973, 40334.0, 40356.0, 44057.55942440574, 39970.0, 40398.0, 40487.0, 40557.391639125366, 40592.59407405925, 7384.815379615499, 7571.734989275376, 7729.497582657137, 7737.032870891138, 7739.806504837367, 7356.521826081287, 9686.418814871131, 9861.654842080528, 9914.058164474363, 10270.7432314192, 9969.451680157588, 10029.1475224606, 10037.849432258485, 10040.799184016316, 10055.145312648434, 9838.950805245964, 9902.80194396108, 9913.75215619608, 9914.107730304308, 9924.305298629099, 9687.951560242154, 9746.853797192976, 9747.512624368754, 9748.463834489101, 9749.171320437772, 38228.808855955824, 38366.80816595899, 38440.80779596112, 38441.61558384408, 38609.42085868714, 3024.1078881729723, 3026.8940587079346, 3138.8430578470984, 3264.722498587623, 3328.417526932777, 27837.721622783716, 27975.860120699468, 28116.718832811614, 28453.288667783265, 28631.856840715664, 3207.8235697036653, 3254.886078987224, 3287.309664970359, 3293.143782616513, 3317.7345812334956, 193661.0, 249287.0, 250575.0, 250644.0, 250680.0, 49800.25299620508, 49880.75059624751, 50268.49731502711, 50325.96088469407, 50352.74823625859, 51500.742496288025, 51540.0, 51545.0, 51605.741971289906, 51658.48341516612, 14045.859541404456, 14537.41850325975, 14549.0, 14559.708805823824, 14568.198749068903, 10082.899171008343, 10358.171346292278, 10795.676129716121, 11080.722981925355, 11166.944165279285, 15271.923640381949, 15875.745472178176, 15913.124778137082, 15947.730715203355, 15964.361425542847, 5998.940010599838, 6213.440790328892, 6333.68331583419, 6366.066298618515, 6488.572514046902, 809.522381794744, 842.4439869685978, 863.935204859638, 877.2499512916493, 1009.4195837393528, 27479.450410991667, 27786.583201251997, 27868.860655696593, 28262.0, 28277.57583636247, 960.6349587156975, 1003.6587560229557, 1042.9582816687248, 1053.3711364349895, 1084.3493903657743, 14187.432502699776, 14718.33767480466, 14809.259537023107, 14972.326245318987, 15393.923030384776, 362201.0, 364341.0, 364437.0, 364857.0, 366107.0, 48745.756271218415, 49044.26433603499, 49231.0, 49312.260316095286, 49366.50633493691, 1118.6588090632436, 1196.3838623108932, 1207.1893504385018, 1208.86098098718, 1228.3612521488953, 24956.37609059788, 26051.348716282246, 26767.464650707265, 27091.0, 27280.181594552196, 41411.0, 41645.58354416538, 41749.58250417457, 41894.1621167581, 41957.37063944104, 304161.0, 304873.0, 305931.0, 306366.0, 306654.0, 25092.74907250977, 25459.618105728798, 25466.0, 25501.0, 25507.23478295654, 40525.79737101414, 40692.1861562773, 40958.0, 41265.0, 41507.79246103871, 34352.0, 34443.311133777686, 34754.0, 35199.82400087983, 35352.823235883654, 34745.826270868485, 34841.30317393689, 35150.64849351474, 35284.82357588298, 35342.46986295156, 31868.203294916886, 33743.4938475928, 33919.321613568085, 34230.31539369149, 34610.82694586511, 27247.86376068107, 29090.854545727136, 29387.55918661179, 29673.85163074171, 29872.402551948406, 22571.774282256967, 22725.545489090455, 23008.654870177295, 23094.307170784232, 23299.0], "value": 17413.019816570297, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.30641016044143, 372.82104589796916, 378.4588039104081, 381.1975790960029, 396.7778044295195, 12790.0, 15574.22128893555, 15819.0, 17110.0, 17273.91363043183, 14846.406143754237, 15138.0, 17015.82984170158, 17090.82909170911, 17302.0, 5028.6731362461505, 5210.843674689746, 5237.319148510688, 5410.567154627621, 5444.50999410053, 3528.964710352889, 5433.26650902129, 5480.451954804507, 5483.561315094803, 5484.040292948738, 3640.290143422034, 3647.7993710345922, 4645.024544845586, 5848.356680765112, 5928.3775203603645, 5883.941160588382, 5894.115882617599, 5898.203742494751, 5900.380460051697, 5905.055191169392, 5057.822976195852, 5136.280920671109, 5163.457836927106, 5165.948340516585, 5170.870728231786, 12434.751304973941, 12503.249805011637, 13435.596932091956, 19605.21579136833, 19973.30093446737, 1815.863810214226, 1825.1330617956496, 1979.2478858033999, 1986.2849374225268, 2444.4133407982135, 9034.277257819424, 9361.4383137012, 9397.906020939772, 11757.235779674294, 13174.538891138764, 1729.7578339032546, 1760.155125539748, 1786.3033416967394, 1789.7494350790769, 1796.8562514998769, 61455.69272153655, 62364.68817655928, 62986.685066574835, 63649.68175159094, 64790.352096478906, 9144.679936202201, 9231.892172939157, 9346.719598412059, 9574.712758617252, 9700.369475984038, 13565.25391103479, 13579.185248885098, 13596.660083497794, 13601.183928964294, 13607.31963401826, 6887.20797108339, 6977.9409927801935, 7101.857962840818, 7105.502614816914, 7141.9642901785155, 6612.834679132964, 6621.834454138685, 6662.333766623299, 6838.384545390939, 6857.725690972405, 3794.4308353746605, 3810.676092532166, 3867.6325749053794, 3935.1145992151537, 3953.248882712333, 1742.3727458115068, 1789.364775504677, 1798.2087881332172, 1808.1320965936425, 1838.7425760393287, 708.3872450330427, 778.2373274191211, 802.0736049862418, 813.7233340664203, 841.486693117198, 14928.850711492963, 15153.242337883063, 15595.922020390053, 15630.218489075502, 16321.428749993693, 757.4167890724111, 773.97678069658, 802.1617409806765, 832.2800777327568, 834.8580741273877, 8222.095569487352, 8810.207081362709, 8932.776680582907, 8947.289682341716, 8969.416987895765, 327028.0, 329844.0, 330053.0, 331385.0, 334742.0, 65353.34646653568, 65950.34049659442, 66771.33228667651, 67255.0, 68286.65856670684, 900.4417261297906, 914.4056363363734, 918.1002617435084, 923.7690577355661, 932.911373419524, 13637.181769093886, 13647.590572282847, 13703.520376786764, 13754.243516606683, 14226.644333891321, 33191.336173275915, 33443.1639209012, 33617.15957101092, 34363.969080927614, 34838.0, 43692.781536092116, 45286.88422696223, 46126.53873461222, 46646.53353466422, 47353.0, 7310.817229569303, 7312.19565847767, 7652.04349456318, 7717.691292348468, 7835.020622422197, 10690.572377104829, 10936.617218397318, 10976.176786741185, 11075.390853503135, 11116.944415278196, 9810.411375317504, 9852.0, 9873.555689994111, 10074.496275186359, 10414.687559373231, 9864.66045299566, 10002.44986525748, 10221.182305415401, 10399.0, 10437.478126093512, 9293.303002274992, 9885.258605604753, 9946.154576861049, 10335.638252661121, 10442.791144177227, 7339.77980660581, 7544.566532358835, 7604.581748003913, 7834.0599128105, 7867.409944254318, 8765.60554775024, 8772.824543508967, 9211.124943130391, 9469.857952130586, 9707.223422126071], "value": 7796.129816294395, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.9999999999998, 1362.0601784768505, 1472.9337179826907, 1661.1694152923537, 1702.1744453939837, 7116.85766284674, 7568.16750157483, 7569.0160279163765, 7571.772846814604, 7581.052368453943, 6681.331866813316, 6849.554778939366, 7094.645267736619, 7307.050083489139, 7415.740449084283, 2117.9682304765442, 2148.387709502792, 2158.169104894619, 2159.5680863827297, 2162.3945295317335, 2196.527746534495, 2215.1693115081844, 2264.501809601893, 2268.0814270220503, 2269.341890851651, 2153.1064608187585, 2156.644153714635, 2173.66308222225, 2174.2607513445423, 2184.388371256051, 2106.599746048246, 2123.214410668055, 2135.231316725977, 2140.1118535807777, 2144.1637761273114, 2141.218455263833, 2182.4871155278497, 2183.9781602183934, 2184.56308738253, 2186.497105665706, 7471.813204669872, 7501.624918754041, 7619.542827430372, 7623.466357354987, 7635.6563954621615, 4441.311596702516, 4583.679142460029, 4586.312053192048, 4588.885277868047, 4589.632829373642, 2787.1638508447586, 2935.9853200734074, 2987.865546050433, 2988.491956367401, 3003.6695963444004, 826.3595713322163, 847.6694089305176, 866.2117473099449, 868.5787393114334, 892.2326798952895, 9985.251106166994, 10057.698269052013, 10058.698239052841, 10058.9497052515, 10066.496675166212, 4084.632383085477, 4244.342126970325, 4282.507511636201, 4310.633596144363, 4312.54718254585, 8598.398112132216, 8599.26906212979, 8605.784855378666, 8608.698695545625, 8614.05245423003, 4714.339992401066, 4714.38712967312, 4717.386739723814, 4718.127146477913, 4718.433787945469, 3748.1379282765115, 3785.1861849701895, 3897.7661340319105, 4255.744655320691, 4262.808173632194, 3560.7151427885706, 3623.8912832614537, 3651.7991510467177, 3717.070732316921, 3740.663340299386, 1214.6416807041944, 1217.2635555488978, 1218.366449446283, 1226.8650448450662, 1235.1045492018216, 805.1465446626478, 814.5560669435172, 818.1654712193648, 823.1274848660439, 836.9707060252862, 4765.404324459442, 4844.0, 4886.755662216946, 4927.482614325516, 5006.048850718352, 392.7741548609561, 394.10734685936376, 394.1860058978201, 397.75339289640607, 404.47822309220777, 3301.323228738151, 3335.31626016666, 3347.8828241011934, 3359.328134373135, 3482.791032538056, 8691.478511289217, 9091.409058411315, 9102.408343457653, 9113.0, 9116.4074335168, 9087.727368178965, 9102.0, 9103.49930753815, 9103.544822758835, 9104.996900681972, 7144.464165187632, 7170.426365890717, 7203.0, 7220.566765994057, 7225.385842203366, 4782.33047373361, 4791.760411979318, 4861.392325959255, 4889.535494128052, 5065.544101030925, 8349.332053435832, 8412.327013839, 8421.07368189511, 8453.746387608382, 8457.534835584102, 8392.538410387488, 8532.914670853212, 8544.530050847014, 8591.44155629882, 8703.956480217812, 5968.97015514937, 6089.117078023751, 6146.231721034871, 6635.5355125142705, 6718.563293385914, 6630.403263706289, 7130.786076417716, 7189.892151617625, 7355.926440735524, 7365.447591430557, 6460.224773027267, 6520.804375868732, 6538.4115429609665, 6558.508111891531, 6727.293634168342, 6240.188775459162, 6308.779192728416, 6376.0, 6437.066625339393, 6482.124913136666, 5772.0, 5874.177615133799, 5879.853003674772, 5883.676397798162, 5888.705564721661, 4298.570142985676, 4302.612764851053, 4336.674749393745, 4355.368471571668, 4457.0, 4120.7733574653685, 4215.3044747616295, 4308.1599088178755, 4390.604845563915, 4425.380446737524], "value": 3925.223833096616, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.6072156298693, 340.53686985699443, 343.7971596757913, 348.37988380682395, 349.90552550811265, 4809.831655892044, 4866.829660961867, 4869.293952376912, 4884.731339776311, 4898.951010489894, 4758.310045043467, 4791.832285869995, 4801.663883528155, 4802.807887684488, 4808.447028591713, 3711.2763011212824, 3815.198808250257, 3832.930217682448, 3837.3860182370686, 3859.1702783901455, 3857.8263978121054, 3859.7684138951613, 3868.40039793831, 3876.6317199865966, 3895.396213586884, 3816.637419445148, 3839.6561203578804, 3844.2503711776076, 3860.5560360558475, 3866.845326186949, 3685.391910334805, 3755.586885442599, 3791.279656865187, 3804.771713697187, 3846.7499612525235, 3408.829558522064, 3408.960267118528, 3415.2486452980306, 3420.846061927219, 3422.8801991930413, 4654.4647365553055, 4656.254999200111, 4659.138059459011, 4667.953320466786, 4668.5564871337165, 4183.226103170893, 4356.542563030899, 4361.716488428242, 4370.366296886966, 4380.912381752347, 4938.2839488274, 5019.891645508858, 5023.2214006829, 5053.5957123430035, 5054.595632349403, 3754.1177823211524, 3765.3975363941913, 3773.52832395366, 3775.528058992626, 3782.281366540376, 5169.224616307584, 5257.553107985826, 5295.0, 5314.601404894648, 5324.5740340772645, 3815.618438156162, 3847.3844184930276, 3855.267499175148, 3870.0, 3872.7095467840027, 3922.6077392260545, 3923.195744872294, 3926.469926559878, 3926.9410958835642, 3932.6264004919485, 4369.453818272716, 4385.802638881258, 4387.363832244307, 4387.55210780448, 4389.802458889294, 4923.187674033816, 5057.468965758616, 5064.442911279756, 5097.923531147036, 5100.132977393852, 1300.3758196065942, 1305.5573591181796, 1308.0189857606795, 1309.6725818545365, 1312.9146605470614, 907.1563445995288, 944.8063147054837, 955.8088382323563, 960.5533426956359, 963.2438535749403, 4310.7629080400875, 4337.804798784062, 4420.889477763081, 4464.888377790581, 4479.529649386833, 5174.6804564836475, 5177.741112944338, 5182.0, 5185.9740701297005, 5217.643412712722, 4434.423524941737, 4459.531749166355, 4459.9930031485965, 4470.530594287618, 4476.798544065525, 2580.87095645217, 2656.375751698349, 2670.492606404777, 2680.34331588762, 2685.194441667492, 4726.196546587088, 4728.574428301469, 4730.597899178609, 4735.0, 4747.371312969895, 4971.975140124277, 5127.205478082716, 5179.326687530597, 5203.427622961472, 5231.895362092737, 4668.579827815445, 4693.436787585375, 4706.647001474903, 4715.363425937455, 4754.690945088558, 5005.09704371471, 5007.549320561021, 5028.8433660257615, 5033.672811267256, 5065.417476990193, 4746.7863946121815, 4909.288153217692, 4937.35814344133, 4972.403311602631, 4975.328330675313, 4682.063587282489, 4728.267118596623, 4746.2168742157155, 4746.905061898812, 4749.810007599657, 3769.9434508481845, 3773.358529050013, 3774.905627359338, 3778.603246659171, 3791.099808646333, 5084.593232541314, 5094.490550944876, 5094.745262736774, 5099.719515426687, 5120.25756265332, 4652.906941861076, 4880.536349046835, 4893.681910675795, 4905.950940490549, 4936.901261974812, 4893.042782886873, 4907.803687852446, 4921.803127874988, 4923.212286034361, 4958.404991401055, 4850.345203397496, 4874.8537543873745, 4876.29293752411, 4883.118959813727, 4887.218045112765, 4626.560476754703, 4874.5856602189215, 4893.388326459192, 4938.38270216223, 4971.552560269593, 4830.565249127595, 4938.75306234694, 4942.8764280893265, 4961.305417241661, 4962.0], "value": 3826.2750925615464, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.3561285295425, 639.686553588741, 672.539310572258, 725.0067407651518, 733.6625152429883, 12424.440900159492, 13692.58922232332, 13721.93139034306, 13756.656083597914, 13765.380557874894, 12761.170523915938, 13021.54424595137, 13192.472301107944, 13194.802077968814, 13198.670033249173, 812.4800127918152, 821.1255013410728, 824.3116997307255, 825.2407784837964, 826.9421140520166, 819.6434550970317, 822.9876551851727, 823.1850468036661, 823.5758584329076, 823.9340852731767, 859.7678626770768, 859.793649524116, 863.0290922711949, 863.6588547523725, 864.1531299326664, 853.6670698427556, 855.0765173612515, 856.2251162697737, 856.7686724584358, 857.6569372251087, 625.799744081894, 630.2027934662656, 631.671530803984, 631.7567736421465, 632.3486808587163, 11300.378479183642, 11570.826437603442, 11603.6518904433, 11610.941945290304, 11649.941750291278, 9956.402615843, 10170.898291017069, 10216.335938163995, 10407.271490995634, 10427.947860260727, 9375.249980001583, 9375.437473751528, 9382.483963382012, 9547.28395370343, 9621.663241786504, 3187.6979281452427, 3227.870245414086, 3260.7446133238873, 3261.037993791837, 3303.9504407433906, 56798.7160064197, 57811.0, 58676.0, 58817.11774323346, 58849.0, 8118.2693557580105, 8355.582220888931, 8394.328453723687, 8488.363372747068, 8514.403991720645, 17277.65444691124, 17328.913355433142, 17497.47507574775, 17515.73726394105, 17532.737008944878, 6301.6534090625455, 6325.9051114233325, 6417.39034791694, 6524.869502610016, 6550.312217217219, 3426.2633533790354, 3428.0915557377316, 3439.810810405452, 3480.7911525308564, 3509.8771542995873, 2920.2991282092144, 2995.5806187133817, 3012.5932999045285, 3021.9546706799415, 3024.1985873743474, 448.5536890793681, 467.3130498167688, 471.4907899468566, 473.1318031412397, 478.74626447982627, 547.4853637580741, 570.1305509098657, 576.224977405389, 596.8448203467129, 609.4941198804981, 7159.964200178966, 7239.276072392719, 7424.575424575424, 7595.164531901486, 7694.884576731353, 460.64300167369885, 491.67795094213346, 521.42382667153, 556.6073644196401, 580.4398755201252, 3537.575490941052, 3794.4877441545036, 3896.279188350164, 3918.118423354779, 4031.717779755389, 52030.73984630129, 52249.0, 65598.34401655922, 68618.56082381523, 70233.64883175654, 49498.0, 52368.73815630897, 58855.23434296978, 59797.0, 60738.0, 46689.53310466988, 48534.27198592024, 49877.25184122241, 53537.73231133819, 57762.0, 8098.797530061795, 8110.83778324442, 8516.531590762568, 8754.562271886507, 8834.116588341114, 33425.0, 34175.48736769, 35174.0, 36115.277694446486, 37830.81084594559, 41778.95552611209, 45635.08729825451, 46522.76738616285, 46678.766606166755, 48678.75660621674, 11392.373419462008, 11420.657380278604, 11464.369459679465, 11616.128790340927, 11828.32677920825, 12522.37388130608, 12661.556845510362, 12743.617691469271, 12967.610971670865, 13113.868861311264, 12004.639860804189, 12021.81967270509, 12130.878691213327, 12203.877961220274, 12229.266244025386, 11890.464929078385, 11911.880881191077, 12287.508499659914, 12361.0, 12766.425510851848, 11302.604408845651, 11485.138614603771, 11600.18798684084, 11747.530098796295, 11767.0, 9958.601655933971, 10051.547680354548, 10061.547230374505, 10337.534810933677, 10524.0, 10023.298369114093, 10189.411512348888, 10251.0, 10376.792464150825, 10377.9481102594], "value": 6115.032084114974, "unit": "score"}], "name": "dromaeo_css", "value": 6213.879193536419, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 114.0, 117.0, 115.0, 114.0, 114.0, 115.0, 115.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 125.0, 124.0, 168.0, 128.0, 132.0, 125.0, 128.0, 157.0, 127.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 162.0, 163.0, 161.0, 161.0, 162.0, 169.0, 162.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 106.0, 76.0, 76.0, 78.0, 78.0, 94.0, 77.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 104.0, 85.0, 84.0, 83.0, 97.0, 84.0, 85.0, 110.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 148.0, 148.0, 149.0, 149.0, 148.0, 149.0, 148.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 60.0, 59.0, 60.0, 59.0, 59.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 67.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 94.0, 85.0, 96.0, 85.0, 85.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 135.0, 133.0, 133.0, 139.0, 132.0, 131.0, 140.0, 189.0, 162.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 174.0, 174.0, 175.0, 173.0, 173.0, 175.0, 173.0, 174.0, 173.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 72.0, 66.0, 67.0, 67.0, 66.0, 66.0, 66.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1542.4, "alertThreshold": 2.0}]} 15:47:19 INFO - Return code: 0 15:47:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:47:19 INFO - # TBPL SUCCESS # 15:47:19 INFO - Running post-action listener: _resource_record_post_action 15:47:19 INFO - [mozharness: 2016-12-23 23:47:19.219115Z] Finished run-tests step (success) 15:47:19 INFO - Running post-run listener: _resource_record_post_run 15:47:19 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393613312; Read time: 8; Write time: 317000 15:47:19 INFO - TinderboxPrint: CPU usage
13.1% 15:47:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:47:19 INFO - TinderboxPrint: I/O write bytes / time
393,613,312 / 317,000 15:47:19 INFO - TinderboxPrint: CPU idle
8,326.7 (86.9%) 15:47:19 INFO - TinderboxPrint: CPU user
1,214.9 (12.7%) 15:47:19 INFO - TinderboxPrint: Swap in / out
0 / 0 15:47:19 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100159488; Read time: 0; Write time: 105640 15:47:19 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 282161152; Read time: 0; Write time: 209552 15:47:19 INFO - Running post-run listener: _upload_blobber_files 15:47:19 INFO - Blob upload gear active. 15:47:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:47:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:47:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:47:20 INFO - (blobuploader) - INFO - Open directory for files ... 15:47:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:47:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:47:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:21 INFO - (blobuploader) - INFO - Done attempting. 15:47:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:47:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:47:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:24 INFO - (blobuploader) - INFO - Done attempting. 15:47:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:47:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:47:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:25 INFO - (blobuploader) - INFO - Done attempting. 15:47:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:47:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:47:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:25 INFO - (blobuploader) - INFO - Done attempting. 15:47:25 INFO - (blobuploader) - INFO - Iteration through files over. 15:47:25 INFO - Return code: 0 15:47:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:47:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:47:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/10d7279877a0fff499876f39b230a3861a946ba791c99069447bfcd2d62543dd5659449d67c48096c83da2d2f9cc33adc231d2db6f3b6f1e9de66602a0c72c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a161b95ddeddab8115d18ea8ce74d10fd3e968e7d08605bbd663137da17c102b7aea252008018f9d22f2a0f3e6c54e47800c478182bf16d3efe4a7fb138f0f68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7184707f7f3b4e024c18770c3bf592d6522e0f324b1e835e8134e626de00616777dc6600e091261397f10860a4c3833eff4ba1f487d802a53110b867d538c388", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba60dcc4787271dc644ba4bf9e420e443f7b9ad50134739e72806186b9c37ddb54b4fe02f27b79159eb5f53eb9ccafdc5669b114a25ec4d6a951c7108acc168a"} 15:47:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:47:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:47:25 INFO - Contents: 15:47:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/10d7279877a0fff499876f39b230a3861a946ba791c99069447bfcd2d62543dd5659449d67c48096c83da2d2f9cc33adc231d2db6f3b6f1e9de66602a0c72c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a161b95ddeddab8115d18ea8ce74d10fd3e968e7d08605bbd663137da17c102b7aea252008018f9d22f2a0f3e6c54e47800c478182bf16d3efe4a7fb138f0f68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7184707f7f3b4e024c18770c3bf592d6522e0f324b1e835e8134e626de00616777dc6600e091261397f10860a4c3833eff4ba1f487d802a53110b867d538c388", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba60dcc4787271dc644ba4bf9e420e443f7b9ad50134739e72806186b9c37ddb54b4fe02f27b79159eb5f53eb9ccafdc5669b114a25ec4d6a951c7108acc168a"} 15:47:25 INFO - Running post-run listener: copy_logs_to_upload_dir 15:47:25 INFO - Copying logs to upload dir... 15:47:25 INFO - mkdir: /builds/slave/test/build/upload/logs 15:47:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.413048 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-23 15:47:25.410621) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 15:47:25.413897) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/10d7279877a0fff499876f39b230a3861a946ba791c99069447bfcd2d62543dd5659449d67c48096c83da2d2f9cc33adc231d2db6f3b6f1e9de66602a0c72c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a161b95ddeddab8115d18ea8ce74d10fd3e968e7d08605bbd663137da17c102b7aea252008018f9d22f2a0f3e6c54e47800c478182bf16d3efe4a7fb138f0f68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7184707f7f3b4e024c18770c3bf592d6522e0f324b1e835e8134e626de00616777dc6600e091261397f10860a4c3833eff4ba1f487d802a53110b867d538c388", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba60dcc4787271dc644ba4bf9e420e443f7b9ad50134739e72806186b9c37ddb54b4fe02f27b79159eb5f53eb9ccafdc5669b114a25ec4d6a951c7108acc168a"} build_url:https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/XqXLNAsRRtuf-GvCk4nI5w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/10d7279877a0fff499876f39b230a3861a946ba791c99069447bfcd2d62543dd5659449d67c48096c83da2d2f9cc33adc231d2db6f3b6f1e9de66602a0c72c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a161b95ddeddab8115d18ea8ce74d10fd3e968e7d08605bbd663137da17c102b7aea252008018f9d22f2a0f3e6c54e47800c478182bf16d3efe4a7fb138f0f68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7184707f7f3b4e024c18770c3bf592d6522e0f324b1e835e8134e626de00616777dc6600e091261397f10860a4c3833eff4ba1f487d802a53110b867d538c388", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba60dcc4787271dc644ba4bf9e420e443f7b9ad50134739e72806186b9c37ddb54b4fe02f27b79159eb5f53eb9ccafdc5669b114a25ec4d6a951c7108acc168a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 15:47:25.438906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:47:25.439204) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1482535579.894350-7849826 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:47:25.521175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 15:47:25.521505) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 15:47:25.521785) ========= ========= Total master_lag: 0.33 =========