builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-019 starttime: 1494556216.22 results: success (0) revision: 4e354dd1049072b47680a37eeed391c1f2177dbf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.225007) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.225450) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.225721) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.240243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.240543) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-11 19:30:16-- 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% 123M=0s 2017-05-11 19:30:16 (123 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109174 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.363418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.363732) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022288 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:16.412984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 19:30:16.416332) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4e354dd1049072b47680a37eeed391c1f2177dbf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4e354dd1049072b47680a37eeed391c1f2177dbf --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=78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-11 19:30:16,948 truncating revision to first 12 chars 2017-05-11 19:30:16,948 Setting DEBUG logging. 2017-05-11 19:30:16,948 attempt 1/10 2017-05-11 19:30:16,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4e354dd10490?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 19:30:18,256 unpacking tar archive at: mozilla-beta-4e354dd10490/testing/mozharness/ program finished with exit code 0 elapsedTime=1.639838 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 19:30:18.069067) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:18.069416) ========= script_repo_revision: 4e354dd1049072b47680a37eeed391c1f2177dbf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:18.069790) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:18.070057) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 19:30:18.081204) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-11 19:30:18.081547) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:30:18 INFO - MultiFileLogger online at 20170511 19:30:18 in /builds/slave/test 19:30:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 19:30:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:30:18 INFO - {'append_to_log': False, 19:30:18 INFO - 'base_work_dir': '/builds/slave/test', 19:30:18 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 19:30:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:30:18 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 19:30:18 INFO - 'buildbot_json_path': 'buildprops.json', 19:30:18 INFO - 'config_files': ('talos/linux_config.py',), 19:30:18 INFO - 'default_actions': ('clobber', 19:30:18 INFO - 'read-buildbot-config', 19:30:18 INFO - 'download-and-extract', 19:30:18 INFO - 'populate-webroot', 19:30:18 INFO - 'create-virtualenv', 19:30:18 INFO - 'install', 19:30:18 INFO - 'run-tests'), 19:30:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:30:18 INFO - 'download_minidump_stackwalk': True, 19:30:18 INFO - 'download_symbols': 'ondemand', 19:30:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:30:18 INFO - 'tooltool.py': '/tools/tooltool.py', 19:30:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:30:18 INFO - '/tools/misc-python/virtualenv.py')}, 19:30:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:30:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:30:18 INFO - 'gecko_profile': False, 19:30:18 INFO - 'gecko_profile_interval': 0, 19:30:18 INFO - 'installer_path': 'installer.exe', 19:30:18 INFO - 'log_level': 'info', 19:30:18 INFO - 'log_name': 'talos', 19:30:18 INFO - 'log_to_console': True, 19:30:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:30:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:30:18 INFO - 'opt_config_files': (), 19:30:18 INFO - 'pip_index': False, 19:30:18 INFO - 'suite': 'dromaeojs', 19:30:18 INFO - 'system_bits': '32', 19:30:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:30:18 INFO - 'title': 'talos-linux64-ix-019', 19:30:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:30:18 INFO - 'use_talos_json': True, 19:30:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:30:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:30:18 INFO - 'work_dir': 'build'} 19:30:18 INFO - [mozharness: 2017-05-12 02:30:18.716274Z] Running clobber step. 19:30:18 INFO - Running pre-action listener: _resource_record_pre_action 19:30:18 INFO - Running main action method: clobber 19:30:18 INFO - rmtree: /builds/slave/test/build 19:30:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:30:20 INFO - Running post-action listener: _resource_record_post_action 19:30:20 INFO - [mozharness: 2017-05-12 02:30:20.258261Z] Finished clobber step (success) 19:30:20 INFO - [mozharness: 2017-05-12 02:30:20.258361Z] Running read-buildbot-config step. 19:30:20 INFO - Running pre-action listener: _resource_record_pre_action 19:30:20 INFO - Running main action method: read_buildbot_config 19:30:20 INFO - Using buildbot properties: 19:30:20 INFO - { 19:30:20 INFO - "project": "", 19:30:20 INFO - "product": "firefox", 19:30:20 INFO - "who": "ryanvm@gmail.com", 19:30:20 INFO - "installer_path": "public/build/target.tar.bz2", 19:30:20 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 19:30:20 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 19:30:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 19:30:20 INFO - "stage_platform": "linux64", 19:30:20 INFO - "basedir": "/builds/slave/test", 19:30:20 INFO - "buildnumber": 40, 19:30:20 INFO - "platform": "ubuntu64_hw", 19:30:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:30:20 INFO - "slavebuilddir": "test", 19:30:20 INFO - "taskId": "ZpJWbgD5SmCHRgUq1p4Gvg", 19:30:20 INFO - "branch": "mozilla-beta", 19:30:20 INFO - "script_repo_revision": "production", 19:30:20 INFO - "revision": "4e354dd1049072b47680a37eeed391c1f2177dbf", 19:30:20 INFO - "slavename": "talos-linux64-ix-019", 19:30:20 INFO - "repo_path": "releases/mozilla-beta" 19:30:20 INFO - } 19:30:20 INFO - Finding installer, test and symbols from parent task. 19:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZpJWbgD5SmCHRgUq1p4Gvg'}, attempt #1 19:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZpJWbgD5SmCHRgUq1p4Gvg'}, attempt #1 19:30:21 INFO - Task dependencies: Ir39m-oNSCmeQtBhK9MCWw 19:30:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw'}, attempt #1 19:30:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2 19:30:21 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/test_packages.json 19:30:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.crashreporter-symbols.zip 19:30:21 INFO - Running post-action listener: _resource_record_post_action 19:30:21 INFO - [mozharness: 2017-05-12 02:30:21.704924Z] Finished read-buildbot-config step (success) 19:30:21 INFO - [mozharness: 2017-05-12 02:30:21.705143Z] Running download-and-extract step. 19:30:21 INFO - Running pre-action listener: _resource_record_pre_action 19:30:21 INFO - Running main action method: download_and_extract 19:30:21 INFO - mkdir: /builds/slave/test/build/tests 19:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:21 INFO - trying https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.test_packages.json 19:30:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:30:22 INFO - Downloaded 986 bytes. 19:30:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:30:22 INFO - Using the following test package requirements: 19:30:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:30:22 INFO - u'common': [u'target.common.tests.zip'], 19:30:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:30:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:30:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:30:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:30:22 INFO - u'mozbase': [u'target.common.tests.zip'], 19:30:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:30:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:30:22 INFO - u'web-platform': [u'target.common.tests.zip', 19:30:22 INFO - u'target.web-platform.tests.zip'], 19:30:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:30:22 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:30:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.common.tests.zip 19:30:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:30:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.common.tests.zip into memory 19:30:23 INFO - Expected file size: 59544526 19:30:23 INFO - Obtained file size: 59544526 19:30:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:30:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.common.tests.zip 19:30:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:30:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.common.tests.zip into memory 19:30:34 INFO - Expected file size: 59544526 19:30:34 INFO - Obtained file size: 59544526 19:30:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.talos.tests.zip 19:30:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:30:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.talos.tests.zip into memory 19:30:45 INFO - Expected file size: 14404449 19:30:45 INFO - Obtained file size: 14404449 19:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:46 INFO - trying https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2 19:30:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:30:47 INFO - Downloaded 59745435 bytes. 19:30:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2 19:30:47 INFO - mkdir: /builds/slave/test/properties 19:30:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:30:47 INFO - Writing to file /builds/slave/test/properties/build_url 19:30:47 INFO - Contents: 19:30:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2 19:30:47 INFO - Running post-action listener: _resource_record_post_action 19:30:47 INFO - Running post-action listener: set_extra_try_arguments 19:30:47 INFO - [mozharness: 2017-05-12 02:30:47.103208Z] Finished download-and-extract step (success) 19:30:47 INFO - [mozharness: 2017-05-12 02:30:47.103305Z] Running populate-webroot step. 19:30:47 INFO - Running pre-action listener: _resource_record_pre_action 19:30:47 INFO - Running main action method: populate_webroot 19:30:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:30:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:30:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:30:47 INFO - '--tppagecycles', 19:30:47 INFO - '7'], 19:30:47 INFO - 'tests': ['tsvgm']}}, 19:30:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:30:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:30:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:30:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:30:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:30:47 INFO - 'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:30:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:30:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:30:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:30:47 INFO - 'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['damp', 'tps']}, 19:30:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:30:47 INFO - 'tests': ['damp', 'tps']}, 19:30:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['dromaeo_dom']}, 19:30:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:30:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['basic_compositor_video']}, 19:30:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:30:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['a11yr', 19:30:47 INFO - 'ts_paint', 19:30:47 INFO - 'tpaint', 19:30:47 INFO - 'sessionrestore', 19:30:47 INFO - 'sessionrestore_no_auto_restore', 19:30:47 INFO - 'tabpaint']}, 19:30:47 INFO - 'other-e10s': {'tests': ['a11yr', 19:30:47 INFO - 'ts_paint', 19:30:47 INFO - 'tpaint', 19:30:47 INFO - 'sessionrestore', 19:30:47 INFO - 'sessionrestore_no_auto_restore', 19:30:47 INFO - 'tabpaint']}, 19:30:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:30:47 INFO - 'ts_paint', 19:30:47 INFO - 'tpaint', 19:30:47 INFO - 'sessionrestore', 19:30:47 INFO - 'sessionrestore_no_auto_restore', 19:30:47 INFO - 'tabpaint']}, 19:30:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:30:47 INFO - 'ts_paint', 19:30:47 INFO - 'tpaint', 19:30:47 INFO - 'sessionrestore', 19:30:47 INFO - 'sessionrestore_no_auto_restore', 19:30:47 INFO - 'tabpaint']}, 19:30:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['a11yr', 19:30:47 INFO - 'ts_paint', 19:30:47 INFO - 'tpaint', 19:30:47 INFO - 'sessionrestore', 19:30:47 INFO - 'sessionrestore_no_auto_restore', 19:30:47 INFO - 'tabpaint']}, 19:30:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['a11yr', 19:30:47 INFO - 'ts_paint', 19:30:47 INFO - 'tpaint', 19:30:47 INFO - 'sessionrestore', 19:30:47 INFO - 'sessionrestore_no_auto_restore', 19:30:47 INFO - 'tabpaint']}, 19:30:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['tsvgx', 19:30:47 INFO - 'tsvgr_opacity', 19:30:47 INFO - 'tart', 19:30:47 INFO - 'tscrollx', 19:30:47 INFO - 'cart', 19:30:47 INFO - 'tsvg_static']}, 19:30:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:30:47 INFO - 'tsvgr_opacity', 19:30:47 INFO - 'tart', 19:30:47 INFO - 'tscrollx', 19:30:47 INFO - 'cart', 19:30:47 INFO - 'tsvg_static']}, 19:30:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:30:47 INFO - 'talos_options': ['--disable-e10s'], 19:30:47 INFO - 'tests': ['tp5o']}, 19:30:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:30:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:30:47 INFO - 'talos_options': ['--disable-e10s', 19:30:47 INFO - '--xperf_path', 19:30:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:30:47 INFO - 'tests': ['tp5n']}, 19:30:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:30:47 INFO - 'talos_options': ['--xperf_path', 19:30:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:30:47 INFO - 'tests': ['tp5n']}}, 19:30:47 INFO - 'talos.zip': {'path': '', 19:30:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:30:47 INFO - Running post-action listener: _resource_record_post_action 19:30:47 INFO - [mozharness: 2017-05-12 02:30:47.116473Z] Finished populate-webroot step (success) 19:30:47 INFO - [mozharness: 2017-05-12 02:30:47.116564Z] Running create-virtualenv step. 19:30:47 INFO - Running pre-action listener: _resource_record_pre_action 19:30:47 INFO - Running main action method: create_virtualenv 19:30:47 INFO - Creating virtualenv /builds/slave/test/build/venv 19:30:47 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 19:30:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:30:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:30:47 INFO - Using real prefix '/usr' 19:30:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:30:47 INFO - Installing distribute.............................................................................................................................................................................................done. 19:30:49 INFO - Installing pip.................done. 19:30:49 INFO - Return code: 0 19:30:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:30:49 INFO - Reading from file tmpfile_stdout 19:30:49 INFO - Output received: 19:30:49 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) 19:30:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:49 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 19:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:30:49 INFO - Using env: {'DISPLAY': ':0', 19:30:49 INFO - 'HOME': '/home/cltbld', 19:30:49 INFO - 'LANG': 'en_US.UTF-8', 19:30:49 INFO - 'LANGUAGE': 'en_US:en', 19:30:49 INFO - 'LOGNAME': 'cltbld', 19:30:49 INFO - 'MAIL': '/var/mail/cltbld', 19:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:49 INFO - 'MOZ_NO_REMOTE': '1', 19:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:49 INFO - 'NO_EM_RESTART': '1', 19:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:49 INFO - 'PWD': '/builds/slave/test', 19:30:49 INFO - 'SHELL': '/bin/bash', 19:30:49 INFO - 'SHLVL': '1', 19:30:49 INFO - 'TERM': 'linux', 19:30:49 INFO - 'TMOUT': '86400', 19:30:49 INFO - 'USER': 'cltbld', 19:30:49 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:49 INFO - '_': '/tools/buildbot/bin/python'} 19:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:50 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 19:30:50 INFO - Cleaning up... 19:30:50 INFO - Return code: 0 19:30:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 19:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:30:50 INFO - Using env: {'DISPLAY': ':0', 19:30:50 INFO - 'HOME': '/home/cltbld', 19:30:50 INFO - 'LANG': 'en_US.UTF-8', 19:30:50 INFO - 'LANGUAGE': 'en_US:en', 19:30:50 INFO - 'LOGNAME': 'cltbld', 19:30:50 INFO - 'MAIL': '/var/mail/cltbld', 19:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:50 INFO - 'MOZ_NO_REMOTE': '1', 19:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:50 INFO - 'NO_EM_RESTART': '1', 19:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:50 INFO - 'PWD': '/builds/slave/test', 19:30:50 INFO - 'SHELL': '/bin/bash', 19:30:50 INFO - 'SHLVL': '1', 19:30:50 INFO - 'TERM': 'linux', 19:30:50 INFO - 'TMOUT': '86400', 19:30:50 INFO - 'USER': 'cltbld', 19:30:50 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:50 INFO - '_': '/tools/buildbot/bin/python'} 19:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:50 INFO - Downloading/unpacking psutil>=3.1.1 19:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:30:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:30:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:30:51 INFO - Installing collected packages: psutil 19:30:51 INFO - Running setup.py install for psutil 19:30:51 INFO - building 'psutil._psutil_linux' extension 19:30:51 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 19:30:51 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 19:30:51 INFO - building 'psutil._psutil_posix' extension 19:30:51 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 19:30:51 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 19:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:30:52 INFO - Successfully installed psutil 19:30:52 INFO - Cleaning up... 19:30:52 INFO - Return code: 0 19:30:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:52 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 19:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:30:52 INFO - Using env: {'DISPLAY': ':0', 19:30:52 INFO - 'HOME': '/home/cltbld', 19:30:52 INFO - 'LANG': 'en_US.UTF-8', 19:30:52 INFO - 'LANGUAGE': 'en_US:en', 19:30:52 INFO - 'LOGNAME': 'cltbld', 19:30:52 INFO - 'MAIL': '/var/mail/cltbld', 19:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:52 INFO - 'MOZ_NO_REMOTE': '1', 19:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:52 INFO - 'NO_EM_RESTART': '1', 19:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:52 INFO - 'PWD': '/builds/slave/test', 19:30:52 INFO - 'SHELL': '/bin/bash', 19:30:52 INFO - 'SHLVL': '1', 19:30:52 INFO - 'TERM': 'linux', 19:30:52 INFO - 'TMOUT': '86400', 19:30:52 INFO - 'USER': 'cltbld', 19:30:52 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:52 INFO - '_': '/tools/buildbot/bin/python'} 19:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:30:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:30:53 INFO - Installing collected packages: mozsystemmonitor 19:30:53 INFO - Running setup.py install for mozsystemmonitor 19:30:53 INFO - Successfully installed mozsystemmonitor 19:30:53 INFO - Cleaning up... 19:30:53 INFO - Return code: 0 19:30:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:53 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 19:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:30:53 INFO - Using env: {'DISPLAY': ':0', 19:30:53 INFO - 'HOME': '/home/cltbld', 19:30:53 INFO - 'LANG': 'en_US.UTF-8', 19:30:53 INFO - 'LANGUAGE': 'en_US:en', 19:30:53 INFO - 'LOGNAME': 'cltbld', 19:30:53 INFO - 'MAIL': '/var/mail/cltbld', 19:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:53 INFO - 'MOZ_NO_REMOTE': '1', 19:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:53 INFO - 'NO_EM_RESTART': '1', 19:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:53 INFO - 'PWD': '/builds/slave/test', 19:30:53 INFO - 'SHELL': '/bin/bash', 19:30:53 INFO - 'SHLVL': '1', 19:30:53 INFO - 'TERM': 'linux', 19:30:53 INFO - 'TMOUT': '86400', 19:30:53 INFO - 'USER': 'cltbld', 19:30:53 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:53 INFO - '_': '/tools/buildbot/bin/python'} 19:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:53 INFO - Downloading/unpacking jsonschema==2.5.1 19:30:53 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 19:30:53 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 19:30:53 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 19:30:53 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 19:30:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:30:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:54 INFO - Downloading functools32-3.2.3-2.tar.gz 19:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:30:55 INFO - warning: no files found matching '*.txt' 19:30:55 INFO - no previously-included directories found matching 'build' 19:30:55 INFO - no previously-included directories found matching 'dist' 19:30:55 INFO - no previously-included directories found matching '.git*' 19:30:55 INFO - Installing collected packages: jsonschema, functools32 19:30:55 INFO - Running setup.py install for functools32 19:30:55 INFO - warning: no files found matching '*.txt' 19:30:55 INFO - no previously-included directories found matching 'build' 19:30:55 INFO - no previously-included directories found matching 'dist' 19:30:55 INFO - no previously-included directories found matching '.git*' 19:30:55 INFO - Successfully installed jsonschema functools32 19:30:55 INFO - Cleaning up... 19:30:55 INFO - Return code: 0 19:30:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:55 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 19:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:30:55 INFO - Using env: {'DISPLAY': ':0', 19:30:55 INFO - 'HOME': '/home/cltbld', 19:30:55 INFO - 'LANG': 'en_US.UTF-8', 19:30:55 INFO - 'LANGUAGE': 'en_US:en', 19:30:55 INFO - 'LOGNAME': 'cltbld', 19:30:55 INFO - 'MAIL': '/var/mail/cltbld', 19:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:55 INFO - 'MOZ_NO_REMOTE': '1', 19:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:55 INFO - 'NO_EM_RESTART': '1', 19:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:55 INFO - 'PWD': '/builds/slave/test', 19:30:55 INFO - 'SHELL': '/bin/bash', 19:30:55 INFO - 'SHLVL': '1', 19:30:55 INFO - 'TERM': 'linux', 19:30:55 INFO - 'TMOUT': '86400', 19:30:55 INFO - 'USER': 'cltbld', 19:30:55 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:55 INFO - '_': '/tools/buildbot/bin/python'} 19:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:30:55 INFO - Cleaning up... 19:30:55 INFO - Return code: 0 19:30:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:55 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 19:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:30:55 INFO - Using env: {'DISPLAY': ':0', 19:30:55 INFO - 'HOME': '/home/cltbld', 19:30:55 INFO - 'LANG': 'en_US.UTF-8', 19:30:55 INFO - 'LANGUAGE': 'en_US:en', 19:30:55 INFO - 'LOGNAME': 'cltbld', 19:30:55 INFO - 'MAIL': '/var/mail/cltbld', 19:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:55 INFO - 'MOZ_NO_REMOTE': '1', 19:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:55 INFO - 'NO_EM_RESTART': '1', 19:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:55 INFO - 'PWD': '/builds/slave/test', 19:30:55 INFO - 'SHELL': '/bin/bash', 19:30:55 INFO - 'SHLVL': '1', 19:30:55 INFO - 'TERM': 'linux', 19:30:55 INFO - 'TMOUT': '86400', 19:30:55 INFO - 'USER': 'cltbld', 19:30:55 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:55 INFO - '_': '/tools/buildbot/bin/python'} 19:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:55 INFO - Downloading/unpacking blobuploader==1.2.4 19:30:55 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 19:30:55 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 19:30:55 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 19:30:55 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 19:30:56 INFO - Downloading blobuploader-1.2.4.tar.gz 19:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:30:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:30:56 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 19:30:56 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 19:30:56 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 19:30:56 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 19:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:30:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:57 INFO - Downloading docopt-0.6.1.tar.gz 19:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:30:57 INFO - Installing collected packages: blobuploader, requests, docopt 19:30:57 INFO - Running setup.py install for blobuploader 19:30:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:30:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:30:57 INFO - Running setup.py install for requests 19:30:57 INFO - Running setup.py install for docopt 19:30:57 INFO - Successfully installed blobuploader requests docopt 19:30:57 INFO - Cleaning up... 19:30:57 INFO - Return code: 0 19:30:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:57 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:30:57 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:30:57 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:30:57 INFO - Using env: {'DISPLAY': ':0', 19:30:57 INFO - 'HOME': '/home/cltbld', 19:30:57 INFO - 'LANG': 'en_US.UTF-8', 19:30:57 INFO - 'LANGUAGE': 'en_US:en', 19:30:57 INFO - 'LOGNAME': 'cltbld', 19:30:57 INFO - 'MAIL': '/var/mail/cltbld', 19:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:57 INFO - 'MOZ_NO_REMOTE': '1', 19:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:57 INFO - 'NO_EM_RESTART': '1', 19:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:57 INFO - 'PWD': '/builds/slave/test', 19:30:57 INFO - 'SHELL': '/bin/bash', 19:30:57 INFO - 'SHLVL': '1', 19:30:57 INFO - 'TERM': 'linux', 19:30:57 INFO - 'TMOUT': '86400', 19:30:57 INFO - 'USER': 'cltbld', 19:30:57 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:57 INFO - '_': '/tools/buildbot/bin/python'} 19:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:30:58 INFO - Running setup.py (path:/tmp/pip-B4blWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:30:58 INFO - Running setup.py (path:/tmp/pip-VnDEel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:30:58 INFO - Running setup.py (path:/tmp/pip-MdVw8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:30:58 INFO - Running setup.py (path:/tmp/pip-AnQMUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:30:58 INFO - Running setup.py (path:/tmp/pip-IoPcJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:30:58 INFO - Running setup.py (path:/tmp/pip-BvoJ3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:30:58 INFO - Running setup.py (path:/tmp/pip-uHMU28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:30:58 INFO - Running setup.py (path:/tmp/pip-cL1GiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:30:58 INFO - Running setup.py (path:/tmp/pip-q4Jwxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:30:58 INFO - Running setup.py (path:/tmp/pip-E93t2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:30:58 INFO - Running setup.py (path:/tmp/pip-Q1PBhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:30:58 INFO - Running setup.py (path:/tmp/pip-6nRQlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:30:59 INFO - Running setup.py (path:/tmp/pip-rYDFsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:30:59 INFO - Running setup.py (path:/tmp/pip-ary5wA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:30:59 INFO - Running setup.py (path:/tmp/pip-YBMS9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:30:59 INFO - Running setup.py (path:/tmp/pip-gLap0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:30:59 INFO - Running setup.py (path:/tmp/pip-oHOqol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:30:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:30:59 INFO - Running setup.py install for manifestparser 19:30:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:30:59 INFO - Running setup.py install for mozcrash 19:30:59 INFO - Running setup.py install for mozdebug 19:30:59 INFO - Running setup.py install for mozdevice 19:30:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:30:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:30:59 INFO - Running setup.py install for mozfile 19:30:59 INFO - Running setup.py install for mozhttpd 19:30:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:30:59 INFO - Running setup.py install for mozinfo 19:31:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Running setup.py install for mozInstall 19:31:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Running setup.py install for mozleak 19:31:00 INFO - Running setup.py install for mozlog 19:31:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Running setup.py install for moznetwork 19:31:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Running setup.py install for mozprocess 19:31:00 INFO - Running setup.py install for mozprofile 19:31:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Running setup.py install for mozrunner 19:31:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:31:00 INFO - Running setup.py install for mozscreenshot 19:31:00 INFO - Running setup.py install for moztest 19:31:01 INFO - Running setup.py install for mozversion 19:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:31:01 INFO - Cleaning up... 19:31:01 INFO - Return code: 0 19:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:31:01 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:31:01 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:31:01 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:31:01 INFO - Using env: {'DISPLAY': ':0', 19:31:01 INFO - 'HOME': '/home/cltbld', 19:31:01 INFO - 'LANG': 'en_US.UTF-8', 19:31:01 INFO - 'LANGUAGE': 'en_US:en', 19:31:01 INFO - 'LOGNAME': 'cltbld', 19:31:01 INFO - 'MAIL': '/var/mail/cltbld', 19:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:01 INFO - 'MOZ_NO_REMOTE': '1', 19:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:31:01 INFO - 'NO_EM_RESTART': '1', 19:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:01 INFO - 'PWD': '/builds/slave/test', 19:31:01 INFO - 'SHELL': '/bin/bash', 19:31:01 INFO - 'SHLVL': '1', 19:31:01 INFO - 'TERM': 'linux', 19:31:01 INFO - 'TMOUT': '86400', 19:31:01 INFO - 'USER': 'cltbld', 19:31:01 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:01 INFO - '_': '/tools/buildbot/bin/python'} 19:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:31:01 INFO - Running setup.py (path:/tmp/pip-BCpvnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:31:01 INFO - Running setup.py (path:/tmp/pip-xyQj2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:31:01 INFO - Running setup.py (path:/tmp/pip-IUzUfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:31:01 INFO - Running setup.py (path:/tmp/pip-Bj1Z48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:31:01 INFO - Running setup.py (path:/tmp/pip-RsiIdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:31:01 INFO - Running setup.py (path:/tmp/pip-dBc64U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:31:01 INFO - Running setup.py (path:/tmp/pip-AbZRg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:31:01 INFO - Running setup.py (path:/tmp/pip-sR01gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:31:01 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)) 19:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:31:01 INFO - Running setup.py (path:/tmp/pip-K9TsWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:31:02 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:31:02 INFO - Running setup.py (path:/tmp/pip-7FToI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:31:02 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:31:02 INFO - Running setup.py (path:/tmp/pip-Ffxm9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:31:02 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:31:02 INFO - Running setup.py (path:/tmp/pip-gh_Ju0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:31:02 INFO - Running setup.py (path:/tmp/pip-iZ9Lps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:31:02 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:31:02 INFO - Running setup.py (path:/tmp/pip-MYHzVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:31:02 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:31:02 INFO - Running setup.py (path:/tmp/pip-vO9YuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:31:02 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:31:02 INFO - Running setup.py (path:/tmp/pip-lyWwu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 19:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:31:02 INFO - Running setup.py (path:/tmp/pip-m_l77P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:31:02 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)) 19:31:02 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)) 19:31:02 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)) 19:31:02 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)) 19:31:02 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)) 19:31:02 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)) 19:31:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:31:02 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 19:31:02 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 19:31:02 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 19:31:02 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 19:31:03 INFO - Downloading blessings-1.6.tar.gz 19:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:31:03 INFO - Installing collected packages: blessings 19:31:03 INFO - Running setup.py install for blessings 19:31:03 INFO - Successfully installed blessings 19:31:03 INFO - Cleaning up... 19:31:03 INFO - Return code: 0 19:31:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:31:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:31:04 INFO - Reading from file tmpfile_stdout 19:31:04 INFO - Current package versions: 19:31:04 INFO - argparse == 1.2.1 19:31:04 INFO - blessings == 1.6 19:31:04 INFO - blobuploader == 1.2.4 19:31:04 INFO - docopt == 0.6.1 19:31:04 INFO - functools32 == 3.2.3-2 19:31:04 INFO - jsonschema == 2.5.1 19:31:04 INFO - manifestparser == 1.1 19:31:04 INFO - mozInstall == 1.12 19:31:04 INFO - mozcrash == 1.0 19:31:04 INFO - mozdebug == 0.1 19:31:04 INFO - mozdevice == 0.49 19:31:04 INFO - mozfile == 1.2 19:31:04 INFO - mozhttpd == 0.7 19:31:04 INFO - mozinfo == 0.9 19:31:04 INFO - mozleak == 0.1 19:31:04 INFO - mozlog == 3.4 19:31:04 INFO - moznetwork == 0.27 19:31:04 INFO - mozprocess == 0.25 19:31:04 INFO - mozprofile == 0.28 19:31:04 INFO - mozrunner == 6.13 19:31:04 INFO - mozscreenshot == 0.1 19:31:04 INFO - mozsystemmonitor == 0.3 19:31:04 INFO - moztest == 0.8 19:31:04 INFO - mozversion == 1.4 19:31:04 INFO - psutil == 3.1.1 19:31:04 INFO - requests == 1.2.3 19:31:04 INFO - wsgiref == 0.1.2 19:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:31:04 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:31:04 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:31:04 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:31:04 INFO - Using env: {'DISPLAY': ':0', 19:31:04 INFO - 'HOME': '/home/cltbld', 19:31:04 INFO - 'LANG': 'en_US.UTF-8', 19:31:04 INFO - 'LANGUAGE': 'en_US:en', 19:31:04 INFO - 'LOGNAME': 'cltbld', 19:31:04 INFO - 'MAIL': '/var/mail/cltbld', 19:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:04 INFO - 'MOZ_NO_REMOTE': '1', 19:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:31:04 INFO - 'NO_EM_RESTART': '1', 19:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:04 INFO - 'PWD': '/builds/slave/test', 19:31:04 INFO - 'SHELL': '/bin/bash', 19:31:04 INFO - 'SHLVL': '1', 19:31:04 INFO - 'TERM': 'linux', 19:31:04 INFO - 'TMOUT': '86400', 19:31:04 INFO - 'USER': 'cltbld', 19:31:04 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:04 INFO - '_': '/tools/buildbot/bin/python'} 19:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 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)) 19:31:04 INFO - Cleaning up... 19:31:04 INFO - Return code: 0 19:31:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:31:04 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/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 0xde81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe26a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', '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 19:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:31:04 INFO - Using env: {'DISPLAY': ':0', 19:31:04 INFO - 'HOME': '/home/cltbld', 19:31:04 INFO - 'LANG': 'en_US.UTF-8', 19:31:04 INFO - 'LANGUAGE': 'en_US:en', 19:31:04 INFO - 'LOGNAME': 'cltbld', 19:31:04 INFO - 'MAIL': '/var/mail/cltbld', 19:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:04 INFO - 'MOZ_NO_REMOTE': '1', 19:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:31:04 INFO - 'NO_EM_RESTART': '1', 19:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:04 INFO - 'PWD': '/builds/slave/test', 19:31:04 INFO - 'SHELL': '/bin/bash', 19:31:04 INFO - 'SHLVL': '1', 19:31:04 INFO - 'TERM': 'linux', 19:31:04 INFO - 'TMOUT': '86400', 19:31:04 INFO - 'USER': 'cltbld', 19:31:04 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:04 INFO - '_': '/tools/buildbot/bin/python'} 19:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:31:04 INFO - Cleaning up... 19:31:04 INFO - Return code: 0 19:31:04 INFO - Running post-action listener: _resource_record_post_action 19:31:04 INFO - Running post-action listener: _start_resource_monitoring 19:31:04 INFO - Starting resource monitoring. 19:31:04 INFO - [mozharness: 2017-05-12 02:31:04.658209Z] Finished create-virtualenv step (success) 19:31:04 INFO - [mozharness: 2017-05-12 02:31:04.658730Z] Running install step. 19:31:04 INFO - Running pre-action listener: _resource_record_pre_action 19:31:04 INFO - Running main action method: install 19:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:31:04 INFO - Reading from file tmpfile_stdout 19:31:04 INFO - Detecting whether we're running mozinstall >=1.0... 19:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:31:04 INFO - Reading from file tmpfile_stdout 19:31:04 INFO - Output received: 19:31:04 INFO - Usage: mozinstall [options] installer 19:31:04 INFO - Options: 19:31:04 INFO - -h, --help show this help message and exit 19:31:04 INFO - -d DEST, --destination=DEST 19:31:04 INFO - Directory to install application into. [default: 19:31:04 INFO - "/builds/slave/test"] 19:31:04 INFO - --app=APP Application being installed. [default: firefox] 19:31:04 INFO - mkdir: /builds/slave/test/build/application 19:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:31:18 INFO - Reading from file tmpfile_stdout 19:31:18 INFO - Output received: 19:31:18 INFO - /builds/slave/test/build/application/firefox/firefox 19:31:18 INFO - Running post-action listener: _resource_record_post_action 19:31:18 INFO - [mozharness: 2017-05-12 02:31:18.977813Z] Finished install step (success) 19:31:18 INFO - [mozharness: 2017-05-12 02:31:18.978057Z] Running run-tests step. 19:31:18 INFO - Running pre-action listener: _resource_record_pre_action 19:31:18 INFO - Running main action method: run_tests 19:31:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:31:18 INFO - Python 2.7.3 19:31:18 INFO - Return code: 0 19:31:18 INFO - grabbing minidump binary from tooltool 19:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 19:31:18 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 0x1083400>, '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 0x10843b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:31:18 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 19:31:18 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 19:31:18 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 19:31:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:31:19 INFO - Return code: 0 19:31:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:31:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:31:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:31:19 INFO - ENV: RUST_BACKTRACE is now 1 19:31:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:31:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:31:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 19:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 19:31:19 INFO - Using env: {'DISPLAY': ':0', 19:31:19 INFO - 'HOME': '/home/cltbld', 19:31:19 INFO - 'LANG': 'en_US.UTF-8', 19:31:19 INFO - 'LANGUAGE': 'en_US:en', 19:31:19 INFO - 'LOGNAME': 'cltbld', 19:31:19 INFO - 'MAIL': '/var/mail/cltbld', 19:31:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:31:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:19 INFO - 'MOZ_NO_REMOTE': '1', 19:31:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:31:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:31:19 INFO - 'NO_EM_RESTART': '1', 19:31:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:19 INFO - 'PWD': '/builds/slave/test', 19:31:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:31:19 INFO - 'RUST_BACKTRACE': '1', 19:31:19 INFO - 'SHELL': '/bin/bash', 19:31:19 INFO - 'SHLVL': '1', 19:31:19 INFO - 'TERM': 'linux', 19:31:19 INFO - 'TMOUT': '86400', 19:31:19 INFO - 'USER': 'cltbld', 19:31:19 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673', 19:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:19 INFO - '_': '/tools/buildbot/bin/python'} 19:31:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 19:31:19 INFO - mozversion application_buildid: 20170512014011 19:31:19 INFO - mozversion application_changeset: 4e354dd1049072b47680a37eeed391c1f2177dbf 19:31:19 INFO - mozversion application_display_name: Firefox 19:31:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:31:19 INFO - mozversion application_name: Firefox 19:31:19 INFO - mozversion application_remotingname: firefox 19:31:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:31:19 INFO - mozversion application_vendor: Mozilla 19:31:19 INFO - mozversion application_version: 54.0 19:31:19 INFO - mozversion platform_buildid: 20170512014011 19:31:19 INFO - mozversion platform_changeset: 4e354dd1049072b47680a37eeed391c1f2177dbf 19:31:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:31:19 INFO - mozversion platform_version: 54.0 19:31:19 INFO - using testdate: 1494556279 19:31:19 INFO - actual date: 1494556279 19:31:19 INFO - starting webserver on 'localhost:40062' 19:31:19 INFO - SUITE-START | Running 2 tests 19:31:19 INFO - TEST-START | dromaeo_css 19:31:19 INFO - operating with platform_type : linux_ 19:31:19 INFO - Initialising browser for dromaeo_css test... 19:31:19 INFO - TEST-INFO | started process 2320 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYHhj1F/profile http://localhost:40062/getInfo.html) 19:31:24 INFO - PROCESS | 2320 | __metrics Screen width/height:1600/1200 19:31:24 INFO - PROCESS | 2320 | colorDepth:24 19:31:24 INFO - PROCESS | 2320 | Browser inner width/height: 1024/697 19:31:24 INFO - PROCESS | 2320 | __metrics 19:31:25 INFO - PROCESS | 2320 | 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" 19:31:25 INFO - PROCESS | 2320 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:31:26 INFO - TEST-INFO | 2320: exit 0 19:31:26 INFO - Browser initialized. 19:31:26 INFO - Running cycle 1/1 for dromaeo_css test... 19:31:26 INFO - TEST-INFO | started process 2484 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYHhj1F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:31:37 INFO - PROCESS | 2484 | RSS: Main: 141463552 19:31:37 INFO - PROCESS | 2484 | 19:34:39 INFO - PROCESS | 2484 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40062/tests/dromaeo/cssquery-ext.html) 19:34:40 INFO - PROCESS | 2484 | RSS: Main: 269701120 19:34:40 INFO - PROCESS | 2484 | 19:37:42 INFO - PROCESS | 2484 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-ext.html (next: http://localhost:40062/tests/dromaeo/cssquery-jquery.html) 19:37:43 INFO - PROCESS | 2484 | RSS: Main: 223969280 19:37:43 INFO - PROCESS | 2484 | 19:40:45 INFO - PROCESS | 2484 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40062/tests/dromaeo/cssquery-mootools.html) 19:40:46 INFO - PROCESS | 2484 | RSS: Main: 175792128 19:40:46 INFO - PROCESS | 2484 | 19:43:47 INFO - PROCESS | 2484 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40062/tests/dromaeo/cssquery-prototype.html) 19:43:48 INFO - PROCESS | 2484 | RSS: Main: 171880448 19:43:48 INFO - PROCESS | 2484 | 19:46:51 INFO - PROCESS | 2484 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40062/tests/dromaeo/cssquery-yui.html) 19:46:51 INFO - PROCESS | 2484 | RSS: Main: 174788608 19:46:51 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-yui.html (next: http://localhost:40062/tests/dromaeo/cssquery-dojo.html) 19:49:54 INFO - PROCESS | 2484 | RSS: Main: 266850304 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | __start_tp_report 19:49:54 INFO - PROCESS | 2484 | _x_x_mozilla_page_load 19:49:54 INFO - PROCESS | 2484 | _x_x_mozilla_page_load_details 19:49:54 INFO - PROCESS | 2484 | |i|pagename|runs| 19:49:54 INFO - PROCESS | 2484 | |0;dojo.html;3424.9315013699725;3470.9652903470956;3484.425069863471;3501.0547152268873;3520.0671821967167;5690.658560486374;5787.102999035145;5798.7100644967795;5807.854803629906;5825.417458254178;5812.099124635678;5870.530357571395;5871.471567558919;5871.941280587203;5872.236609240804;5185.688858668483;5192.870178245536;5209.7134657593815;5262.684238945656;5273.1826566882;4929.309896614477;4944.752762361885;5284.5772338212855;5290.867728306804;5301.1253143231515;5085.821996230133;5122.92315615266;5161.483851614846;5184.1705327147665;5185.611079169077;5227.215917612389;5228.973855130719;5245.632805703603;5250.9474905250845;5278.4721527846905;4977.626677999128;5121.180611102243;5188.403333616645;5224.555912747421;5236.528712415863;5612.607117501777;5721.742521586539;5808.767649294024;5814.273215848019;5818.883622327529;6185.907211391833;6194.597351172204;6231.844203894939;6240.032794916795;6242.032484964838;6262.906056409158;6269;6290.654014029226;6291.433770960635;6299.905501417483;5615;5642.943570564324;5742.397048309909;5786.681732504752;5794.159846822272;6335.809925702236;6351.2696039956;6361.395667411589;6362.45919096882;6367.204099487564;6068.4538391544975;6119.4798442132915;6128.63228206309;6132.7546898124465;6135.07973803933;5998.310194327708;6032.125341825411;6092.908606370908;6248.031555108965;6253.937460625336;6142.508599312045;6151.692415379213;6166.506679465632;6167.84580385494;6169.96915015422;5952.077427998667;5997.940020599825;5998.940010599926;6126;6149.846253843601;4623.768811559476;4698.906021879543;4700.153972285021;4702.764861756898;4764.8332308369045;4105.58944105593;4183.435236243129;4194.412782210493;4215.262329092397;4228.61942425177;5833.79581714638;5865.912011319833;5902.527797776168;5969.820905372846;6004.39956004396;6261.154744109578;6264.404881536247;6268.843278918063;6271.749130034838;6280.654563998933;5901.097369102381;5933.080372542263;5937.10943358491;5941.70291485424;5950.19672344228;5047.570956468668;5076;5147.794088236504;5192.402873669501;5281.0758117329315;6444.355564443518;6477.093206951031;6487.481001519867;6496.1230233919705;6499.707513162014;6178.104174894705;6179.0422484514975;6230;6230.937690623217;6235.314115447207;6200.720967556561;6227;6384.968075159781;6499.805005849832;6507.251666058274;6111.19673459643;6133.028644417115;6171.753129874754;6175.166352542529;6179.969100154471;6291.402316779899;6294.3076261612105;6296.401841825019;6300.779472718433;6311.400416960565;5855.94144058554;6067.544934130046;6127.325994140552;6133.969330153321;6138;5928.555358348228;5940.8811823764145;5972.940270597239;6098.695065246809;6125.203723515914;6046.455818976313;6049.546284028627;6060.636361818305;6062.484688801501;6063.5452341073715;5857.7949771757785;5883.146943693055;5911.881762364643;5916.467517923407;6104.725287361991;5795.594308398372;5824;5890.676012819336;5909.231799866162;5911.261092363455 19:49:54 INFO - PROCESS | 2484 | |1;ext.html;763.6792547130204;818.8034871630808;842.0526907229367;909.7498187998295;918.2562124679015;31729.36541269176;43544.782276088656;44552.10895782083;44560.108797824025;44601;44702.55297447024;44949.77525112378;45099.32351014738;45100.77449612755;45123;7838.843223135534;8008.719694810683;9211.217046551035;9344.159025687686;9347.719568412924;10510.421926794023;10767.138628909708;10791.19066070044;10795.83806242907;10799.460026998659;10847.67456976292;10876.401797901111;10890.183236257273;10890.836637450444;10891.782164356708;10785.838212426781;10818.459077046195;10825.350478971286;10829;10829.67510974672;10573.207009474243;10601.151907847354;10601.522931468102;10609.469526523642;10630.574777008911;43006.35490467645;43241.56758432407;43333.566664333266;43336.56663433388;43600;4285.335772955197;4322.783860806947;4336.8265269389185;4428.977855110736;4440.35614835847;30403.239919001982;30901.227469313224;31135.532967005514;31194.688053119404;32647.347053058806;4340.457442819647;4491.6855820092605;4506.076254367847;4557.407537020199;4580.587747102777;254958;256727;257034;257405;258459.9999999981;45193.54806451959;45499;45509;45575.088498230514;45585.316220256726;47036.52963470321;47131.764341178074;47180.292295615596;47239;47280.290795638095;14328.426862925575;14561.344739486749;14577.41690332396;14598.562043138723;14608.853911460961;11350.14873884462;11505.309681419141;13762.174269543859;13881.4447422104;13952.093113947763;13209.471621135239;13295.534656286984;13305.334733263298;13307.66730831737;13353.265570393527;5377.865553361197;5464.453554644583;5491.395946445968;5507.5593952483705;5567.387587365387;996.21299173652;1023.3450591621369;1025.9794804103876;1044.498640652491;1057.6298295596634;28293.57559636607;29604.703952960626;29712.108636740933;29946.25134371658;30170.09489715312;1078.8489611454402;1089.242976131581;1100.9009189173014;1105.1159072741934;1108.3294606762954;14966.925165374103;15628.2185890705;15752.133632650315;15864.286107125206;16089.195540222941;344369;345423;345977;346297;346985;57531;58128;58192.709036455395;58222;58235;1443.3360654099022;1473.911565306085;1499.7000599880068;1511.0178384050234;1569.6311366828786;28047.579286310312;29316.853415732785;29469;29658.703412965595;30226.54660180055;38313.808430957666;38486.2302753949;38514;38562;38592.61407386002;324591;326561;328492;328814;329560;25706.485870282864;26626.733732662426;29206.7079329204;30470.542941855445;31865.36269274555;38843;38883.611163887996;39125.80437097796;39160;39406;30559.847200763856;30739;30867.845660771553;31051.534226987063;31259.84370078135;30106.24734381658;30260.69739302579;30495;30830;31199.532007019458;34527;34956.650433496354;35063.64936350706;35225.11937201488;35351.29297413986;29336.706632933397;29345.413091737617;29984.400311994075;30036.249093771956;30110.849445752632;22362.329130126123;23411.882940585187;23505.882470587538;23819.166329178395;23911 19:49:54 INFO - PROCESS | 2484 | |2;jquery.html;461.5453778028642;464.54938709451864;469.32651644889586;478.70559605842396;487.00571161095314;20437.386878393652;22264.109435622555;22409.32772016838;22707.54584908301;22817.543649127027;22143.667844982298;22303.330900072982;22459.663105053398;22541;22939.655905161395;6598;6619.867602647944;6724.831879203035;6770.32296770324;6819.045333653267;6692.665366731669;6788.660566971632;6808.523403361766;7160.64196790161;7461.515001524911;7638.045244344457;7643.923560764405;7648.2351764823625;7652.502587331834;7660.693572257103;7524.736634217804;7549.849003019936;7584;7603.0496187976505;7608.961955190244;7307.744228951961;7343.339099481019;7380.667869945865;7382.446316526282;7388.9261107388775;25966.610500842315;26139.869300653376;26226.34434139143;26382;26409.207723768126;3135.372925414926;3141.27750617357;3282.2122690554334;3321.501774733785;3778.7732736035928;16351.100689462075;16351.672966540722;16360.67278654432;16423.42518011864;17037.829621703873;2273.454370950976;2456.9631455528183;2575.343287461704;2596.805239607018;2634.288742039658;106359.46820265926;107970;108224;108923;111454.44272778585;17769.378071767427;17905.7314140288;17993.7300940486;18032.729509057375;18541.81458185428;31183.220419489695;31192.220194494865;31346.373072538878;31660.683393166233;31682.465402767724;10705.785884282272;10765.30025548337;10775.730606734895;10788.622398216025;11035.282706624044;9133.406328588753;9172.312076594282;9235.676751313671;9431.386959847589;9437;8912.242459391024;9341.719748407559;9417.670381536613;9461;9504.857427138599;3932.9016774580864;3969.126792105732;3979.164375481181;4007.9119298316136;4070;959.3045042344245;968.2689569375077;969.60730903984;970.6263088710897;985.2561316206219;19409.4177174685;20651.69022464664;20853.478663033242;20985.47536311574;21143.471413214484;967.7999280891644;978.4520668425704;980.8822941247096;993.7068564773409;1004.3622299839654;11597.13021523389;11602.839716028499;11693.824592631117;11774.764504709858;11815.881841181477;621737;625907;648421;681937;682963;104308.47845760874;108328;109992;111611;112609;1310.5478609879576;1349.595121463557;1368.4115830193114;1377.7313423882247;1380.5375199308344;18471.53821154482;18726.906365468087;18913.810861891754;19012.714809277597;19186.904065479583;51519;51928.48071519387;52829.207561885836;52856.471435286694;53507.732461339;58399.956001539744;58585.70707146437;64846.35153648592;69996.65001674958;71156.64421677859;12397.876021240032;12412.875871241173;12550.560730374393;12684.112112152061;12750.48998040068;13467.32663366808;13620.65948351299;13648.727025459633;13723.45106195741;13968.58094257174;12099;12193.390330483619;12278.693032674255;12325.506979721069;12366.258024518558;11809.527618895148;11825.113116516124;12027.338496382434;12087.456064477296;12214.389280536116;11244;11799.587014454453;12555.121141520005;12622.990160787298;12765.553205637758;12409;12409.751804964031;12523.37383130858;12525.311107889154;12534.623961281177;12167.817482737943;12205.267683938991;12212.816807747713;12336.81494777597;12426.254424734545 19:49:54 INFO - PROCESS | 2484 | |3;mootools.html;1446.3708286895194;1527.6715506166172;1557.8130624325079;1591.4191320168143;1605.277625068719;9524.380915240505;11205.495752691128;11477.368744719037;11482.253653512516;11484.081273498132;11137.220394572383;11262.831057534124;11265.887341126587;11266.155038372113;11288.492017859195;2036.4806974221553;2106.294391378882;2451.215611004475;2455.5579995600783;2497.08856267462;2532.5947848344267;2586.780123689489;2587.2109006752935;2587.4178309880313;2588.79289656827;2451.5587194305017;2470.3700556358194;2475.591527397977;2485.9378515537073;2489.4523204894895;2426.2236084452893;2448.6816713827266;2453.153661986612;2458.8893499792553;2460.987695061522;2375.1330764270915;2383.3564937466786;2392.9521409571885;2397.6643269942224;2411.5900296949562;11784.941075294655;11792.587259445878;11874.287542747463;11876.881231187665;11948.342841143734;5844.561657875676;5997.130416089686;6014.30835453924;6084.087386891957;6120.663363515005;3423.5549378580627;3459.9481007784902;3491.2493813830147;3596.514470546469;3648.3980143276326;1331.5406184866206;1369.1442848219863;1372.540199033325;1377.1392879450343;1401.2993503248376;17417.47747567575;17494.212760425686;17651.205695743723;17652.91173544137;17685.469435916944;4807.471178170398;4845.903081938412;4934.1611925972675;4938.185199442124;5020.949790502122;14330.355134018786;14337.283135843167;14371.856281437262;14373.425062997572;14375.640608984859;6064.181335519736;6070.969645151834;6079.148919151322;6088.908666370008;6090.025595904634;4450.488193857748;5226.686398816083;5414.35027796667;5462.617616766788;5530.944690553123;4290.871273861789;4379.102284031829;4398.670099742468;4442.178197033559;4447.955520444819;1161.7095726068483;1179.7345597240724;1195.2529668956902;1196.3659260591726;1200.5497938273147;888.6090120347027;895.5164211325941;898.5417437106992;901.7204666553389;921.7465197070859;6625.039369291522;6666.566673166229;6749.257581666013;6790.388865002174;6948.200956890022;1084.8481212630238;1111.5276007696696;1114.3285072391413;1121.971950701239;1124.7188202949262;4388.166248412791;4436.423264975597;4437.2234858899565;4503.437070366204;4513.954860451354;14992;15055;15211.771823422658;15445;15592.376304947902;15193.544193674208;15230.923845380703;15346.462873799586;15382.461613843461;15502.30239639219;10908.181886358396;10987.340759554452;11005;11082.889171108185;11145.72135696614;6801.387875091265;6852.897206542006;6861.382475577222;6871.415929646036;6917.13535808024;13237.933810331271;13342.19946803195;13651.38568764388;13722.588322350346;13752.449902003807;13530.120542164728;13625.727485450434;13653.385597647932;13736.381862815997;13828.239446830521;8064.596770161351;8273.46222495536;9404;9414.482203478874;9583.712488625353;11444.370559618968;11494.367809770209;11634.88365116338;11640.068794496588;11730.765384692088;9521.714348569554;9583.233341332538;9698.56356463975;9758.365706229357;9907.058829411195;9338.906610933804;9390.389624674373;9426.434413935185;9533.427994320364;9748;8446.535440550828;8572.971243450826;8574.056853745959;8580.270676992526;8580.485170889517;6194.907076393768;6259.467945224706;6260.937390626035;6288.937110628835;6292.811215663537;6284.465820405317;6316;6364.777232797015;6446.935530644633;6541.70562324706 19:49:54 INFO - PROCESS | 2484 | |4;prototype.html;427.4251132227154;428.1608048225478;449.563922994695;452.27861560810516;453.45358842594675;5229.50319719627;5314.627976041674;5360.24956506089;5366.812161574346;5398.0310829044065;5237.214417837326;5246.868828279295;5275.630705850592;5283.471652834714;5338.946610533893;4246.278132717438;4330.350447432878;4332.870013899588;4338.566143385668;4350.847720329789;4336.457942757155;4336.696431249814;4338.97830510847;4375.278079116942;4409.58108979648;4378.956210437903;4431.601155895984;4436.622887054604;4455.331700244957;4474.642028637718;4318.784060796947;4338.924285522133;4344.6089851913475;4345.608895199447;4356.673249506268;4092.386142078712;4100.159467309194;4100.995256162229;4109.054917368992;4121.155163191568;5110;5113.181890897438;5156.767945442464;5169.508896654812;5181.5854731621375;5004.724740139291;5069.214271787879;5113.590912726973;5128.820491282787;5197.506236907488;5473.397926228112;5474.37044739856;5494.917576236319;5510.862228444281;5540.002799496089;4613.584777370019;4616.907661846744;4620.745858977755;4627.968680514268;4629.768511574442;5430.8913821723345;5539.446055394509;5598.44015598441;5667.376588575254;5694.629849059798;4547.499775024745;4561.338605902126;4598.8620341389815;4743.478217396084;4744.715317080986;4818.4217893852965;4827.637927155477;4851.442084160296;4856.975715121402;4860.198067318853;4881.949581175914;5066.594672426197;5128.820491282787;5136.743162841843;5170.40540337866;5437.564994800486;5438.891222175614;5487.42382049887;5497.615166938276;5554.250176226158;1642.4744081893919;1648.8433598808094;1651.4962936304541;1687.2744719770617;1689.6705142497094;1233.582256666898;1286.4018231522386;1286.4339690536135;1290.7931084435895;1310.351376068833;5198.948010519922;5444.265024221759;5466.508014278655;5479.205515200274;5492.752826122754;5614.9157762633595;5654.095344744821;5674.914876276859;5700.25896633435;5704.657720536781;5429.077056900336;5443.836684899459;5446.739603064763;5448.700321482356;5483.232347471358;2844.615976843141;2967.5697023931407;2993.664941345754;2995.0864986179427;3015.7888947773445;5863.648181109147;5870.207521984477;5889.9692553802915;5919.585629005928;5923.733431995571;5792.466595376817;5802.47777700009;5827.300723913073;5845.88308233833;5849.53203743708;5550.639208451437;5822.534197264209;5892.322382925932;5953.916129354691;5966.5835336688715;5657.60396772222;5685.77256909719;5715.913976344481;5726.255586773693;5779.508741756829;5485.01269771445;5522.088855338824;5554.50009499147;5689.800856969986;5714.628549144292;5462.644928079662;5699.316082070012;5816.1857339973285;5852;5853.912191317048;4710.364100846342;4803.543663351976;4807.6394270429155;4820.975895120642;4852.587530059985;5504;5643.26637537134;5745.9425405745405;5759.625624334405;5810.6513609183585;5513.669179849222;5541.168824676331;5556.555475562026;5587.972060139673;5616.971915140398;5461.699606521679;5514.641548299508;5540.529055030368;5547.1679248111495;5559.499645031967;5398.78404863801;5438.211459338453;5493.176023596492;5495.203195536705;5504.843982763505;5443.346798384219;5490.972545137249;5513.2005859151;5515.290260019407;5581;5257.5005374489365;5408.242846001642;5459.181122831607;5509.338879334506;5512.91730624033 19:49:54 INFO - PROCESS | 2484 | |5;yui.html;875.6234819027819;912.7672443526902;923.6997975657913;933.3140141995632;956.7273327101775;17746.911265443672;18828.811711882878;19511.512212194702;19670.91809950452;19827;16970.272295778166;17684.292628294887;17781.46655600331;17949.820501794977;17974;883.8188171424858;903.6972614174259;905.9229965452945;907.7549061753323;909.2271569166222;883.33308352194;906.5965645287849;907.0340087806497;913.8583519554479;915.9038300978401;962.3215632978734;969.4667932637057;969.8011907558933;970.4710932541782;970.6651205334149;960.3085778239656;961.423146112334;962.4706411473661;964.0793042644258;964.5418426247546;702.6170736948325;703.0508813102322;703.0649236515474;703.085988215318;703.3318347569829;16750.58123546921;16910.239039243264;17105.828941710548;19425.320113795948;19602.313919012908;16536.75194872078;16754.41359552422;16849.66300673992;16915.83084169155;17267.654646907118;13806.840021765123;14065.929670351685;14267.357968891425;14356.784648230181;14406.783898241534;4131.958680413188;4184.748915065105;4212.073343864345;4214.178235244129;4358.978205108986;88602;88694;89184;91954;92501.53749231211;11726;11789.410529473493;11795.35125568085;11868.762624747456;11903;21625.879267970544;21694.34916952494;21734.130634774432;21826;22023;8776.078511756301;8817.426867253607;8964;9031.412958157698;9054.456732596065;5032.798688052437;5052.9242061369105;5141.562967147759;5210.166373380241;5309.123994540934;3735.3463143950244;3809.085819403323;3826.770393776382;3829.119302560371;3833.7316387852584;610.1457958857635;617.2038070888593;621.2358798677652;627.0218459203656;633.2654121219456;642.8296501427126;650.1580453312971;669.668514085521;677;681.4173881331391;10289.94855025735;11453.14101442395;11803.822942655866;11897;12372.44324005422;803.6022169026369;807.3944541593804;819.2954059508937;823.6087858267275;825.2160447574828;5052.469490703424;5152.92270615941;5198.532132108128;5199.168133098686;5204.661696989683;62580;66919;69588;72028.2797172032;85204.57397712972;67517.66241168861;68625;68638.31361686319;69021.3097869025;69957.3004269961;61361.693191533755;62630.37369626336;62673.68663156746;63058.684706576176;63438.365616343246;11199.88800112021;11322.660320190407;11388.316700997966;11692.649220523397;11708.648740537797;36666.26667466582;39804;40536.59463405328;40830;41767.58232417637;49296.50703492919;49312.260316096006;49365.50634493609;49488.25767613417;49917;13657.180569165881;13691.452341906212;13779.586612401645;13956.511522096678;14000.719985600435;14245.21651309188;14853.82620425978;14893.180875051976;16270.755938661167;16376.181190940168;15636.530904072895;15975.760363594787;16061.67876642484;16324.510264692079;16965;15736.92131539335;15902.522924312289;16268.918655406647;16516.75224871604;17195.312187512358;11811.704707382385;15372.538823835303;15932.840671593136;16330;16351;10023.899761002296;10088.848667270144;10362.326448780805;10631.681049568526;10666;11578.189526733384;11652.30086194831;11848.466818992994;11875;11925.224860384047 19:49:54 INFO - PROCESS | 2484 | __end_tp_report 19:49:54 INFO - PROCESS | 2484 | __start_cc_report 19:49:54 INFO - PROCESS | 2484 | _x_x_mozilla_cycle_collect,1264 19:49:54 INFO - PROCESS | 2484 | __end_cc_report 19:49:54 INFO - PROCESS | 2484 | __startTimestamp1494557394333__endTimestamp 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | ------- Summary: start ------- 19:49:54 INFO - PROCESS | 2484 | Number of tests: 6 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | [#0] dojo.html Cycles:165 Average:5739.69 Median:5940.88 stddev:663.94 (11.2%) stddev-sans-first:640.66 19:49:54 INFO - PROCESS | 2484 | Values: 3424.9 3471.0 3484.4 3501.1 3520.1 5690.7 5787.1 5798.7 5807.9 5825.4 5812.1 5870.5 5871.5 5871.9 5872.2 5185.7 5192.9 5209.7 5262.7 5273.2 4929.3 4944.8 5284.6 5290.9 5301.1 5085.8 5122.9 5161.5 5184.2 5185.6 5227.2 5229.0 5245.6 5250.9 5278.5 4977.6 5121.2 5188.4 5224.6 5236.5 5612.6 5721.7 5808.8 5814.3 5818.9 6185.9 6194.6 6231.8 6240.0 6242.0 6262.9 6269.0 6290.7 6291.4 6299.9 5615.0 5642.9 5742.4 5786.7 5794.2 6335.8 6351.3 6361.4 6362.5 6367.2 6068.5 6119.5 6128.6 6132.8 6135.1 5998.3 6032.1 6092.9 6248.0 6253.9 6142.5 6151.7 6166.5 6167.8 6170.0 5952.1 5997.9 5998.9 6126.0 6149.8 4623.8 4698.9 4700.2 4702.8 4764.8 4105.6 4183.4 4194.4 4215.3 4228.6 5833.8 5865.9 5902.5 5969.8 6004.4 6261.2 6264.4 6268.8 6271.7 6280.7 5901.1 5933.1 5937.1 5941.7 5950.2 5047.6 5076.0 5147.8 5192.4 5281.1 6444.4 6477.1 6487.5 6496.1 6499.7 6178.1 6179.0 6230.0 6230.9 6235.3 6200.7 6227.0 6385.0 6499.8 6507.3 6111.2 6133.0 6171.8 6175.2 6180.0 6291.4 6294.3 6296.4 6300.8 6311.4 5855.9 6067.5 6127.3 6134.0 6138.0 5928.6 5940.9 5972.9 6098.7 6125.2 6046.5 6049.5 6060.6 6062.5 6063.5 5857.8 5883.1 5911.9 5916.5 6104.7 5795.6 5824.0 5890.7 5909.2 5911.3 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | [#1] ext.html Cycles:180 Average:46785.41 Median:30884.54 stddev:81873.80 (265.1%) stddev-sans-first:82030.56 19:49:54 INFO - PROCESS | 2484 | Values: 763.7 818.8 842.1 909.7 918.3 31729.4 43544.8 44552.1 44560.1 44601.0 44702.6 44949.8 45099.3 45100.8 45123.0 7838.8 8008.7 9211.2 9344.2 9347.7 10510.4 10767.1 10791.2 10795.8 10799.5 10847.7 10876.4 10890.2 10890.8 10891.8 10785.8 10818.5 10825.4 10829.0 10829.7 10573.2 10601.2 10601.5 10609.5 10630.6 43006.4 43241.6 43333.6 43336.6 43600.0 4285.3 4322.8 4336.8 4429.0 4440.4 30403.2 30901.2 31135.5 31194.7 32647.3 4340.5 4491.7 4506.1 4557.4 4580.6 254958.0 256727.0 257034.0 257405.0 258460.0 45193.5 45499.0 45509.0 45575.1 45585.3 47036.5 47131.8 47180.3 47239.0 47280.3 14328.4 14561.3 14577.4 14598.6 14608.9 11350.1 11505.3 13762.2 13881.4 13952.1 13209.5 13295.5 13305.3 13307.7 13353.3 5377.9 5464.5 5491.4 5507.6 5567.4 996.2 1023.3 1026.0 1044.5 1057.6 28293.6 29604.7 29712.1 29946.3 30170.1 1078.8 1089.2 1100.9 1105.1 1108.3 14966.9 15628.2 15752.1 15864.3 16089.2 344369.0 345423.0 345977.0 346297.0 346985.0 57531.0 58128.0 58192.7 58222.0 58235.0 1443.3 1473.9 1499.7 1511.0 1569.6 28047.6 29316.9 29469.0 29658.7 30226.5 38313.8 38486.2 38514.0 38562.0 38592.6 324591.0 326561.0 328492.0 328814.0 329560.0 25706.5 26626.7 29206.7 30470.5 31865.4 38843.0 38883.6 39125.8 39160.0 39406.0 30559.8 30739.0 30867.8 31051.5 31259.8 30106.2 30260.7 30495.0 30830.0 31199.5 34527.0 34956.7 35063.6 35225.1 35351.3 29336.7 29345.4 29984.4 30036.2 30110.8 22362.3 23411.9 23505.9 23819.2 23911.0 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | [#2] jquery.html Cycles:180 Average:37277.45 Median:22878.60 stddev:107357.91 (469.3%) stddev-sans-first:107623.48 19:49:54 INFO - PROCESS | 2484 | Values: 461.5 464.5 469.3 478.7 487.0 20437.4 22264.1 22409.3 22707.5 22817.5 22143.7 22303.3 22459.7 22541.0 22939.7 6598.0 6619.9 6724.8 6770.3 6819.0 6692.7 6788.7 6808.5 7160.6 7461.5 7638.0 7643.9 7648.2 7652.5 7660.7 7524.7 7549.8 7584.0 7603.0 7609.0 7307.7 7343.3 7380.7 7382.4 7388.9 25966.6 26139.9 26226.3 26382.0 26409.2 3135.4 3141.3 3282.2 3321.5 3778.8 16351.1 16351.7 16360.7 16423.4 17037.8 2273.5 2457.0 2575.3 2596.8 2634.3 106359.5 107970.0 108224.0 108923.0 111454.4 17769.4 17905.7 17993.7 18032.7 18541.8 31183.2 31192.2 31346.4 31660.7 31682.5 10705.8 10765.3 10775.7 10788.6 11035.3 9133.4 9172.3 9235.7 9431.4 9437.0 8912.2 9341.7 9417.7 9461.0 9504.9 3932.9 3969.1 3979.2 4007.9 4070.0 959.3 968.3 969.6 970.6 985.3 19409.4 20651.7 20853.5 20985.5 21143.5 967.8 978.5 980.9 993.7 1004.4 11597.1 11602.8 11693.8 11774.8 11815.9 621737.0 625907.0 648421.0 681937.0 682963.0 104308.5 108328.0 109992.0 111611.0 112609.0 1310.5 1349.6 1368.4 1377.7 1380.5 18471.5 18726.9 18913.8 19012.7 19186.9 51519.0 51928.5 52829.2 52856.5 53507.7 58400.0 58585.7 64846.4 69996.7 71156.6 12397.9 12412.9 12550.6 12684.1 12750.5 13467.3 13620.7 13648.7 13723.5 13968.6 12099.0 12193.4 12278.7 12325.5 12366.3 11809.5 11825.1 12027.3 12087.5 12214.4 11244.0 11799.6 12555.1 12623.0 12765.6 12409.0 12409.8 12523.4 12525.3 12534.6 12167.8 12205.3 12212.8 12336.8 12426.3 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | [#3] mootools.html Cycles:180 Average:7287.41 Median:2480.76 stddev:4759.91 (191.9%) stddev-sans-first:4753.03 19:49:54 INFO - PROCESS | 2484 | Values: 1446.4 1527.7 1557.8 1591.4 1605.3 9524.4 11205.5 11477.4 11482.3 11484.1 11137.2 11262.8 11265.9 11266.2 11288.5 2036.5 2106.3 2451.2 2455.6 2497.1 2532.6 2586.8 2587.2 2587.4 2588.8 2451.6 2470.4 2475.6 2485.9 2489.5 2426.2 2448.7 2453.2 2458.9 2461.0 2375.1 2383.4 2393.0 2397.7 2411.6 11784.9 11792.6 11874.3 11876.9 11948.3 5844.6 5997.1 6014.3 6084.1 6120.7 3423.6 3459.9 3491.2 3596.5 3648.4 1331.5 1369.1 1372.5 1377.1 1401.3 17417.5 17494.2 17651.2 17652.9 17685.5 4807.5 4845.9 4934.2 4938.2 5020.9 14330.4 14337.3 14371.9 14373.4 14375.6 6064.2 6071.0 6079.1 6088.9 6090.0 4450.5 5226.7 5414.4 5462.6 5530.9 4290.9 4379.1 4398.7 4442.2 4448.0 1161.7 1179.7 1195.3 1196.4 1200.5 888.6 895.5 898.5 901.7 921.7 6625.0 6666.6 6749.3 6790.4 6948.2 1084.8 1111.5 1114.3 1122.0 1124.7 4388.2 4436.4 4437.2 4503.4 4514.0 14992.0 15055.0 15211.8 15445.0 15592.4 15193.5 15230.9 15346.5 15382.5 15502.3 10908.2 10987.3 11005.0 11082.9 11145.7 6801.4 6852.9 6861.4 6871.4 6917.1 13237.9 13342.2 13651.4 13722.6 13752.4 13530.1 13625.7 13653.4 13736.4 13828.2 8064.6 8273.5 9404.0 9414.5 9583.7 11444.4 11494.4 11634.9 11640.1 11730.8 9521.7 9583.2 9698.6 9758.4 9907.1 9338.9 9390.4 9426.4 9533.4 9748.0 8446.5 8573.0 8574.1 8580.3 8580.5 6194.9 6259.5 6260.9 6288.9 6292.8 6284.5 6316.0 6364.8 6446.9 6541.7 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | [#4] prototype.html Cycles:180 Average:4823.25 Median:5382.42 stddev:1282.19 (23.8%) stddev-sans-first:1242.62 19:49:54 INFO - PROCESS | 2484 | Values: 427.4 428.2 449.6 452.3 453.5 5229.5 5314.6 5360.2 5366.8 5398.0 5237.2 5246.9 5275.6 5283.5 5338.9 4246.3 4330.4 4332.9 4338.6 4350.8 4336.5 4336.7 4339.0 4375.3 4409.6 4379.0 4431.6 4436.6 4455.3 4474.6 4318.8 4338.9 4344.6 4345.6 4356.7 4092.4 4100.2 4101.0 4109.1 4121.2 5110.0 5113.2 5156.8 5169.5 5181.6 5004.7 5069.2 5113.6 5128.8 5197.5 5473.4 5474.4 5494.9 5510.9 5540.0 4613.6 4616.9 4620.7 4628.0 4629.8 5430.9 5539.4 5598.4 5667.4 5694.6 4547.5 4561.3 4598.9 4743.5 4744.7 4818.4 4827.6 4851.4 4857.0 4860.2 4881.9 5066.6 5128.8 5136.7 5170.4 5437.6 5438.9 5487.4 5497.6 5554.3 1642.5 1648.8 1651.5 1687.3 1689.7 1233.6 1286.4 1286.4 1290.8 1310.4 5198.9 5444.3 5466.5 5479.2 5492.8 5614.9 5654.1 5674.9 5700.3 5704.7 5429.1 5443.8 5446.7 5448.7 5483.2 2844.6 2967.6 2993.7 2995.1 3015.8 5863.6 5870.2 5890.0 5919.6 5923.7 5792.5 5802.5 5827.3 5845.9 5849.5 5550.6 5822.5 5892.3 5953.9 5966.6 5657.6 5685.8 5715.9 5726.3 5779.5 5485.0 5522.1 5554.5 5689.8 5714.6 5462.6 5699.3 5816.2 5852.0 5853.9 4710.4 4803.5 4807.6 4821.0 4852.6 5504.0 5643.3 5745.9 5759.6 5810.7 5513.7 5541.2 5556.6 5588.0 5617.0 5461.7 5514.6 5540.5 5547.2 5559.5 5398.8 5438.2 5493.2 5495.2 5504.8 5443.3 5491.0 5513.2 5515.3 5581.0 5257.5 5408.2 5459.2 5509.3 5512.9 19:49:54 INFO - PROCESS | 2484 | 19:49:54 INFO - PROCESS | 2484 | [#5] yui.html Cycles:180 Average:18286.97 Median:22976.17 stddev:22363.16 (97.3%) stddev-sans-first:22387.68 19:49:54 INFO - PROCESS | 2484 | Values: 875.6 912.8 923.7 933.3 956.7 17746.9 18828.8 19511.5 19670.9 19827.0 16970.3 17684.3 17781.5 17949.8 17974.0 883.8 903.7 905.9 907.8 909.2 883.3 906.6 907.0 913.9 915.9 962.3 969.5 969.8 970.5 970.7 960.3 961.4 962.5 964.1 964.5 702.6 703.1 703.1 703.1 703.3 16750.6 16910.2 17105.8 19425.3 19602.3 16536.8 16754.4 16849.7 16915.8 17267.7 13806.8 14065.9 14267.4 14356.8 14406.8 4132.0 4184.7 4212.1 4214.2 4359.0 88602.0 88694.0 89184.0 91954.0 92501.5 11726.0 11789.4 11795.4 11868.8 11903.0 21625.9 21694.3 21734.1 21826.0 22023.0 8776.1 8817.4 8964.0 9031.4 9054.5 5032.8 5052.9 5141.6 5210.2 5309.1 3735.3 3809.1 3826.8 3829.1 3833.7 610.1 617.2 621.2 627.0 633.3 642.8 650.2 669.7 677.0 681.4 10289.9 11453.1 11803.8 11897.0 12372.4 803.6 807.4 819.3 823.6 825.2 5052.5 5152.9 5198.5 5199.2 5204.7 62580.0 66919.0 69588.0 72028.3 85204.6 67517.7 68625.0 68638.3 69021.3 69957.3 61361.7 62630.4 62673.7 63058.7 63438.4 11199.9 11322.7 11388.3 11692.6 11708.6 36666.3 39804.0 40536.6 40830.0 41767.6 49296.5 49312.3 49365.5 49488.3 49917.0 13657.2 13691.5 13779.6 13956.5 14000.7 14245.2 14853.8 14893.2 16270.8 16376.2 15636.5 15975.8 16061.7 16324.5 16965.0 15736.9 15902.5 16268.9 16516.8 17195.3 11811.7 15372.5 15932.8 16330.0 16351.0 10023.9 10088.8 10362.3 10631.7 10666.0 11578.2 11652.3 11848.5 11875.0 11925.2 19:49:54 INFO - PROCESS | 2484 | -------- Summary: end -------- 19:49:54 INFO - PROCESS | 2484 | 19:49:55 INFO - TEST-INFO | 2484: exit 0 19:49:55 INFO - TEST-OK | dromaeo_css | took 1115820ms 19:49:55 INFO - TEST-START | kraken 19:49:55 INFO - operating with platform_type : linux_ 19:49:55 INFO - Initialising browser for kraken test... 19:49:55 INFO - TEST-INFO | started process 2893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnmyxB8/profile http://localhost:40062/getInfo.html) 19:49:59 INFO - PROCESS | 2893 | __metrics Screen width/height:1600/1200 19:49:59 INFO - PROCESS | 2893 | colorDepth:24 19:49:59 INFO - PROCESS | 2893 | Browser inner width/height: 1024/697 19:49:59 INFO - PROCESS | 2893 | __metrics 19:50:00 INFO - PROCESS | 2893 | 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" 19:50:00 INFO - PROCESS | 2893 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:50:01 INFO - TEST-INFO | 2893: exit 0 19:50:01 INFO - Browser initialized. 19:50:01 INFO - Running cycle 1/1 for kraken test... 19:50:01 INFO - TEST-INFO | started process 3006 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnmyxB8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:50:12 INFO - PROCESS | 3006 | RSS: Main: 141688832 19:50:12 INFO - PROCESS | 3006 | 19:50:15 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40062/tests/kraken/driver.html?testName=audio-beat-detection) 19:50:16 INFO - PROCESS | 3006 | RSS: Main: 184037376 19:50:16 INFO - PROCESS | 3006 | 19:50:18 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40062/tests/kraken/driver.html?testName=audio-dft) 19:50:19 INFO - PROCESS | 3006 | RSS: Main: 173793280 19:50:19 INFO - PROCESS | 3006 | 19:50:22 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40062/tests/kraken/driver.html?testName=audio-fft) 19:50:22 INFO - PROCESS | 3006 | RSS: Main: 265588736 19:50:22 INFO - PROCESS | 3006 | 19:50:24 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40062/tests/kraken/driver.html?testName=audio-oscillator) 19:50:25 INFO - PROCESS | 3006 | RSS: Main: 171917312 19:50:25 INFO - PROCESS | 3006 | 19:50:27 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40062/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:50:28 INFO - PROCESS | 3006 | RSS: Main: 174538752 19:50:28 INFO - PROCESS | 3006 | 19:50:30 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40062/tests/kraken/driver.html?testName=imaging-darkroom) 19:50:31 INFO - PROCESS | 3006 | RSS: Main: 211066880 19:50:31 INFO - PROCESS | 3006 | 19:50:35 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40062/tests/kraken/driver.html?testName=imaging-desaturate) 19:50:35 INFO - PROCESS | 3006 | RSS: Main: 213024768 19:50:35 INFO - PROCESS | 3006 | 19:50:38 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40062/tests/kraken/driver.html?testName=json-parse-financial) 19:50:38 INFO - PROCESS | 3006 | RSS: Main: 211767296 19:50:38 INFO - PROCESS | 3006 | 19:50:40 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40062/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:50:41 INFO - PROCESS | 3006 | RSS: Main: 174178304 19:50:41 INFO - PROCESS | 3006 | 19:50:42 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:50:43 INFO - PROCESS | 3006 | RSS: Main: 180928512 19:50:43 INFO - PROCESS | 3006 | 19:50:45 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:50:46 INFO - PROCESS | 3006 | RSS: Main: 186535936 19:50:46 INFO - PROCESS | 3006 | 19:50:48 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:50:49 INFO - PROCESS | 3006 | RSS: Main: 181424128 19:50:49 INFO - PROCESS | 3006 | 19:50:51 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:50:52 INFO - PROCESS | 3006 | RSS: Main: 185950208 19:50:52 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | Cycle 1(1): loaded http://localhost:40062/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40062/tests/kraken/driver.html?testName=ai-astar) 19:50:54 INFO - PROCESS | 3006 | RSS: Main: 238686208 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | __start_tp_report 19:50:54 INFO - PROCESS | 3006 | _x_x_mozilla_page_load 19:50:54 INFO - PROCESS | 3006 | _x_x_mozilla_page_load_details 19:50:54 INFO - PROCESS | 3006 | |i|pagename|runs| 19:50:54 INFO - PROCESS | 3006 | |0;ai-astar;103;119;112;112;111;113;111;112;111;112 19:50:54 INFO - PROCESS | 3006 | |1;audio-beat-detection;140;114;115;159;116;122;122;117;141;115 19:50:54 INFO - PROCESS | 3006 | |2;audio-dft;160;160;161;161;161;160;161;161;160;160 19:50:54 INFO - PROCESS | 3006 | |3;audio-fft;74;75;74;106;76;76;77;77;89;76 19:50:54 INFO - PROCESS | 3006 | |4;audio-oscillator;82;83;104;82;83;83;92;81;81;113 19:50:54 INFO - PROCESS | 3006 | |5;imaging-gaussian-blur;115;113;113;113;112;113;113;113;113;112 19:50:54 INFO - PROCESS | 3006 | |6;imaging-darkroom;146;145;146;145;148;146;146;146;147;146 19:50:54 INFO - PROCESS | 3006 | |7;imaging-desaturate;94;94;94;93;93;94;94;95;94;95 19:50:54 INFO - PROCESS | 3006 | |8;json-parse-financial;57;58;55;55;55;66;55;56;56;56 19:50:54 INFO - PROCESS | 3006 | |9;json-stringify-tinderbox;52;52;53;52;56;54;52;53;52;53 19:50:54 INFO - PROCESS | 3006 | |10;stanford-crypto-aes;79;76;82;75;84;79;84;79;78;99 19:50:54 INFO - PROCESS | 3006 | |11;stanford-crypto-ccm;124;123;119;127;128;119;121;119;119;121 19:50:54 INFO - PROCESS | 3006 | |12;stanford-crypto-pbkdf2;163;159;157;159;159;157;158;156;161;159 19:50:54 INFO - PROCESS | 3006 | |13;stanford-crypto-sha256-iterative;60;67;60;60;59;59;59;60;58;58 19:50:54 INFO - PROCESS | 3006 | __end_tp_report 19:50:54 INFO - PROCESS | 3006 | __start_cc_report 19:50:54 INFO - PROCESS | 3006 | _x_x_mozilla_cycle_collect,1636 19:50:54 INFO - PROCESS | 3006 | __end_cc_report 19:50:54 INFO - PROCESS | 3006 | __startTimestamp1494557454376__endTimestamp 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | ------- Summary: start ------- 19:50:54 INFO - PROCESS | 3006 | Number of tests: 14 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:3.84 (3.4%) stddev-sans-first:2.51 19:50:54 INFO - PROCESS | 3006 | Values: 103.0 119.0 112.0 112.0 111.0 113.0 111.0 112.0 111.0 112.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:122.00 stddev:15.31 (12.5%) stddev-sans-first:15.39 19:50:54 INFO - PROCESS | 3006 | Values: 140.0 114.0 115.0 159.0 116.0 122.0 122.0 117.0 141.0 115.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 19:50:54 INFO - PROCESS | 3006 | Values: 160.0 160.0 161.0 161.0 161.0 160.0 161.0 161.0 160.0 160.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#3] audio-fft Cycles:10 Average:80.00 Median:76.00 stddev:10.11 (13.3%) stddev-sans-first:10.49 19:50:54 INFO - PROCESS | 3006 | Values: 74.0 75.0 74.0 106.0 76.0 76.0 77.0 77.0 89.0 76.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:82.50 stddev:11.26 (13.6%) stddev-sans-first:11.70 19:50:54 INFO - PROCESS | 3006 | Values: 82.0 83.0 104.0 82.0 83.0 83.0 92.0 81.0 81.0 113.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.82 (0.7%) stddev-sans-first:0.44 19:50:54 INFO - PROCESS | 3006 | Values: 115.0 113.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 112.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 19:50:54 INFO - PROCESS | 3006 | Values: 146.0 145.0 146.0 145.0 148.0 146.0 146.0 146.0 147.0 146.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 19:50:54 INFO - PROCESS | 3006 | Values: 94.0 94.0 94.0 93.0 93.0 94.0 94.0 95.0 94.0 95.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:56.00 stddev:3.35 (6.0%) stddev-sans-first:3.55 19:50:54 INFO - PROCESS | 3006 | Values: 57.0 58.0 55.0 55.0 55.0 66.0 55.0 56.0 56.0 56.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#9] json-stringify-tinderbox Cycles:10 Average:52.90 Median:53.00 stddev:1.29 (2.4%) stddev-sans-first:1.32 19:50:54 INFO - PROCESS | 3006 | Values: 52.0 52.0 53.0 52.0 56.0 54.0 52.0 53.0 52.0 53.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:80.50 stddev:6.85 (8.5%) stddev-sans-first:7.21 19:50:54 INFO - PROCESS | 3006 | Values: 79.0 76.0 82.0 75.0 84.0 79.0 84.0 79.0 78.0 99.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:122.00 stddev:3.40 (2.8%) stddev-sans-first:3.53 19:50:54 INFO - PROCESS | 3006 | Values: 124.0 123.0 119.0 127.0 128.0 119.0 121.0 119.0 119.0 121.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.80 Median:159.00 stddev:2.04 (1.3%) stddev-sans-first:1.50 19:50:54 INFO - PROCESS | 3006 | Values: 163.0 159.0 157.0 159.0 159.0 157.0 158.0 156.0 161.0 159.0 19:50:54 INFO - PROCESS | 3006 | 19:50:54 INFO - PROCESS | 3006 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:60.00 stddev:2.58 (4.3%) stddev-sans-first:2.74 19:50:54 INFO - PROCESS | 3006 | Values: 60.0 67.0 60.0 60.0 59.0 59.0 59.0 60.0 58.0 58.0 19:50:54 INFO - PROCESS | 3006 | -------- Summary: end -------- 19:50:54 INFO - PROCESS | 3006 | 19:50:55 INFO - TEST-INFO | 3006: exit 0 19:50:55 INFO - TEST-OK | kraken | took 60336ms 19:50:55 INFO - SUITE-END | took 1176s 19:50:55 INFO - Completed test suite (00:19:36) 19:50:55 INFO - javascript benchmark 19:50:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7873.092703956117, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3424.9315013699725, 3470.9652903470956, 3484.425069863471, 3501.0547152268873, 3520.0671821967167, 5690.658560486374, 5787.102999035145, 5798.7100644967795, 5807.854803629906, 5825.417458254178, 5812.099124635678, 5870.530357571395, 5871.471567558919, 5871.941280587203, 5872.236609240804, 5185.688858668483, 5192.870178245536, 5209.7134657593815, 5262.684238945656, 5273.1826566882, 4929.309896614477, 4944.752762361885, 5284.5772338212855, 5290.867728306804, 5301.1253143231515, 5085.821996230133, 5122.92315615266, 5161.483851614846, 5184.1705327147665, 5185.611079169077, 5227.215917612389, 5228.973855130719, 5245.632805703603, 5250.9474905250845, 5278.4721527846905, 4977.626677999128, 5121.180611102243, 5188.403333616645, 5224.555912747421, 5236.528712415863, 5612.607117501777, 5721.742521586539, 5808.767649294024, 5814.273215848019, 5818.883622327529, 6185.907211391833, 6194.597351172204, 6231.844203894939, 6240.032794916795, 6242.032484964838, 6262.906056409158, 6269.0, 6290.654014029226, 6291.433770960635, 6299.905501417483, 5615.0, 5642.943570564324, 5742.397048309909, 5786.681732504752, 5794.159846822272, 6335.809925702236, 6351.2696039956, 6361.395667411589, 6362.45919096882, 6367.204099487564, 6068.4538391544975, 6119.4798442132915, 6128.63228206309, 6132.7546898124465, 6135.07973803933, 5998.310194327708, 6032.125341825411, 6092.908606370908, 6248.031555108965, 6253.937460625336, 6142.508599312045, 6151.692415379213, 6166.506679465632, 6167.84580385494, 6169.96915015422, 5952.077427998667, 5997.940020599825, 5998.940010599926, 6126.0, 6149.846253843601, 4623.768811559476, 4698.906021879543, 4700.153972285021, 4702.764861756898, 4764.8332308369045, 4105.58944105593, 4183.435236243129, 4194.412782210493, 4215.262329092397, 4228.61942425177, 5833.79581714638, 5865.912011319833, 5902.527797776168, 5969.820905372846, 6004.39956004396, 6261.154744109578, 6264.404881536247, 6268.843278918063, 6271.749130034838, 6280.654563998933, 5901.097369102381, 5933.080372542263, 5937.10943358491, 5941.70291485424, 5950.19672344228, 5047.570956468668, 5076.0, 5147.794088236504, 5192.402873669501, 5281.0758117329315, 6444.355564443518, 6477.093206951031, 6487.481001519867, 6496.1230233919705, 6499.707513162014, 6178.104174894705, 6179.0422484514975, 6230.0, 6230.937690623217, 6235.314115447207, 6200.720967556561, 6227.0, 6384.968075159781, 6499.805005849832, 6507.251666058274, 6111.19673459643, 6133.028644417115, 6171.753129874754, 6175.166352542529, 6179.969100154471, 6291.402316779899, 6294.3076261612105, 6296.401841825019, 6300.779472718433, 6311.400416960565, 5855.94144058554, 6067.544934130046, 6127.325994140552, 6133.969330153321, 6138.0, 5928.555358348228, 5940.8811823764145, 5972.940270597239, 6098.695065246809, 6125.203723515914, 6046.455818976313, 6049.546284028627, 6060.636361818305, 6062.484688801501, 6063.5452341073715, 5857.7949771757785, 5883.146943693055, 5911.881762364643, 5916.467517923407, 6104.725287361991, 5795.594308398372, 5824.0, 5890.676012819336, 5909.231799866162, 5911.261092363455], "value": 5695.358992562946, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.6792547130204, 818.8034871630808, 842.0526907229367, 909.7498187998295, 918.2562124679015, 31729.36541269176, 43544.782276088656, 44552.10895782083, 44560.108797824025, 44601.0, 44702.55297447024, 44949.77525112378, 45099.32351014738, 45100.77449612755, 45123.0, 7838.843223135534, 8008.719694810683, 9211.217046551035, 9344.159025687686, 9347.719568412924, 10510.421926794023, 10767.138628909708, 10791.19066070044, 10795.83806242907, 10799.460026998659, 10847.67456976292, 10876.401797901111, 10890.183236257273, 10890.836637450444, 10891.782164356708, 10785.838212426781, 10818.459077046195, 10825.350478971286, 10829.0, 10829.67510974672, 10573.207009474243, 10601.151907847354, 10601.522931468102, 10609.469526523642, 10630.574777008911, 43006.35490467645, 43241.56758432407, 43333.566664333266, 43336.56663433388, 43600.0, 4285.335772955197, 4322.783860806947, 4336.8265269389185, 4428.977855110736, 4440.35614835847, 30403.239919001982, 30901.227469313224, 31135.532967005514, 31194.688053119404, 32647.347053058806, 4340.457442819647, 4491.6855820092605, 4506.076254367847, 4557.407537020199, 4580.587747102777, 254958.0, 256727.0, 257034.0, 257405.0, 258459.9999999981, 45193.54806451959, 45499.0, 45509.0, 45575.088498230514, 45585.316220256726, 47036.52963470321, 47131.764341178074, 47180.292295615596, 47239.0, 47280.290795638095, 14328.426862925575, 14561.344739486749, 14577.41690332396, 14598.562043138723, 14608.853911460961, 11350.14873884462, 11505.309681419141, 13762.174269543859, 13881.4447422104, 13952.093113947763, 13209.471621135239, 13295.534656286984, 13305.334733263298, 13307.66730831737, 13353.265570393527, 5377.865553361197, 5464.453554644583, 5491.395946445968, 5507.5593952483705, 5567.387587365387, 996.21299173652, 1023.3450591621369, 1025.9794804103876, 1044.498640652491, 1057.6298295596634, 28293.57559636607, 29604.703952960626, 29712.108636740933, 29946.25134371658, 30170.09489715312, 1078.8489611454402, 1089.242976131581, 1100.9009189173014, 1105.1159072741934, 1108.3294606762954, 14966.925165374103, 15628.2185890705, 15752.133632650315, 15864.286107125206, 16089.195540222941, 344369.0, 345423.0, 345977.0, 346297.0, 346985.0, 57531.0, 58128.0, 58192.709036455395, 58222.0, 58235.0, 1443.3360654099022, 1473.911565306085, 1499.7000599880068, 1511.0178384050234, 1569.6311366828786, 28047.579286310312, 29316.853415732785, 29469.0, 29658.703412965595, 30226.54660180055, 38313.808430957666, 38486.2302753949, 38514.0, 38562.0, 38592.61407386002, 324591.0, 326561.0, 328492.0, 328814.0, 329560.0, 25706.485870282864, 26626.733732662426, 29206.7079329204, 30470.542941855445, 31865.36269274555, 38843.0, 38883.611163887996, 39125.80437097796, 39160.0, 39406.0, 30559.847200763856, 30739.0, 30867.845660771553, 31051.534226987063, 31259.84370078135, 30106.24734381658, 30260.69739302579, 30495.0, 30830.0, 31199.532007019458, 34527.0, 34956.650433496354, 35063.64936350706, 35225.11937201488, 35351.29297413986, 29336.706632933397, 29345.413091737617, 29984.400311994075, 30036.249093771956, 30110.849445752632, 22362.329130126123, 23411.882940585187, 23505.882470587538, 23819.166329178395, 23911.0], "value": 18660.777644511367, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.5453778028642, 464.54938709451864, 469.32651644889586, 478.70559605842396, 487.00571161095314, 20437.386878393652, 22264.109435622555, 22409.32772016838, 22707.54584908301, 22817.543649127027, 22143.667844982298, 22303.330900072982, 22459.663105053398, 22541.0, 22939.655905161395, 6598.0, 6619.867602647944, 6724.831879203035, 6770.32296770324, 6819.045333653267, 6692.665366731669, 6788.660566971632, 6808.523403361766, 7160.64196790161, 7461.515001524911, 7638.045244344457, 7643.923560764405, 7648.2351764823625, 7652.502587331834, 7660.693572257103, 7524.736634217804, 7549.849003019936, 7584.0, 7603.0496187976505, 7608.961955190244, 7307.744228951961, 7343.339099481019, 7380.667869945865, 7382.446316526282, 7388.9261107388775, 25966.610500842315, 26139.869300653376, 26226.34434139143, 26382.0, 26409.207723768126, 3135.372925414926, 3141.27750617357, 3282.2122690554334, 3321.501774733785, 3778.7732736035928, 16351.100689462075, 16351.672966540722, 16360.67278654432, 16423.42518011864, 17037.829621703873, 2273.454370950976, 2456.9631455528183, 2575.343287461704, 2596.805239607018, 2634.288742039658, 106359.46820265926, 107970.0, 108224.0, 108923.0, 111454.44272778585, 17769.378071767427, 17905.7314140288, 17993.7300940486, 18032.729509057375, 18541.81458185428, 31183.220419489695, 31192.220194494865, 31346.373072538878, 31660.683393166233, 31682.465402767724, 10705.785884282272, 10765.30025548337, 10775.730606734895, 10788.622398216025, 11035.282706624044, 9133.406328588753, 9172.312076594282, 9235.676751313671, 9431.386959847589, 9437.0, 8912.242459391024, 9341.719748407559, 9417.670381536613, 9461.0, 9504.857427138599, 3932.9016774580864, 3969.126792105732, 3979.164375481181, 4007.9119298316136, 4070.0, 959.3045042344245, 968.2689569375077, 969.60730903984, 970.6263088710897, 985.2561316206219, 19409.4177174685, 20651.69022464664, 20853.478663033242, 20985.47536311574, 21143.471413214484, 967.7999280891644, 978.4520668425704, 980.8822941247096, 993.7068564773409, 1004.3622299839654, 11597.13021523389, 11602.839716028499, 11693.824592631117, 11774.764504709858, 11815.881841181477, 621737.0, 625907.0, 648421.0, 681937.0, 682963.0, 104308.47845760874, 108328.0, 109992.0, 111611.0, 112609.0, 1310.5478609879576, 1349.595121463557, 1368.4115830193114, 1377.7313423882247, 1380.5375199308344, 18471.53821154482, 18726.906365468087, 18913.810861891754, 19012.714809277597, 19186.904065479583, 51519.0, 51928.48071519387, 52829.207561885836, 52856.471435286694, 53507.732461339, 58399.956001539744, 58585.70707146437, 64846.35153648592, 69996.65001674958, 71156.64421677859, 12397.876021240032, 12412.875871241173, 12550.560730374393, 12684.112112152061, 12750.48998040068, 13467.32663366808, 13620.65948351299, 13648.727025459633, 13723.45106195741, 13968.58094257174, 12099.0, 12193.390330483619, 12278.693032674255, 12325.506979721069, 12366.258024518558, 11809.527618895148, 11825.113116516124, 12027.338496382434, 12087.456064477296, 12214.389280536116, 11244.0, 11799.587014454453, 12555.121141520005, 12622.990160787298, 12765.553205637758, 12409.0, 12409.751804964031, 12523.37383130858, 12525.311107889154, 12534.623961281177, 12167.817482737943, 12205.267683938991, 12212.816807747713, 12336.81494777597, 12426.254424734545], "value": 11675.473699814525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.3708286895194, 1527.6715506166172, 1557.8130624325079, 1591.4191320168143, 1605.277625068719, 9524.380915240505, 11205.495752691128, 11477.368744719037, 11482.253653512516, 11484.081273498132, 11137.220394572383, 11262.831057534124, 11265.887341126587, 11266.155038372113, 11288.492017859195, 2036.4806974221553, 2106.294391378882, 2451.215611004475, 2455.5579995600783, 2497.08856267462, 2532.5947848344267, 2586.780123689489, 2587.2109006752935, 2587.4178309880313, 2588.79289656827, 2451.5587194305017, 2470.3700556358194, 2475.591527397977, 2485.9378515537073, 2489.4523204894895, 2426.2236084452893, 2448.6816713827266, 2453.153661986612, 2458.8893499792553, 2460.987695061522, 2375.1330764270915, 2383.3564937466786, 2392.9521409571885, 2397.6643269942224, 2411.5900296949562, 11784.941075294655, 11792.587259445878, 11874.287542747463, 11876.881231187665, 11948.342841143734, 5844.561657875676, 5997.130416089686, 6014.30835453924, 6084.087386891957, 6120.663363515005, 3423.5549378580627, 3459.9481007784902, 3491.2493813830147, 3596.514470546469, 3648.3980143276326, 1331.5406184866206, 1369.1442848219863, 1372.540199033325, 1377.1392879450343, 1401.2993503248376, 17417.47747567575, 17494.212760425686, 17651.205695743723, 17652.91173544137, 17685.469435916944, 4807.471178170398, 4845.903081938412, 4934.1611925972675, 4938.185199442124, 5020.949790502122, 14330.355134018786, 14337.283135843167, 14371.856281437262, 14373.425062997572, 14375.640608984859, 6064.181335519736, 6070.969645151834, 6079.148919151322, 6088.908666370008, 6090.025595904634, 4450.488193857748, 5226.686398816083, 5414.35027796667, 5462.617616766788, 5530.944690553123, 4290.871273861789, 4379.102284031829, 4398.670099742468, 4442.178197033559, 4447.955520444819, 1161.7095726068483, 1179.7345597240724, 1195.2529668956902, 1196.3659260591726, 1200.5497938273147, 888.6090120347027, 895.5164211325941, 898.5417437106992, 901.7204666553389, 921.7465197070859, 6625.039369291522, 6666.566673166229, 6749.257581666013, 6790.388865002174, 6948.200956890022, 1084.8481212630238, 1111.5276007696696, 1114.3285072391413, 1121.971950701239, 1124.7188202949262, 4388.166248412791, 4436.423264975597, 4437.2234858899565, 4503.437070366204, 4513.954860451354, 14992.0, 15055.0, 15211.771823422658, 15445.0, 15592.376304947902, 15193.544193674208, 15230.923845380703, 15346.462873799586, 15382.461613843461, 15502.30239639219, 10908.181886358396, 10987.340759554452, 11005.0, 11082.889171108185, 11145.72135696614, 6801.387875091265, 6852.897206542006, 6861.382475577222, 6871.415929646036, 6917.13535808024, 13237.933810331271, 13342.19946803195, 13651.38568764388, 13722.588322350346, 13752.449902003807, 13530.120542164728, 13625.727485450434, 13653.385597647932, 13736.381862815997, 13828.239446830521, 8064.596770161351, 8273.46222495536, 9404.0, 9414.482203478874, 9583.712488625353, 11444.370559618968, 11494.367809770209, 11634.88365116338, 11640.068794496588, 11730.765384692088, 9521.714348569554, 9583.233341332538, 9698.56356463975, 9758.365706229357, 9907.058829411195, 9338.906610933804, 9390.389624674373, 9426.434413935185, 9533.427994320364, 9748.0, 8446.535440550828, 8572.971243450826, 8574.056853745959, 8580.270676992526, 8580.485170889517, 6194.907076393768, 6259.467945224706, 6260.937390626035, 6288.937110628835, 6292.811215663537, 6284.465820405317, 6316.0, 6364.777232797015, 6446.935530644633, 6541.70562324706], "value": 5473.145516146822, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.4251132227154, 428.1608048225478, 449.563922994695, 452.27861560810516, 453.45358842594675, 5229.50319719627, 5314.627976041674, 5360.24956506089, 5366.812161574346, 5398.0310829044065, 5237.214417837326, 5246.868828279295, 5275.630705850592, 5283.471652834714, 5338.946610533893, 4246.278132717438, 4330.350447432878, 4332.870013899588, 4338.566143385668, 4350.847720329789, 4336.457942757155, 4336.696431249814, 4338.97830510847, 4375.278079116942, 4409.58108979648, 4378.956210437903, 4431.601155895984, 4436.622887054604, 4455.331700244957, 4474.642028637718, 4318.784060796947, 4338.924285522133, 4344.6089851913475, 4345.608895199447, 4356.673249506268, 4092.386142078712, 4100.159467309194, 4100.995256162229, 4109.054917368992, 4121.155163191568, 5110.0, 5113.181890897438, 5156.767945442464, 5169.508896654812, 5181.5854731621375, 5004.724740139291, 5069.214271787879, 5113.590912726973, 5128.820491282787, 5197.506236907488, 5473.397926228112, 5474.37044739856, 5494.917576236319, 5510.862228444281, 5540.002799496089, 4613.584777370019, 4616.907661846744, 4620.745858977755, 4627.968680514268, 4629.768511574442, 5430.8913821723345, 5539.446055394509, 5598.44015598441, 5667.376588575254, 5694.629849059798, 4547.499775024745, 4561.338605902126, 4598.8620341389815, 4743.478217396084, 4744.715317080986, 4818.4217893852965, 4827.637927155477, 4851.442084160296, 4856.975715121402, 4860.198067318853, 4881.949581175914, 5066.594672426197, 5128.820491282787, 5136.743162841843, 5170.40540337866, 5437.564994800486, 5438.891222175614, 5487.42382049887, 5497.615166938276, 5554.250176226158, 1642.4744081893919, 1648.8433598808094, 1651.4962936304541, 1687.2744719770617, 1689.6705142497094, 1233.582256666898, 1286.4018231522386, 1286.4339690536135, 1290.7931084435895, 1310.351376068833, 5198.948010519922, 5444.265024221759, 5466.508014278655, 5479.205515200274, 5492.752826122754, 5614.9157762633595, 5654.095344744821, 5674.914876276859, 5700.25896633435, 5704.657720536781, 5429.077056900336, 5443.836684899459, 5446.739603064763, 5448.700321482356, 5483.232347471358, 2844.615976843141, 2967.5697023931407, 2993.664941345754, 2995.0864986179427, 3015.7888947773445, 5863.648181109147, 5870.207521984477, 5889.9692553802915, 5919.585629005928, 5923.733431995571, 5792.466595376817, 5802.47777700009, 5827.300723913073, 5845.88308233833, 5849.53203743708, 5550.639208451437, 5822.534197264209, 5892.322382925932, 5953.916129354691, 5966.5835336688715, 5657.60396772222, 5685.77256909719, 5715.913976344481, 5726.255586773693, 5779.508741756829, 5485.01269771445, 5522.088855338824, 5554.50009499147, 5689.800856969986, 5714.628549144292, 5462.644928079662, 5699.316082070012, 5816.1857339973285, 5852.0, 5853.912191317048, 4710.364100846342, 4803.543663351976, 4807.6394270429155, 4820.975895120642, 4852.587530059985, 5504.0, 5643.26637537134, 5745.9425405745405, 5759.625624334405, 5810.6513609183585, 5513.669179849222, 5541.168824676331, 5556.555475562026, 5587.972060139673, 5616.971915140398, 5461.699606521679, 5514.641548299508, 5540.529055030368, 5547.1679248111495, 5559.499645031967, 5398.78404863801, 5438.211459338453, 5493.176023596492, 5495.203195536705, 5504.843982763505, 5443.346798384219, 5490.972545137249, 5513.2005859151, 5515.290260019407, 5581.0, 5257.5005374489365, 5408.242846001642, 5459.181122831607, 5509.338879334506, 5512.91730624033], "value": 4456.31064298159, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [875.6234819027819, 912.7672443526902, 923.6997975657913, 933.3140141995632, 956.7273327101775, 17746.911265443672, 18828.811711882878, 19511.512212194702, 19670.91809950452, 19827.0, 16970.272295778166, 17684.292628294887, 17781.46655600331, 17949.820501794977, 17974.0, 883.8188171424858, 903.6972614174259, 905.9229965452945, 907.7549061753323, 909.2271569166222, 883.33308352194, 906.5965645287849, 907.0340087806497, 913.8583519554479, 915.9038300978401, 962.3215632978734, 969.4667932637057, 969.8011907558933, 970.4710932541782, 970.6651205334149, 960.3085778239656, 961.423146112334, 962.4706411473661, 964.0793042644258, 964.5418426247546, 702.6170736948325, 703.0508813102322, 703.0649236515474, 703.085988215318, 703.3318347569829, 16750.58123546921, 16910.239039243264, 17105.828941710548, 19425.320113795948, 19602.313919012908, 16536.75194872078, 16754.41359552422, 16849.66300673992, 16915.83084169155, 17267.654646907118, 13806.840021765123, 14065.929670351685, 14267.357968891425, 14356.784648230181, 14406.783898241534, 4131.958680413188, 4184.748915065105, 4212.073343864345, 4214.178235244129, 4358.978205108986, 88602.0, 88694.0, 89184.0, 91954.0, 92501.53749231211, 11726.0, 11789.410529473493, 11795.35125568085, 11868.762624747456, 11903.0, 21625.879267970544, 21694.34916952494, 21734.130634774432, 21826.0, 22023.0, 8776.078511756301, 8817.426867253607, 8964.0, 9031.412958157698, 9054.456732596065, 5032.798688052437, 5052.9242061369105, 5141.562967147759, 5210.166373380241, 5309.123994540934, 3735.3463143950244, 3809.085819403323, 3826.770393776382, 3829.119302560371, 3833.7316387852584, 610.1457958857635, 617.2038070888593, 621.2358798677652, 627.0218459203656, 633.2654121219456, 642.8296501427126, 650.1580453312971, 669.668514085521, 677.0, 681.4173881331391, 10289.94855025735, 11453.14101442395, 11803.822942655866, 11897.0, 12372.44324005422, 803.6022169026369, 807.3944541593804, 819.2954059508937, 823.6087858267275, 825.2160447574828, 5052.469490703424, 5152.92270615941, 5198.532132108128, 5199.168133098686, 5204.661696989683, 62580.0, 66919.0, 69588.0, 72028.2797172032, 85204.57397712972, 67517.66241168861, 68625.0, 68638.31361686319, 69021.3097869025, 69957.3004269961, 61361.693191533755, 62630.37369626336, 62673.68663156746, 63058.684706576176, 63438.365616343246, 11199.88800112021, 11322.660320190407, 11388.316700997966, 11692.649220523397, 11708.648740537797, 36666.26667466582, 39804.0, 40536.59463405328, 40830.0, 41767.58232417637, 49296.50703492919, 49312.260316096006, 49365.50634493609, 49488.25767613417, 49917.0, 13657.180569165881, 13691.452341906212, 13779.586612401645, 13956.511522096678, 14000.719985600435, 14245.21651309188, 14853.82620425978, 14893.180875051976, 16270.755938661167, 16376.181190940168, 15636.530904072895, 15975.760363594787, 16061.67876642484, 16324.510264692079, 16965.0, 15736.92131539335, 15902.522924312289, 16268.918655406647, 16516.75224871604, 17195.312187512358, 11811.704707382385, 15372.538823835303, 15932.840671593136, 16330.0, 16351.0, 10023.899761002296, 10088.848667270144, 10362.326448780805, 10631.681049568526, 10666.0, 11578.189526733384, 11652.30086194831, 11848.466818992994, 11875.0, 11925.224860384047], "value": 7868.585058887635, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1451.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 119.0, 112.0, 112.0, 111.0, 113.0, 111.0, 112.0, 111.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 114.0, 115.0, 159.0, 116.0, 122.0, 122.0, 117.0, 141.0, 115.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 160.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 106.0, 76.0, 76.0, 77.0, 77.0, 89.0, 76.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 104.0, 82.0, 83.0, 83.0, 92.0, 81.0, 81.0, 113.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 148.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 55.0, 55.0, 55.0, 66.0, 55.0, 56.0, 56.0, 56.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 52.0, 56.0, 54.0, 52.0, 53.0, 52.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 82.0, 75.0, 84.0, 79.0, 84.0, 79.0, 78.0, 99.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 119.0, 127.0, 128.0, 119.0, 121.0, 119.0, 119.0, 121.0], "value": 122.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 157.0, 159.0, 159.0, 157.0, 158.0, 156.0, 161.0, 159.0], "value": 158.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 60.0, 60.0, 59.0, 59.0, 59.0, 60.0, 58.0, 58.0], "value": 60.0, "unit": "score"}]}]} 19:50:55 INFO - Return code: 0 19:50:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:50:55 INFO - # TBPL SUCCESS # 19:50:55 INFO - Running post-action listener: _resource_record_post_action 19:50:55 INFO - [mozharness: 2017-05-12 02:50:55.682282Z] Finished run-tests step (success) 19:50:55 INFO - Running post-run listener: _resource_record_post_run 19:50:55 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 339992576; Read time: 0; Write time: 268264 19:50:55 INFO - TinderboxPrint: CPU usage
13.1% 19:50:55 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:50:55 INFO - TinderboxPrint: I/O write bytes / time
339,992,576 / 268,264 19:50:55 INFO - TinderboxPrint: CPU idle
8,255.7 (86.9%) 19:50:55 INFO - TinderboxPrint: CPU user
1,197.1 (12.6%) 19:50:55 INFO - TinderboxPrint: Swap in / out
0 / 0 19:50:55 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 15642624; Read time: 0; Write time: 7336 19:50:55 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 231108608; Read time: 0; Write time: 190316 19:50:56 INFO - Running post-run listener: _upload_blobber_files 19:50:56 INFO - Blob upload gear active. 19:50:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:50:56 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 19:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:50:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:50:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:57 INFO - (blobuploader) - INFO - Done attempting. 19:50:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:50:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:50:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:59 INFO - (blobuploader) - INFO - Done attempting. 19:50:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:50:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:51:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:51:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:51:00 INFO - (blobuploader) - INFO - Done attempting. 19:51:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:51:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:51:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:51:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:51:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:51:03 INFO - (blobuploader) - INFO - Done attempting. 19:51:03 INFO - (blobuploader) - INFO - Iteration through files over. 19:51:03 INFO - Return code: 0 19:51:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:51:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:51:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7d73956659db895bf4459f859bff96d42c16970210e3f91bfe9fcd77cef19a433f9989846183e69681be72cdf4f051230e25a763f45c75a497fadf2b77976bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65cd06a03493f6439a5605d5e9bbc299391dde7dd0ebb8a20e7e97fb96305edb578624321bf4bbd2b8420b74ef1cbdd23558ad7e6389d4edd4adf670633b6731", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb3406ce91ba3d3d316773609766a1d5e94f039a4f9e9efa42f2e504a8b69398df4b0ea447ea5ab90ff4803d81287f09b80125925983735f186388e377cdb0d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f3b58d16df54a69f356303caacbbdbf9ebfccd74b73b70d1780bffbfb168e0164ceca1ae959e709a2c7d21facc602d6d453828b76df4ca6082b07491e9a7c266"} 19:51:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:51:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:51:03 INFO - Contents: 19:51:03 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7d73956659db895bf4459f859bff96d42c16970210e3f91bfe9fcd77cef19a433f9989846183e69681be72cdf4f051230e25a763f45c75a497fadf2b77976bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65cd06a03493f6439a5605d5e9bbc299391dde7dd0ebb8a20e7e97fb96305edb578624321bf4bbd2b8420b74ef1cbdd23558ad7e6389d4edd4adf670633b6731", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb3406ce91ba3d3d316773609766a1d5e94f039a4f9e9efa42f2e504a8b69398df4b0ea447ea5ab90ff4803d81287f09b80125925983735f186388e377cdb0d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f3b58d16df54a69f356303caacbbdbf9ebfccd74b73b70d1780bffbfb168e0164ceca1ae959e709a2c7d21facc602d6d453828b76df4ca6082b07491e9a7c266"} 19:51:03 INFO - Running post-run listener: copy_logs_to_upload_dir 19:51:03 INFO - Copying logs to upload dir... 19:51:03 INFO - mkdir: /builds/slave/test/build/upload/logs 19:51:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.880970 ========= master_lag: -0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-11 19:51:02.704773) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 19:51:02.709755) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7d73956659db895bf4459f859bff96d42c16970210e3f91bfe9fcd77cef19a433f9989846183e69681be72cdf4f051230e25a763f45c75a497fadf2b77976bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65cd06a03493f6439a5605d5e9bbc299391dde7dd0ebb8a20e7e97fb96305edb578624321bf4bbd2b8420b74ef1cbdd23558ad7e6389d4edd4adf670633b6731", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb3406ce91ba3d3d316773609766a1d5e94f039a4f9e9efa42f2e504a8b69398df4b0ea447ea5ab90ff4803d81287f09b80125925983735f186388e377cdb0d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f3b58d16df54a69f356303caacbbdbf9ebfccd74b73b70d1780bffbfb168e0164ceca1ae959e709a2c7d21facc602d6d453828b76df4ca6082b07491e9a7c266"} build_url:https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011443 build_url: 'https://queue.taskcluster.net/v1/task/Ir39m-oNSCmeQtBhK9MCWw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7d73956659db895bf4459f859bff96d42c16970210e3f91bfe9fcd77cef19a433f9989846183e69681be72cdf4f051230e25a763f45c75a497fadf2b77976bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65cd06a03493f6439a5605d5e9bbc299391dde7dd0ebb8a20e7e97fb96305edb578624321bf4bbd2b8420b74ef1cbdd23558ad7e6389d4edd4adf670633b6731", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb3406ce91ba3d3d316773609766a1d5e94f039a4f9e9efa42f2e504a8b69398df4b0ea447ea5ab90ff4803d81287f09b80125925983735f186388e377cdb0d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f3b58d16df54a69f356303caacbbdbf9ebfccd74b73b70d1780bffbfb168e0164ceca1ae959e709a2c7d21facc602d6d453828b76df4ca6082b07491e9a7c266"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 19:51:02.737720) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:51:02.738049) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494556215.890203-137788673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004424 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:51:02.818130) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 19:51:02.818472) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 19:51:02.818772) ========= ========= Total master_lag: -0.11 =========