builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1496303149.06 results: success (0) revision: c2332a18922bace569fdbca5cb0f43a82b211ed3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.055802) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.056489) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.056790) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.089183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.089460) ========= 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=4300f881736b422476174874000001bc-1496301783.65284-1210956103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-01 00:45:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2017-06-01 00:45:49 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137920 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.263220) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.266400) ========= 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=4300f881736b422476174874000001bc-1496301783.65284-1210956103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019946 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:49.337862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 00:45:49.338182) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c2332a18922bace569fdbca5cb0f43a82b211ed3 --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 c2332a18922bace569fdbca5cb0f43a82b211ed3 --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=4300f881736b422476174874000001bc-1496301783.65284-1210956103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-01 00:45:49,838 truncating revision to first 12 chars 2017-06-01 00:45:49,838 Setting DEBUG logging. 2017-06-01 00:45:49,838 attempt 1/10 2017-06-01 00:45:49,838 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c2332a18922b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 00:45:50,553 unpacking tar archive at: mozilla-beta-c2332a18922b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.058248 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 00:45:50.418725) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:50.419037) ========= script_repo_revision: c2332a18922bace569fdbca5cb0f43a82b211ed3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:50.421445) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:50.421713) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 00:45:50.439557) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-06-01 00:45:50.439822) ========= /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=4300f881736b422476174874000001bc-1496301783.65284-1210956103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:45:51 INFO - MultiFileLogger online at 20170601 00:45:51 in /builds/slave/test 00:45:51 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 00:45:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:45:51 INFO - {'append_to_log': False, 00:45:51 INFO - 'base_work_dir': '/builds/slave/test', 00:45:51 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 00:45:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:45:51 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 00:45:51 INFO - 'buildbot_json_path': 'buildprops.json', 00:45:51 INFO - 'config_files': ('talos/linux_config.py',), 00:45:51 INFO - 'default_actions': ('clobber', 00:45:51 INFO - 'read-buildbot-config', 00:45:51 INFO - 'download-and-extract', 00:45:51 INFO - 'populate-webroot', 00:45:51 INFO - 'create-virtualenv', 00:45:51 INFO - 'install', 00:45:51 INFO - 'run-tests'), 00:45:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:45:51 INFO - 'download_minidump_stackwalk': True, 00:45:51 INFO - 'download_symbols': 'ondemand', 00:45:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:45:51 INFO - 'tooltool.py': '/tools/tooltool.py', 00:45:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:45:51 INFO - '/tools/misc-python/virtualenv.py')}, 00:45:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:45:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:45:51 INFO - 'gecko_profile': False, 00:45:51 INFO - 'gecko_profile_interval': 0, 00:45:51 INFO - 'installer_path': 'installer.exe', 00:45:51 INFO - 'log_level': 'info', 00:45:51 INFO - 'log_name': 'talos', 00:45:51 INFO - 'log_to_console': True, 00:45:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:45:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:45:51 INFO - 'opt_config_files': (), 00:45:51 INFO - 'pip_index': False, 00:45:51 INFO - 'suite': 'dromaeojs', 00:45:51 INFO - 'system_bits': '32', 00:45:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:45:51 INFO - 'title': 'talos-linux64-ix-045', 00:45:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:45:51 INFO - 'use_talos_json': True, 00:45:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:45:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:45:51 INFO - 'work_dir': 'build'} 00:45:51 INFO - [mozharness: 2017-06-01 07:45:51.029931Z] Running clobber step. 00:45:51 INFO - Running pre-action listener: _resource_record_pre_action 00:45:51 INFO - Running main action method: clobber 00:45:51 INFO - rmtree: /builds/slave/test/build 00:45:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:45:52 INFO - Running post-action listener: _resource_record_post_action 00:45:52 INFO - [mozharness: 2017-06-01 07:45:52.570745Z] Finished clobber step (success) 00:45:52 INFO - [mozharness: 2017-06-01 07:45:52.570843Z] Running read-buildbot-config step. 00:45:52 INFO - Running pre-action listener: _resource_record_pre_action 00:45:52 INFO - Running main action method: read_buildbot_config 00:45:52 INFO - Using buildbot properties: 00:45:52 INFO - { 00:45:52 INFO - "project": "", 00:45:52 INFO - "product": "firefox", 00:45:52 INFO - "who": "philringnalda@gmail.com", 00:45:52 INFO - "installer_path": "public/build/target.tar.bz2", 00:45:52 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 00:45:52 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 00:45:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 00:45:52 INFO - "stage_platform": "linux64", 00:45:52 INFO - "basedir": "/builds/slave/test", 00:45:52 INFO - "buildnumber": 64, 00:45:52 INFO - "platform": "ubuntu64_hw", 00:45:52 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 00:45:52 INFO - "slavebuilddir": "test", 00:45:52 INFO - "taskId": "QGviPwkPQnOoC7FQqQNEKg", 00:45:52 INFO - "branch": "mozilla-beta", 00:45:52 INFO - "script_repo_revision": "production", 00:45:52 INFO - "revision": "c2332a18922bace569fdbca5cb0f43a82b211ed3", 00:45:52 INFO - "slavename": "talos-linux64-ix-045", 00:45:52 INFO - "repo_path": "releases/mozilla-beta" 00:45:52 INFO - } 00:45:52 INFO - Finding installer, test and symbols from parent task. 00:45:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QGviPwkPQnOoC7FQqQNEKg'}, attempt #1 00:45:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QGviPwkPQnOoC7FQqQNEKg'}, attempt #1 00:45:53 INFO - Task dependencies: AtvSNPKMTf6WKIgJEldMPg 00:45:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg'}, attempt #1 00:45:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2 00:45:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/test_packages.json 00:45:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.crashreporter-symbols.zip 00:45:53 INFO - Running post-action listener: _resource_record_post_action 00:45:53 INFO - [mozharness: 2017-06-01 07:45:53.932134Z] Finished read-buildbot-config step (success) 00:45:53 INFO - [mozharness: 2017-06-01 07:45:53.932352Z] Running download-and-extract step. 00:45:53 INFO - Running pre-action listener: _resource_record_pre_action 00:45:53 INFO - Running main action method: download_and_extract 00:45:53 INFO - mkdir: /builds/slave/test/build/tests 00:45: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')]} 00:45:53 INFO - trying https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.test_packages.json 00:45:53 INFO - Downloading https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 00:45:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 00:45:54 INFO - Downloaded 986 bytes. 00:45:54 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 00:45:54 INFO - Using the following test package requirements: 00:45:54 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 00:45:54 INFO - u'common': [u'target.common.tests.zip'], 00:45:54 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 00:45:54 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 00:45:54 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 00:45:54 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 00:45:54 INFO - u'mozbase': [u'target.common.tests.zip'], 00:45:54 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 00:45:54 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 00:45:54 INFO - u'web-platform': [u'target.common.tests.zip', 00:45:54 INFO - u'target.web-platform.tests.zip'], 00:45:54 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 00:45:54 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 00:45:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.common.tests.zip 00:45:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.common.tests.zip'}, attempt #1 00:45:54 INFO - Fetch https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.common.tests.zip into memory 00:45:57 INFO - Expected file size: 59713736 00:45:57 INFO - Obtained file size: 59713736 00:46:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 00:46:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.common.tests.zip 00:46:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.common.tests.zip'}, attempt #1 00:46:06 INFO - Fetch https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.common.tests.zip into memory 00:46:08 INFO - Expected file size: 59713736 00:46:08 INFO - Obtained file size: 59713736 00:46:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.talos.tests.zip 00:46:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 00:46:19 INFO - Fetch https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.talos.tests.zip into memory 00:46:20 INFO - Expected file size: 14404461 00:46:20 INFO - Obtained file size: 14404461 00:46:20 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')]} 00:46:20 INFO - trying https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2 00:46:20 INFO - Downloading https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 00:46:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 00:46:23 INFO - Downloaded 59808881 bytes. 00:46:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2 00:46:23 INFO - mkdir: /builds/slave/test/properties 00:46:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:46:23 INFO - Writing to file /builds/slave/test/properties/build_url 00:46:23 INFO - Contents: 00:46:23 INFO - build_url:https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2 00:46:23 INFO - Running post-action listener: _resource_record_post_action 00:46:23 INFO - Running post-action listener: set_extra_try_arguments 00:46:23 INFO - [mozharness: 2017-06-01 07:46:23.308303Z] Finished download-and-extract step (success) 00:46:23 INFO - [mozharness: 2017-06-01 07:46:23.308527Z] Running populate-webroot step. 00:46:23 INFO - Running pre-action listener: _resource_record_pre_action 00:46:23 INFO - Running main action method: populate_webroot 00:46:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:46:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:46:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:46:23 INFO - '--tppagecycles', 00:46:23 INFO - '7'], 00:46:23 INFO - 'tests': ['tsvgm']}}, 00:46:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:46:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:46:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:46:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:46:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 00:46:23 INFO - 'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:46:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:46:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:46:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 00:46:23 INFO - 'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['damp', 'tps']}, 00:46:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:46:23 INFO - 'tests': ['damp', 'tps']}, 00:46:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['dromaeo_dom']}, 00:46:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:46:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['basic_compositor_video']}, 00:46:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:46:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['a11yr', 00:46:23 INFO - 'ts_paint', 00:46:23 INFO - 'tpaint', 00:46:23 INFO - 'sessionrestore', 00:46:23 INFO - 'sessionrestore_no_auto_restore', 00:46:23 INFO - 'tabpaint']}, 00:46:23 INFO - 'other-e10s': {'tests': ['a11yr', 00:46:23 INFO - 'ts_paint', 00:46:23 INFO - 'tpaint', 00:46:23 INFO - 'sessionrestore', 00:46:23 INFO - 'sessionrestore_no_auto_restore', 00:46:23 INFO - 'tabpaint']}, 00:46:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:46:23 INFO - 'ts_paint', 00:46:23 INFO - 'tpaint', 00:46:23 INFO - 'sessionrestore', 00:46:23 INFO - 'sessionrestore_no_auto_restore', 00:46:23 INFO - 'tabpaint']}, 00:46:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:46:23 INFO - 'ts_paint', 00:46:23 INFO - 'tpaint', 00:46:23 INFO - 'sessionrestore', 00:46:23 INFO - 'sessionrestore_no_auto_restore', 00:46:23 INFO - 'tabpaint']}, 00:46:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['a11yr', 00:46:23 INFO - 'ts_paint', 00:46:23 INFO - 'tpaint', 00:46:23 INFO - 'sessionrestore', 00:46:23 INFO - 'sessionrestore_no_auto_restore', 00:46:23 INFO - 'tabpaint']}, 00:46:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['a11yr', 00:46:23 INFO - 'ts_paint', 00:46:23 INFO - 'tpaint', 00:46:23 INFO - 'sessionrestore', 00:46:23 INFO - 'sessionrestore_no_auto_restore', 00:46:23 INFO - 'tabpaint']}, 00:46:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['tsvgx', 00:46:23 INFO - 'tsvgr_opacity', 00:46:23 INFO - 'tart', 00:46:23 INFO - 'tscrollx', 00:46:23 INFO - 'cart', 00:46:23 INFO - 'tsvg_static']}, 00:46:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:46:23 INFO - 'tsvgr_opacity', 00:46:23 INFO - 'tart', 00:46:23 INFO - 'tscrollx', 00:46:23 INFO - 'cart', 00:46:23 INFO - 'tsvg_static']}, 00:46:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 00:46:23 INFO - 'talos_options': ['--disable-e10s'], 00:46:23 INFO - 'tests': ['tp5o']}, 00:46:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:46:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 00:46:23 INFO - 'talos_options': ['--disable-e10s', 00:46:23 INFO - '--xperf_path', 00:46:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:46:23 INFO - 'tests': ['tp5n']}, 00:46:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:46:23 INFO - 'talos_options': ['--xperf_path', 00:46:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:46:23 INFO - 'tests': ['tp5n']}}, 00:46:23 INFO - 'talos.zip': {'path': '', 00:46:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:46:23 INFO - Running post-action listener: _resource_record_post_action 00:46:23 INFO - [mozharness: 2017-06-01 07:46:23.327133Z] Finished populate-webroot step (success) 00:46:23 INFO - [mozharness: 2017-06-01 07:46:23.327224Z] Running create-virtualenv step. 00:46:23 INFO - Running pre-action listener: _resource_record_pre_action 00:46:23 INFO - Running main action method: create_virtualenv 00:46:23 INFO - Creating virtualenv /builds/slave/test/build/venv 00:46:23 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 00:46:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:46:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 00:46:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:46:23 INFO - Using real prefix '/usr' 00:46:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:46:23 INFO - Installing distribute.............................................................................................................................................................................................done. 00:46:25 INFO - Installing pip.................done. 00:46:25 INFO - Return code: 0 00:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:46:26 INFO - Reading from file tmpfile_stdout 00:46:26 INFO - Output received: 00:46:26 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) 00:46:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:46:26 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')]} 00:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:26 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')]} 00:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:26 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:26 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 00:46:26 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 00:46:26 INFO - Using env: {'DISPLAY': ':0', 00:46:26 INFO - 'HOME': '/home/cltbld', 00:46:26 INFO - 'LANG': 'en_US.UTF-8', 00:46:26 INFO - 'LANGUAGE': 'en_US:en', 00:46:26 INFO - 'LOGNAME': 'cltbld', 00:46:26 INFO - 'MAIL': '/var/mail/cltbld', 00:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:26 INFO - 'MOZ_NO_REMOTE': '1', 00:46:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:26 INFO - 'NO_EM_RESTART': '1', 00:46:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:26 INFO - 'PWD': '/builds/slave/test', 00:46:26 INFO - 'SHELL': '/bin/bash', 00:46:26 INFO - 'SHLVL': '1', 00:46:26 INFO - 'TERM': 'linux', 00:46:26 INFO - 'TMOUT': '86400', 00:46:26 INFO - 'USER': 'cltbld', 00:46:26 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:26 INFO - '_': '/tools/buildbot/bin/python'} 00:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:26 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 00:46:26 INFO - Cleaning up... 00:46:26 INFO - Return code: 0 00:46:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:46:26 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')]} 00:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:26 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')]} 00:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:26 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:26 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 00:46:26 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 00:46:26 INFO - Using env: {'DISPLAY': ':0', 00:46:26 INFO - 'HOME': '/home/cltbld', 00:46:26 INFO - 'LANG': 'en_US.UTF-8', 00:46:26 INFO - 'LANGUAGE': 'en_US:en', 00:46:26 INFO - 'LOGNAME': 'cltbld', 00:46:26 INFO - 'MAIL': '/var/mail/cltbld', 00:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:26 INFO - 'MOZ_NO_REMOTE': '1', 00:46:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:26 INFO - 'NO_EM_RESTART': '1', 00:46:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:26 INFO - 'PWD': '/builds/slave/test', 00:46:26 INFO - 'SHELL': '/bin/bash', 00:46:26 INFO - 'SHLVL': '1', 00:46:26 INFO - 'TERM': 'linux', 00:46:26 INFO - 'TMOUT': '86400', 00:46:26 INFO - 'USER': 'cltbld', 00:46:26 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:26 INFO - '_': '/tools/buildbot/bin/python'} 00:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:26 INFO - Downloading/unpacking psutil>=3.1.1 00:46:26 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 00:46:26 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 00:46:26 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 00:46:26 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 00:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:46:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:46:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:46:27 INFO - Installing collected packages: psutil 00:46:27 INFO - Running setup.py install for psutil 00:46:27 INFO - building 'psutil._psutil_linux' extension 00:46:27 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 00:46:28 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 00:46:28 INFO - building 'psutil._psutil_posix' extension 00:46:28 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 00:46:28 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 00:46:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:46:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:46:28 INFO - Successfully installed psutil 00:46:28 INFO - Cleaning up... 00:46:28 INFO - Return code: 0 00:46:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:46:28 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')]} 00:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:28 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')]} 00:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:28 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:28 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 00:46:28 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 00:46:28 INFO - Using env: {'DISPLAY': ':0', 00:46:28 INFO - 'HOME': '/home/cltbld', 00:46:28 INFO - 'LANG': 'en_US.UTF-8', 00:46:28 INFO - 'LANGUAGE': 'en_US:en', 00:46:28 INFO - 'LOGNAME': 'cltbld', 00:46:28 INFO - 'MAIL': '/var/mail/cltbld', 00:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:28 INFO - 'MOZ_NO_REMOTE': '1', 00:46:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:28 INFO - 'NO_EM_RESTART': '1', 00:46:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:28 INFO - 'PWD': '/builds/slave/test', 00:46:28 INFO - 'SHELL': '/bin/bash', 00:46:28 INFO - 'SHLVL': '1', 00:46:28 INFO - 'TERM': 'linux', 00:46:28 INFO - 'TMOUT': '86400', 00:46:28 INFO - 'USER': 'cltbld', 00:46:28 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:28 INFO - '_': '/tools/buildbot/bin/python'} 00:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:46:28 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 00:46:28 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 00:46:28 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 00:46:28 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 00:46:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:46:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:46:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:46:29 INFO - Installing collected packages: mozsystemmonitor 00:46:29 INFO - Running setup.py install for mozsystemmonitor 00:46:29 INFO - Successfully installed mozsystemmonitor 00:46:29 INFO - Cleaning up... 00:46:29 INFO - Return code: 0 00:46:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:46:29 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')]} 00:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:29 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')]} 00:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:29 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:29 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 00:46:29 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 00:46:29 INFO - Using env: {'DISPLAY': ':0', 00:46:29 INFO - 'HOME': '/home/cltbld', 00:46:29 INFO - 'LANG': 'en_US.UTF-8', 00:46:29 INFO - 'LANGUAGE': 'en_US:en', 00:46:29 INFO - 'LOGNAME': 'cltbld', 00:46:29 INFO - 'MAIL': '/var/mail/cltbld', 00:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:29 INFO - 'MOZ_NO_REMOTE': '1', 00:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:29 INFO - 'NO_EM_RESTART': '1', 00:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:29 INFO - 'PWD': '/builds/slave/test', 00:46:29 INFO - 'SHELL': '/bin/bash', 00:46:29 INFO - 'SHLVL': '1', 00:46:29 INFO - 'TERM': 'linux', 00:46:29 INFO - 'TMOUT': '86400', 00:46:29 INFO - 'USER': 'cltbld', 00:46:29 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:29 INFO - '_': '/tools/buildbot/bin/python'} 00:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:30 INFO - Downloading/unpacking jsonschema==2.5.1 00:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:46:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:31 INFO - Downloading functools32-3.2.3-2.tar.gz 00:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:46:31 INFO - warning: no files found matching '*.txt' 00:46:31 INFO - no previously-included directories found matching 'build' 00:46:31 INFO - no previously-included directories found matching 'dist' 00:46:31 INFO - no previously-included directories found matching '.git*' 00:46:31 INFO - Installing collected packages: jsonschema, functools32 00:46:31 INFO - Running setup.py install for functools32 00:46:31 INFO - warning: no files found matching '*.txt' 00:46:31 INFO - no previously-included directories found matching 'build' 00:46:31 INFO - no previously-included directories found matching 'dist' 00:46:31 INFO - no previously-included directories found matching '.git*' 00:46:31 INFO - Successfully installed jsonschema functools32 00:46:31 INFO - Cleaning up... 00:46:31 INFO - Return code: 0 00:46:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:46:31 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')]} 00:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:31 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')]} 00:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:31 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:31 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 00:46:31 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 00:46:31 INFO - Using env: {'DISPLAY': ':0', 00:46:31 INFO - 'HOME': '/home/cltbld', 00:46:31 INFO - 'LANG': 'en_US.UTF-8', 00:46:31 INFO - 'LANGUAGE': 'en_US:en', 00:46:31 INFO - 'LOGNAME': 'cltbld', 00:46:31 INFO - 'MAIL': '/var/mail/cltbld', 00:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:31 INFO - 'MOZ_NO_REMOTE': '1', 00:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:31 INFO - 'NO_EM_RESTART': '1', 00:46:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:31 INFO - 'PWD': '/builds/slave/test', 00:46:31 INFO - 'SHELL': '/bin/bash', 00:46:31 INFO - 'SHLVL': '1', 00:46:31 INFO - 'TERM': 'linux', 00:46:31 INFO - 'TMOUT': '86400', 00:46:31 INFO - 'USER': 'cltbld', 00:46:31 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:31 INFO - '_': '/tools/buildbot/bin/python'} 00:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:46:31 INFO - Cleaning up... 00:46:31 INFO - Return code: 0 00:46:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:46:31 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')]} 00:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:31 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')]} 00:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:31 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:31 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 00:46:31 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 00:46:31 INFO - Using env: {'DISPLAY': ':0', 00:46:31 INFO - 'HOME': '/home/cltbld', 00:46:31 INFO - 'LANG': 'en_US.UTF-8', 00:46:31 INFO - 'LANGUAGE': 'en_US:en', 00:46:31 INFO - 'LOGNAME': 'cltbld', 00:46:31 INFO - 'MAIL': '/var/mail/cltbld', 00:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:31 INFO - 'MOZ_NO_REMOTE': '1', 00:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:31 INFO - 'NO_EM_RESTART': '1', 00:46:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:31 INFO - 'PWD': '/builds/slave/test', 00:46:31 INFO - 'SHELL': '/bin/bash', 00:46:31 INFO - 'SHLVL': '1', 00:46:31 INFO - 'TERM': 'linux', 00:46:31 INFO - 'TMOUT': '86400', 00:46:31 INFO - 'USER': 'cltbld', 00:46:31 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:31 INFO - '_': '/tools/buildbot/bin/python'} 00:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:31 INFO - Downloading/unpacking blobuploader==1.2.4 00:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:32 INFO - Downloading blobuploader-1.2.4.tar.gz 00:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:46:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:46:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:33 INFO - Downloading docopt-0.6.1.tar.gz 00:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:46:33 INFO - Installing collected packages: blobuploader, requests, docopt 00:46:33 INFO - Running setup.py install for blobuploader 00:46:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:46:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:46:33 INFO - Running setup.py install for requests 00:46:34 INFO - Running setup.py install for docopt 00:46:34 INFO - Successfully installed blobuploader requests docopt 00:46:34 INFO - Cleaning up... 00:46:34 INFO - Return code: 0 00:46:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:46:34 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')]} 00:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:34 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')]} 00:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:34 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:34 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 00:46:34 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 00:46:34 INFO - Using env: {'DISPLAY': ':0', 00:46:34 INFO - 'HOME': '/home/cltbld', 00:46:34 INFO - 'LANG': 'en_US.UTF-8', 00:46:34 INFO - 'LANGUAGE': 'en_US:en', 00:46:34 INFO - 'LOGNAME': 'cltbld', 00:46:34 INFO - 'MAIL': '/var/mail/cltbld', 00:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:34 INFO - 'MOZ_NO_REMOTE': '1', 00:46:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:34 INFO - 'NO_EM_RESTART': '1', 00:46:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:34 INFO - 'PWD': '/builds/slave/test', 00:46:34 INFO - 'SHELL': '/bin/bash', 00:46:34 INFO - 'SHLVL': '1', 00:46:34 INFO - 'TERM': 'linux', 00:46:34 INFO - 'TMOUT': '86400', 00:46:34 INFO - 'USER': 'cltbld', 00:46:34 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:34 INFO - '_': '/tools/buildbot/bin/python'} 00:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:46:34 INFO - Running setup.py (path:/tmp/pip-0hAPmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:46:34 INFO - Running setup.py (path:/tmp/pip-6F5I30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:46:34 INFO - Running setup.py (path:/tmp/pip-gAmtal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:46:34 INFO - Running setup.py (path:/tmp/pip-t6rEQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:46:34 INFO - Running setup.py (path:/tmp/pip-PkOMUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:46:34 INFO - Running setup.py (path:/tmp/pip-vM4yvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:46:34 INFO - Running setup.py (path:/tmp/pip-UdAJO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:46:34 INFO - Running setup.py (path:/tmp/pip-j5CJAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:46:34 INFO - Running setup.py (path:/tmp/pip-fRkaIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:46:35 INFO - Running setup.py (path:/tmp/pip-0JZMfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:46:35 INFO - Running setup.py (path:/tmp/pip-JZzYcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:46:35 INFO - Running setup.py (path:/tmp/pip-mRkBeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:46:35 INFO - Running setup.py (path:/tmp/pip-um9mVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:46:35 INFO - Running setup.py (path:/tmp/pip-Z96THV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:46:35 INFO - Running setup.py (path:/tmp/pip-1gFNrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:46:35 INFO - Running setup.py (path:/tmp/pip-U34ejj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:46:35 INFO - Running setup.py (path:/tmp/pip-HWCpZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:46:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:46:35 INFO - Running setup.py install for manifestparser 00:46:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:46:35 INFO - Running setup.py install for mozcrash 00:46:35 INFO - Running setup.py install for mozdebug 00:46:35 INFO - Running setup.py install for mozdevice 00:46:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Running setup.py install for mozfile 00:46:36 INFO - Running setup.py install for mozhttpd 00:46:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Running setup.py install for mozinfo 00:46:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Running setup.py install for mozInstall 00:46:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Running setup.py install for mozleak 00:46:36 INFO - Running setup.py install for mozlog 00:46:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Running setup.py install for moznetwork 00:46:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Running setup.py install for mozprocess 00:46:36 INFO - Running setup.py install for mozprofile 00:46:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:46:36 INFO - Running setup.py install for mozrunner 00:46:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:46:37 INFO - Running setup.py install for mozscreenshot 00:46:37 INFO - Running setup.py install for moztest 00:46:37 INFO - Running setup.py install for mozversion 00:46:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:46:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:46:37 INFO - Cleaning up... 00:46:37 INFO - Return code: 0 00:46:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:46:37 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')]} 00:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:37 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')]} 00:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:37 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:37 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 00:46:37 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 00:46:37 INFO - Using env: {'DISPLAY': ':0', 00:46:37 INFO - 'HOME': '/home/cltbld', 00:46:37 INFO - 'LANG': 'en_US.UTF-8', 00:46:37 INFO - 'LANGUAGE': 'en_US:en', 00:46:37 INFO - 'LOGNAME': 'cltbld', 00:46:37 INFO - 'MAIL': '/var/mail/cltbld', 00:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:37 INFO - 'MOZ_NO_REMOTE': '1', 00:46:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:37 INFO - 'NO_EM_RESTART': '1', 00:46:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:37 INFO - 'PWD': '/builds/slave/test', 00:46:37 INFO - 'SHELL': '/bin/bash', 00:46:37 INFO - 'SHLVL': '1', 00:46:37 INFO - 'TERM': 'linux', 00:46:37 INFO - 'TMOUT': '86400', 00:46:37 INFO - 'USER': 'cltbld', 00:46:37 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:37 INFO - '_': '/tools/buildbot/bin/python'} 00:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:46:37 INFO - Running setup.py (path:/tmp/pip-aV0iPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:46:37 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)) 00:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:46:37 INFO - Running setup.py (path:/tmp/pip-FqgQ8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:46:37 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)) 00:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:46:37 INFO - Running setup.py (path:/tmp/pip-kTszkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:46:37 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)) 00:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:46:37 INFO - Running setup.py (path:/tmp/pip-9SsdEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:46:37 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)) 00:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:46:37 INFO - Running setup.py (path:/tmp/pip-ZPgndB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:46:38 INFO - Running setup.py (path:/tmp/pip-JQMKCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:46:38 INFO - Running setup.py (path:/tmp/pip-04WH0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:46:38 INFO - Running setup.py (path:/tmp/pip-MQ6wfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:46:38 INFO - Running setup.py (path:/tmp/pip-1xGHFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:46:38 INFO - Running setup.py (path:/tmp/pip-YVdbmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:46:38 INFO - Running setup.py (path:/tmp/pip-9etU1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:46:38 INFO - Running setup.py (path:/tmp/pip-OJ6hRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:46:38 INFO - Running setup.py (path:/tmp/pip-RYBpLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:46:38 INFO - Running setup.py (path:/tmp/pip-sKg0w0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:46:38 INFO - Running setup.py (path:/tmp/pip-8RYGjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:46:38 INFO - Running setup.py (path:/tmp/pip-gUd6Tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:46:38 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)) 00:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:46:38 INFO - Running setup.py (path:/tmp/pip-zAUDDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:46:38 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)) 00:46:38 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)) 00:46:38 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)) 00:46:38 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)) 00:46:38 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)) 00:46:38 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)) 00:46:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:46:38 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 00:46:38 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 00:46:38 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 00:46:38 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 00:46:39 INFO - Downloading blessings-1.6.tar.gz 00:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:46:40 INFO - Installing collected packages: blessings 00:46:40 INFO - Running setup.py install for blessings 00:46:40 INFO - Successfully installed blessings 00:46:40 INFO - Cleaning up... 00:46:40 INFO - Return code: 0 00:46:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:46:40 INFO - Reading from file tmpfile_stdout 00:46:40 INFO - Current package versions: 00:46:40 INFO - argparse == 1.2.1 00:46:40 INFO - blessings == 1.6 00:46:40 INFO - blobuploader == 1.2.4 00:46:40 INFO - docopt == 0.6.1 00:46:40 INFO - functools32 == 3.2.3-2 00:46:40 INFO - jsonschema == 2.5.1 00:46:40 INFO - manifestparser == 1.1 00:46:40 INFO - mozInstall == 1.12 00:46:40 INFO - mozcrash == 1.0 00:46:40 INFO - mozdebug == 0.1 00:46:40 INFO - mozdevice == 0.49 00:46:40 INFO - mozfile == 1.2 00:46:40 INFO - mozhttpd == 0.7 00:46:40 INFO - mozinfo == 0.9 00:46:40 INFO - mozleak == 0.1 00:46:40 INFO - mozlog == 3.4 00:46:40 INFO - moznetwork == 0.27 00:46:40 INFO - mozprocess == 0.25 00:46:40 INFO - mozprofile == 0.28 00:46:40 INFO - mozrunner == 6.13 00:46:40 INFO - mozscreenshot == 0.1 00:46:40 INFO - mozsystemmonitor == 0.3 00:46:40 INFO - moztest == 0.8 00:46:40 INFO - mozversion == 1.4 00:46:40 INFO - psutil == 3.1.1 00:46:40 INFO - requests == 1.2.3 00:46:40 INFO - wsgiref == 0.1.2 00:46:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:46:40 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')]} 00:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:40 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')]} 00:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:40 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:40 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 00:46:40 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 00:46:40 INFO - Using env: {'DISPLAY': ':0', 00:46:40 INFO - 'HOME': '/home/cltbld', 00:46:40 INFO - 'LANG': 'en_US.UTF-8', 00:46:40 INFO - 'LANGUAGE': 'en_US:en', 00:46:40 INFO - 'LOGNAME': 'cltbld', 00:46:40 INFO - 'MAIL': '/var/mail/cltbld', 00:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:40 INFO - 'MOZ_NO_REMOTE': '1', 00:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:40 INFO - 'NO_EM_RESTART': '1', 00:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:40 INFO - 'PWD': '/builds/slave/test', 00:46:40 INFO - 'SHELL': '/bin/bash', 00:46:40 INFO - 'SHLVL': '1', 00:46:40 INFO - 'TERM': 'linux', 00:46:40 INFO - 'TMOUT': '86400', 00:46:40 INFO - 'USER': 'cltbld', 00:46:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:40 INFO - '_': '/tools/buildbot/bin/python'} 00:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 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)) 00:46:40 INFO - Cleaning up... 00:46:40 INFO - Return code: 0 00:46:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:46:40 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')]} 00:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:40 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')]} 00:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:40 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 0xbc31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe61310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, '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': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', '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 00:46:40 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 00:46:40 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 00:46:40 INFO - Using env: {'DISPLAY': ':0', 00:46:40 INFO - 'HOME': '/home/cltbld', 00:46:40 INFO - 'LANG': 'en_US.UTF-8', 00:46:40 INFO - 'LANGUAGE': 'en_US:en', 00:46:40 INFO - 'LOGNAME': 'cltbld', 00:46:40 INFO - 'MAIL': '/var/mail/cltbld', 00:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:40 INFO - 'MOZ_NO_REMOTE': '1', 00:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:40 INFO - 'NO_EM_RESTART': '1', 00:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:40 INFO - 'PWD': '/builds/slave/test', 00:46:40 INFO - 'SHELL': '/bin/bash', 00:46:40 INFO - 'SHLVL': '1', 00:46:40 INFO - 'TERM': 'linux', 00:46:40 INFO - 'TMOUT': '86400', 00:46:40 INFO - 'USER': 'cltbld', 00:46:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:40 INFO - '_': '/tools/buildbot/bin/python'} 00:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:46:40 INFO - Cleaning up... 00:46:40 INFO - Return code: 0 00:46:40 INFO - Running post-action listener: _resource_record_post_action 00:46:40 INFO - Running post-action listener: _start_resource_monitoring 00:46:40 INFO - Starting resource monitoring. 00:46:40 INFO - [mozharness: 2017-06-01 07:46:40.905637Z] Finished create-virtualenv step (success) 00:46:40 INFO - [mozharness: 2017-06-01 07:46:40.906113Z] Running install step. 00:46:40 INFO - Running pre-action listener: _resource_record_pre_action 00:46:40 INFO - Running main action method: install 00:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:46:41 INFO - Reading from file tmpfile_stdout 00:46:41 INFO - Detecting whether we're running mozinstall >=1.0... 00:46:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:46:41 INFO - Reading from file tmpfile_stdout 00:46:41 INFO - Output received: 00:46:41 INFO - Usage: mozinstall [options] installer 00:46:41 INFO - Options: 00:46:41 INFO - -h, --help show this help message and exit 00:46:41 INFO - -d DEST, --destination=DEST 00:46:41 INFO - Directory to install application into. [default: 00:46:41 INFO - "/builds/slave/test"] 00:46:41 INFO - --app=APP Application being installed. [default: firefox] 00:46:41 INFO - mkdir: /builds/slave/test/build/application 00:46:41 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'] 00:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 00:46:55 INFO - Reading from file tmpfile_stdout 00:46:55 INFO - Output received: 00:46:55 INFO - /builds/slave/test/build/application/firefox/firefox 00:46:55 INFO - Running post-action listener: _resource_record_post_action 00:46:55 INFO - [mozharness: 2017-06-01 07:46:55.230567Z] Finished install step (success) 00:46:55 INFO - [mozharness: 2017-06-01 07:46:55.230810Z] Running run-tests step. 00:46:55 INFO - Running pre-action listener: _resource_record_pre_action 00:46:55 INFO - Running main action method: run_tests 00:46:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:46:55 INFO - Python 2.7.3 00:46:55 INFO - Return code: 0 00:46:55 INFO - grabbing minidump binary from tooltool 00:46: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')]} 00:46:55 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 0xe5e400>, '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 0xe5f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5f840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:46:55 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 00:46:55 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 00:46:55 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 00:46:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:46:55 INFO - Return code: 0 00:46:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:46:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:46:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:46:55 INFO - ENV: RUST_BACKTRACE is now 1 00:46:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:46:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:46:55 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/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 00:46:55 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/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 00:46:55 INFO - Using env: {'DISPLAY': ':0', 00:46:55 INFO - 'HOME': '/home/cltbld', 00:46:55 INFO - 'LANG': 'en_US.UTF-8', 00:46:55 INFO - 'LANGUAGE': 'en_US:en', 00:46:55 INFO - 'LOGNAME': 'cltbld', 00:46:55 INFO - 'MAIL': '/var/mail/cltbld', 00:46:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:46:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:55 INFO - 'MOZ_NO_REMOTE': '1', 00:46:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:46:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:55 INFO - 'NO_EM_RESTART': '1', 00:46:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:55 INFO - 'PWD': '/builds/slave/test', 00:46:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:46:55 INFO - 'RUST_BACKTRACE': '1', 00:46:55 INFO - 'SHELL': '/bin/bash', 00:46:55 INFO - 'SHLVL': '1', 00:46:55 INFO - 'TERM': 'linux', 00:46:55 INFO - 'TMOUT': '86400', 00:46:55 INFO - 'USER': 'cltbld', 00:46:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1496301783.65284-1210956103', 00:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:55 INFO - '_': '/tools/buildbot/bin/python'} 00:46:55 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/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 00:46:55 INFO - mozversion application_buildid: 20170601064714 00:46:55 INFO - mozversion application_changeset: c2332a18922bace569fdbca5cb0f43a82b211ed3 00:46:55 INFO - mozversion application_display_name: Firefox 00:46:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:46:55 INFO - mozversion application_name: Firefox 00:46:55 INFO - mozversion application_remotingname: firefox 00:46:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 00:46:55 INFO - mozversion application_vendor: Mozilla 00:46:55 INFO - mozversion application_version: 54.0 00:46:55 INFO - mozversion platform_buildid: 20170601064714 00:46:55 INFO - mozversion platform_changeset: c2332a18922bace569fdbca5cb0f43a82b211ed3 00:46:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 00:46:55 INFO - mozversion platform_version: 54.0 00:46:55 INFO - using testdate: 1496303215 00:46:55 INFO - actual date: 1496303215 00:46:55 INFO - starting webserver on 'localhost:52548' 00:46:55 INFO - SUITE-START | Running 2 tests 00:46:55 INFO - TEST-START | dromaeo_css 00:46:55 INFO - operating with platform_type : linux_ 00:46:55 INFO - Initialising browser for dromaeo_css test... 00:46:55 INFO - TEST-INFO | started process 4810 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDCGdXO/profile http://localhost:52548/getInfo.html) 00:46:59 INFO - PROCESS | 4810 | __metrics Screen width/height:1600/1200 00:46:59 INFO - PROCESS | 4810 | colorDepth:24 00:46:59 INFO - PROCESS | 4810 | Browser inner width/height: 1024/697 00:46:59 INFO - PROCESS | 4810 | __metrics 00:47:00 INFO - PROCESS | 4810 | 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" 00:47:00 INFO - PROCESS | 4810 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:47:01 INFO - TEST-INFO | 4810: exit 0 00:47:01 INFO - Browser initialized. 00:47:01 INFO - Running cycle 1/1 for dromaeo_css test... 00:47:01 INFO - TEST-INFO | started process 4904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDCGdXO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:47:13 INFO - PROCESS | 4904 | RSS: Main: 141570048 00:47:13 INFO - PROCESS | 4904 | 00:50:14 INFO - PROCESS | 4904 | Cycle 1(1): loaded http://localhost:52548/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52548/tests/dromaeo/cssquery-ext.html) 00:50:16 INFO - PROCESS | 4904 | RSS: Main: 203816960 00:50:16 INFO - PROCESS | 4904 | 00:53:18 INFO - PROCESS | 4904 | Cycle 1(1): loaded http://localhost:52548/tests/dromaeo/cssquery-ext.html (next: http://localhost:52548/tests/dromaeo/cssquery-jquery.html) 00:53:19 INFO - PROCESS | 4904 | RSS: Main: 226361344 00:53:19 INFO - PROCESS | 4904 | 00:56:21 INFO - PROCESS | 4904 | Cycle 1(1): loaded http://localhost:52548/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52548/tests/dromaeo/cssquery-mootools.html) 00:56:22 INFO - PROCESS | 4904 | RSS: Main: 171204608 00:56:22 INFO - PROCESS | 4904 | 00:59:23 INFO - PROCESS | 4904 | Cycle 1(1): loaded http://localhost:52548/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52548/tests/dromaeo/cssquery-prototype.html) 00:59:24 INFO - PROCESS | 4904 | RSS: Main: 200466432 00:59:24 INFO - PROCESS | 4904 | 01:02:27 INFO - PROCESS | 4904 | Cycle 1(1): loaded http://localhost:52548/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52548/tests/dromaeo/cssquery-yui.html) 01:02:27 INFO - PROCESS | 4904 | RSS: Main: 171016192 01:02:27 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | Cycle 1(1): loaded http://localhost:52548/tests/dromaeo/cssquery-yui.html (next: http://localhost:52548/tests/dromaeo/cssquery-dojo.html) 01:05:30 INFO - PROCESS | 4904 | RSS: Main: 265801728 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | __start_tp_report 01:05:30 INFO - PROCESS | 4904 | _x_x_mozilla_page_load 01:05:30 INFO - PROCESS | 4904 | _x_x_mozilla_page_load_details 01:05:30 INFO - PROCESS | 4904 | |i|pagename|runs| 01:05:30 INFO - PROCESS | 4904 | |0;dojo.html;3376.57792775903;3412.4710669846177;3419.9487007694875;3446.0867870014436;3462.0306314232007;5661.4055524169935;5665.206871038048;5668.234788303573;5714;5734.971325143374;5685.687287199203;5767.596268261213;5769;5771.682557459338;5772.0476121439915;5201.453847346031;5221.791128354861;5229.372475302969;5255;5289.8942021159555;5015.724135172564;5063.544281014708;5307.416184219733;5317.12267475866;5318.734063296839;5188.221766734982;5206.895862082756;5209.244659524366;5221.451747566508;5229.60777941654;5285.8942821143555;5286.31277933868;5289.021531016774;5291.3385826771655;5297;4932.852014439537;5150.201718733602;5212.791488340462;5248.580113590941;5273.947260527422;5470.2888624478965;5588.050031494615;5617.915731264035;5642.830715078554;5644.74598643062;6073.271207455134;6110.358412366682;6127.325994140641;6137.508999280047;6145.0167973124535;5925.762969481216;6156.8768624627255;6178.598391104609;6179.443850053472;6196.566240363177;5629.408912064257;5700.144978253254;5731.398203188605;5733.197352370713;5744.971275143597;6186.907196392058;6225.657588832658;6237.09562113491;6237.376262373817;6242.282137554149;5967.462928336384;6023.066424704178;6033.909491357633;6039.969800151059;6053.788117415869;5909.261342332208;5943.970280148572;6121.204243448412;6124.441964285689;6127.525510585588;6054.939450605526;6100.359462256399;6103.054026625791;6104.755809767648;6128.908066379008;5845.678487683218;5867.823965281048;5868.501177399883;5878.735456904449;6136.447719705248;4574.130915126182;4626.629869610423;4627.444706635225;4627.699199552018;4682.836100736458;4219.767912764766;4242.8727138185905;4244.533101358848;4249.957500424956;4267.359896015622;5619.409961953936;5654.547636189095;5806.564507661942;5815.447532484407;5818.272715910512;6088.086786981989;6096.451319381277;6114.541409394314;6116.724747386289;6133.96933015341;5790.276215473066;5797.159411885253;5800.984827655144;5804.506616937608;5804.970975145097;5059.089363914531;5233.659812112201;5235.476452354734;5239.240310155083;5241.580673546107;6193.81418557444;6218.284897236695;6225;6235.064740288993;6240.625562466266;6036.843601754055;6043.09353596964;6047.879042419039;6055.576109672457;6058.636481810929;6229.314775374614;6252.312245652884;6263.467605253422;6286.214223222098;6302.84242893913;5735.655860648374;5759.424057594207;5945.10823376497;5972.432618901197;6010.038393856962;6148.4773794227995;6151.446369826737;6152.907706384497;6156.045812899008;6161.168242287413;6017.909731354121;6018.217631708025;6019;6020.488258498077;6053.061775424816;5750.712464376681;5784.50831679312;5932.1694962706115;5970.134330521963;6026.758929642765;5928.407159284209;5929.6145750526075;5980.37206093354;6037.184980027816;6052.818415447544;5804.187413762161;6029.155918171372;6031.125486804476;6031.7587296507645;6036.9094463582205;5697.7720891165545;5786.768529258782;5791.218185544897;5812.3025236971835;5886.558508111823 01:05:30 INFO - PROCESS | 4904 | |1;ext.html;816.6978218059318;835.8704400817871;913.5112714697638;924.4268553496833;924.5331107790569;43786.78106609466;44460.77769611155;44681;44751.55248447515;44879.55120448794;41945.7902710486;42327;45545.7722711386;45595.544044559545;45619.543804562025;9128.132827381405;9260.629574817;9269.536523173849;9272.953635231848;9273.489958052305;7500.362469190125;7737.342325902305;10660.626878059269;10850.457477126112;10864.185186111034;10921.672349829478;10928.836067458995;10943.179261555375;10946;10947.726306842313;10878.61924832631;10888.564457421693;10919.12646988239;10919.781604367869;10922.617708380249;10701.732456688567;10732.463376831127;10769.246152769309;10772.569097236179;10785.191110666732;41750.37374439386;41755.582444175474;41975.1604967902;41985;42015.159696805895;4177.624013838738;4187.937180942288;4227.408162857172;4281.6574674026315;4309.78451077448;30070.398592028036;30408.239794005105;30751.231219219473;31230.84384578085;31661;4249.192653395845;4321.395004699345;4336.718113322624;4366.366876802846;4454.77726113693;222167;222365.99999999837;222415;223422;224859;49260.50739492631;49994;50416.747916260916;51161.23258151131;51170;49672.25491617629;49675.25487117696;49800.25299620508;49960.750196249515;50329.24506132411;16670.749938750927;16761.245743941552;16762.58093547671;16765.413210537572;16769.832301676826;14789.630259243606;14791.556253312418;14854.331555080047;14854.777178342334;14870.851291487163;13177.934110329386;13202.801957970447;13219.867801321863;13261.535846245335;13277.269750163834;5527.429745168435;5545.750441230073;5556.111022236423;5627.7467513961155;5644.74598643062;1033.1528146919454;1034.1933292032227;1040.5837664933936;1043.671243558292;1047.5966752800227;28475.43049139047;28568.285792854927;28638.97097166671;28720.425591488052;28732;1090.8292497929222;1098.2586753941057;1107.145531235178;1112.2881355932209;1136.4999400263857;14794.556163315117;15486.380544778309;15495.14776687293;15548.533543993699;15626.718389138181;306648;306650;306984;307507;315250;56519.43480565224;56679;56756;56778.14832777512;56865.43134568684;1492.8133983252094;1505.4881340344336;1514.1823415355584;1521.8782497400402;1525.7940178075817;27486.17541473759;27578.44843103167;28680.569791452726;28739.28151796222;29099.7090029097;36063.63936360708;36131;36141;36218.63781362153;36245.81877090598;282190;283233;430475;438605;442896;25716.357091072125;26098.739012609632;27239.182824515297;28000.43999120047;29321.7067829319;40294;40607.18785624212;40890.79554602208;40895;40912;31429.8428507856;31485.370292594478;31585;31777.364452711277;32027.519587206676;30939.84530077335;31139.68860311368;31193.53209701811;31236.531452027783;31795.523067154474;34920.82539587286;34961.475577865844;35379.11552211215;35542.28915421729;36075.45886811752;30024;30092.849535752182;30255.394892101594;30346.393072138875;30894.382112357176;23576.882115589997;24774.876125619256;24871.253862384157;24954.62568061517;25142.62286065674 01:05:30 INFO - PROCESS | 4904 | |2;jquery.html;432.7749570223486;454.68626647613144;459.5863722649615;478.7151644771361;491.62390771060143;19680.803191968076;21400.89299553502;22939.082436702512;22954.88522557387;23186.65220021697;22487.887560562176;22559.887200563975;22915;22975.310740677764;23329.5334093318;6703.329667033282;7000.089988301514;7288.781336559885;7500.287472690113;7584.924150758477;7497.1753107158165;7643.694252229904;7687.884681729778;7693.076830780315;7750.728724494644;7790.298873101419;7792.064952205743;7793.76618701437;7795.376369890423;7800.804979875492;7506.512076714996;7649.808754781147;7702;7716.035495563055;7717.112532058801;7285.7814265572115;7505.924940750577;7551.056117985252;7598.164201937782;7606.505577137468;25181.748182518124;25315.493690126095;25530.744692553024;25605.3598660035;25961;3180.9522857157162;3299.2081900343746;3357.0600231935096;3378.577677790276;3414.9487757683655;15665.608359790982;16185.514434566981;16470.258838352303;16622.50132496015;16926.830731692648;2154.4721543222013;2225.109956017606;2264.558411109834;2267.5918334699745;2280.1335492512903;85398.57300713519;96067.51966240194;99881.5005924973;103227;110172.4491377546;17733.822661773218;18338.724919126224;18602.25590976346;18765.437036888914;19791.703124453146;32027.83986080055;32256.83871580674;32345.35309293848;32387.838060809547;32927.83536082304;10757.354558726345;10978.670639880656;11017.11863050954;11021.228514004104;11260.042896353738;9316.410153907602;9341.532923353805;9362.204212641866;9600.135987761136;9624.326297159272;8918.420302680435;9494.145526902612;9642.325037247465;9669.516524173763;9786.41281523111;3381.746369022333;3435.6736110069505;3497.7901325920525;3912.256671232457;3924.823382947717;965.4882912056621;984.1831280037552;985.5417230987625;994.9602015919427;995.9203263738883;20756.896215518827;21370.572588548453;21667.458313542287;21708;21884.671729924063;1102.9227954043301;1124.1849658997162;1126.4367816091954;1134.7163209197702;1152.3086148311113;11108.55565777376;11721.296722196696;11842.111841611913;11874.406279685982;11876.88123118775;627922;629836;641353;649819;663498;89074;116350;117295.41352293183;119742.40128799474;124725.37637311756;1314.8487923888874;1377.8415482219473;1380.6203294094205;1384.9099808512415;1391.7494850926728;18449.07754612291;19904.30334938328;19968.201271948958;19969.900150499736;20021.499462513555;52342.738286308326;53279.46720532745;53450.73274633602;53702.19446708224;54075.459245407044;66953;67944.98082528668;67994.66002669955;68079;68667;12649.241045537297;12886.613401597968;12941.482340706643;12993.155444896052;13215.933920330337;13945.860541394457;14064.507742229382;14611.85388146105;14677.41290348374;14690.485832995793;11770.58802941893;12621.305828179538;12760.234385936874;12902.161359511601;13236.669083272995;11583.884161158281;11589.362585057566;11669.533218671497;11753.823692644788;11921.165518413627;12844.293563854078;12858.421371038512;12889.677758056123;12955.352232388532;13021.934890325489;12276.815847762113;12351.135420520837;12543.882734409439;12750.553730619384;12775.339205903174;12378.62864114078;12467.812982805084;12485;12551.372431378211;12713.745725085631 01:05:30 INFO - PROCESS | 4904 | |3;mootools.html;1583.1371902313238;1603.414753614931;1614.2251719174797;1614.547926580557;1622.6511300070483;10428.530716117773;11076.224664273484;11079.113670906328;11085.889141108586;11086.445677716123;10467.057964783167;10687.78624427511;10691.091257243124;10702.250842441032;10854;2545.9236222913296;2581.7160112387623;2583.6641236639302;2583.9354016149655;2588.508183445149;2451.767082127195;2513.773760361576;2550.9489810203786;2568.4092658688514;2569.4218800769772;2470.3700556358103;2509.749025097494;2607.947841043178;2611.308003379106;2611.5560354739646;2406.651035599837;2467.0255249176566;2470.3083136721657;2497.700275966896;2506.0602274147195;2441.9145329913545;2458.4591389894194;2465.3713303107593;2470.258922323314;2471.9011239550396;11519.712007199803;11752.177347585672;11759.05927525796;11770.882291177064;11775.293482391084;5968.97015514924;6107.725152368154;6107.816765497042;6113.755449782004;6114.602550834182;3386.255023894739;3428.434308339121;3488.4069708149677;3532.5760908690863;3615.439606860941;1291.7351942851787;1294.8187253784479;1327.502186679995;1335.4391155714527;1346.6902612399203;14930.477433289783;15302;15433.845661543466;15440.382384704712;16969.15154242283;4851.854444366674;4885.828995985124;5063.215201643751;5076.6192535559985;5115.769790359443;10381.117605003508;10620.309679870783;12463.065270104778;13256.337183140804;13258.53595124166;5826.563007774434;5890.087036509348;5898.14476900847;5902.704864756745;5903.58674892762;5465.508104270635;5465.508104270635;5467.535259502987;5558.555315574744;5664.348599910981;4229.365595160751;4283.20760659279;4299.871003869889;4320.827166913288;4330.437043184366;1116.6315116011697;1125.5216532973454;1134.444122380028;1154.7344110854551;1159.913006524514;1032.4992378696477;1042.0517329230363;1043.3531210649294;1050.2333296693457;1060.4962642744636;7102.68037938294;7155.463340249502;7173.461990350745;7269.527480713737;7325.5970921598755;1041.8385150301715;1047.800917825626;1060.591672206206;1080.1466841195524;1083.7344850511674;4545.931811022837;4564.178447879413;4600.585947264763;4645.9303110453375;4661.487236403993;14691.338889749986;15320.540383788504;15329.46346877854;15354.692906141814;15364.078155310717;15000.324985375684;15056;15222.923885380502;15246;15265.160416176996;11004.449777511092;11076;11091.611793587186;11093.223474356879;11113.11095112389;6633.204015517976;6659.966700166662;6693.431058359899;6708.765193218409;6742.69657865407;12516;13277.468901243841;13286;13303;13339;12469.438875250455;12766.29785361814;13931.651708706959;13940.721185576434;14136.363863625957;11152.275102118336;11313.377764223047;11443.027342675632;11447.25592836463;11453.255538389978;11167.441627918734;11241.66275011751;11337.659870203906;11358.0913526916;11362.204645674723;9256.861147082935;9331.486768227813;9469.621215151317;9481.620735170793;9589.664361747306;9440.669576564787;9543.141117299476;9632.518374081128;9747.805043899225;9891.752706182115;8578.485290882567;8605.698800541952;8609.139086091342;8688.13118688126;8696.130386961506;5907.70461476933;5993.160957465871;6103.572749907464;6129.754809807558;6197.690115494297;6125.632462052291;6127.969360153349;6131.202943617302;6178.443940045418;6206.224221972254 01:05:30 INFO - PROCESS | 4904 | |4;prototype.html;422.2989836870795;425.6297021591215;430.7716910037679;435.46655347199675;444.73916446873113;5106.234064890264;5202.635815492917;5276;5281.9207711884355;5291.603129765269;5137.743112844361;5150.201718733593;5155.7679904404295;5172.267290457701;5185.533302002819;4449.621782148505;4450.285325951168;4452.398926144978;4464.241079016575;4484.8878778030485;4222.788860556975;4422.7125236859665;4485.887852803689;4489.102179564084;4506.729596224237;4400.73595584266;4411.558844115595;4420.359047938047;4422;4422.292433210687;4418.5360537143615;4427.406133791832;4445.644348452116;4453.732776033416;4458.773837194781;3955.8330292563473;4198.181354635847;4222.641075508585;4228.4080228768;4234.618884300427;5033.798648054073;5087.771050302708;5095.617828662865;5124.129692662189;5150.8881358642775;5087.186050231945;5120.487951204887;5138.58891288696;5153.2270159476;5181.5854731621375;5286.735663216862;5462.945370546323;5505.752241149158;5537.145056406109;5541.473560011793;4593.701409408412;4597.310403439477;4601.573512211192;4604.58558729716;4615.399998000238;5509.311336082989;5566.304211973504;5635.464630860061;5737.196792449061;5840.299164100293;4640.303954406866;4660.13787449323;4664.603508701798;4700;4725.551072648162;4710.528947105262;4728.385309909759;4743.482085732601;4755.976220118877;4783.832565860178;4921.630877684188;4980.327655766425;5060.280693690135;5061.898762024739;5077.441481437039;5377.569794416438;5475.014497390508;5480.835574932758;5485.670859748428;5516.558675305966;1641.727661062686;1655.329591515438;1661.8088919774139;1671.7492376143675;1679.6892574873443;1240.6154092231257;1261.735035642507;1278.8912942399813;1285.9871401286055;1297.9026573007063;5215.869603259949;5243.397009343974;5264;5319.420081260663;5320.733963301819;5542.667439953616;5544.63959842609;5624.943750562524;5633.211350410946;5642.351129620064;5088.262201980692;5298.887233680893;5316;5353.678779273256;5366.382865970463;2969.9851500742357;2974.2267010576975;2987.8057926234724;2995.281132528221;3010.8494575271147;5661.2923384576925;5847.356790753014;5849.327327357323;5879.764809407661;5906.527477801851;5715.457031581993;5734.792105039506;5759.65442073477;5759.798407055733;5763.884722305615;5811.767529298865;5814.918093410414;5831.183634291203;5840.591158618856;5855.707214639337;5375.731213439234;5571.328601419477;5620.943790562042;5624.6625202487985;5637.3235211774845;5318.7074710891275;5530.198121272473;5545.195946587803;5552.194931734795;5608.943910560843;5703.346029651355;5744.885102298014;5763.279590051244;5774.220480235114;5783.334916484489;4630.6758526902795;4633.536646335339;4679.338834713628;4727.503612120678;4728.38530990969;5477.726113694379;5569.081101618188;5616.354119276172;5678.7728490861555;5697.316322041382;5484.670919744828;5490.588205884655;5539.529140023144;5574.358948720949;5588.916166257591;5476.315460567429;5481.451854814646;5537.169424586345;5573.693446860461;5583.69289689071;5259.4498713369085;5396.3524377075;5455.781768729206;5462.071447853954;5479.589030822624;5474.726263686879;5490.752916118704;5508.283923089973;5513.8345849624575;5519.69641669712;5198.582659063553;5441.918371224356;5443.918341224806;5460.180972854104;5476.835694929158 01:05:30 INFO - PROCESS | 4904 | |5;yui.html;849.817289282804;866.4281574161055;916.2029034739782;925.0501398965014;945.631203830506;16882;18081.367152149676;18175.72736408955;18212.271509139635;18238.179281932313;14637.341319640615;14789.778153327708;14801;14864.777028344584;14869.702605947901;697.4699228586286;853.8420392227426;907.8683590879318;917.0508523677995;925.0333401595316;902.3593248793364;908.9318301127441;910.968116115936;911.5260064766318;911.694582314922;970.4807927758651;970.6796757070185;970.8495183246608;971.9076687714655;973.8247115519204;956.784723437228;957.3968399908048;957.6408846682494;959.4051687953513;960.3901522533172;707.9469039822034;709.3261401668384;713.1371041040347;713.304528085118;714.039616715522;17011.48965531036;17015.149242537824;17708.203130859143;18741.625167496575;18901.62196756071;14400.711985760227;14500.202488863108;14750.188739619316;15000.099994000286;15014.399424023135;13624.863751362558;13686.777627115265;13688;13807.861921380758;14021.369038393297;3995.900102497461;4030.6170913763144;4035.8385664573384;4077.3272410052305;4084.8570300039655;82326.58836705839;82401;82485.17514824835;84196;84392.57803711004;13584.456621735217;13646.181229126285;13649.863501365058;13662.385192666354;13822.032457727862;26041;26520.60219096715;26883.596746048825;26984.865075674497;27121.728782712315;9086.954565227265;9125.224355929688;9128;9128.81742365149;9134.543272836332;4868.78090485929;4885.267209918469;4912.828051018198;5021.974890125599;5031.698098114125;3742.607026262258;3815.961840381616;3828.0429892526868;3856.093817952779;3865.613438656168;609.2445367743966;617.2685367839125;617.9721912513947;623.5074291309814;625.2497003595703;809.8704207326917;821.650798410673;834.1075049696888;837.2757564706485;852.424613385962;10296.794064118825;10811.729706757395;10819.404932728776;10904.836427453594;11295.209335346612;661.8841702702008;679.3953381490477;719.4352433339803;825.6449726617612;851.82537579796;4809.398825146856;5322.787088516416;5498;5636.464535869086;5804.622699524602;63924.68037659782;64224.67887660625;77833.22166778259;79565.60217198876;80861.76552937817;60128.69935650294;66006.66996665082;67906;68128.6593567039;68611.65694171497;59054.40945590489;60568;61009.389906101256;62002.68998655068;62065.68967155135;11302.886971130183;11464.31214127155;11590.478428470571;11752.58865939688;11918.702032449259;37408.43887341637;38335.42496862605;41310;41951.160976780906;42945.785271073444;50765.492345076076;51671.741641291555;55264.72367638136;55997.72001139968;57714.0943019848;14183.716325673222;14193.432262709788;14322.641933951318;14414.639634009234;14488.420463181776;13622.250776207404;13690.452381904612;13697.109687870257;14411;14762.114273143647;12649.177803442746;12750.872491275339;12914.354282286036;13690.794638080635;14675.779863302272;14927;14938.850611493746;15007.099574025593;15170.544883653509;16454.424095156613;14014.640676397048;14147.221902795445;14187.929060354632;14270.286485675882;14530.491432799801;10858.294210876267;10866.61966831157;11356.26184298018;11453.198276120926;11491.48288327044;11427.542898284308;12463.828853100329;12729.93635031856;12760.87239127634;13014 01:05:30 INFO - PROCESS | 4904 | __end_tp_report 01:05:30 INFO - PROCESS | 4904 | __start_cc_report 01:05:30 INFO - PROCESS | 4904 | _x_x_mozilla_cycle_collect,1487 01:05:30 INFO - PROCESS | 4904 | __end_cc_report 01:05:30 INFO - PROCESS | 4904 | __startTimestamp1496304330384__endTimestamp 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | ------- Summary: start ------- 01:05:30 INFO - PROCESS | 4904 | Number of tests: 6 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | [#0] dojo.html Cycles:165 Average:5661.29 Median:5815.45 stddev:616.47 (10.6%) stddev-sans-first:591.73 01:05:30 INFO - PROCESS | 4904 | Values: 3376.6 3412.5 3419.9 3446.1 3462.0 5661.4 5665.2 5668.2 5714.0 5735.0 5685.7 5767.6 5769.0 5771.7 5772.0 5201.5 5221.8 5229.4 5255.0 5289.9 5015.7 5063.5 5307.4 5317.1 5318.7 5188.2 5206.9 5209.2 5221.5 5229.6 5285.9 5286.3 5289.0 5291.3 5297.0 4932.9 5150.2 5212.8 5248.6 5273.9 5470.3 5588.1 5617.9 5642.8 5644.7 6073.3 6110.4 6127.3 6137.5 6145.0 5925.8 6156.9 6178.6 6179.4 6196.6 5629.4 5700.1 5731.4 5733.2 5745.0 6186.9 6225.7 6237.1 6237.4 6242.3 5967.5 6023.1 6033.9 6040.0 6053.8 5909.3 5944.0 6121.2 6124.4 6127.5 6054.9 6100.4 6103.1 6104.8 6128.9 5845.7 5867.8 5868.5 5878.7 6136.4 4574.1 4626.6 4627.4 4627.7 4682.8 4219.8 4242.9 4244.5 4250.0 4267.4 5619.4 5654.5 5806.6 5815.4 5818.3 6088.1 6096.5 6114.5 6116.7 6134.0 5790.3 5797.2 5801.0 5804.5 5805.0 5059.1 5233.7 5235.5 5239.2 5241.6 6193.8 6218.3 6225.0 6235.1 6240.6 6036.8 6043.1 6047.9 6055.6 6058.6 6229.3 6252.3 6263.5 6286.2 6302.8 5735.7 5759.4 5945.1 5972.4 6010.0 6148.5 6151.4 6152.9 6156.0 6161.2 6017.9 6018.2 6019.0 6020.5 6053.1 5750.7 5784.5 5932.2 5970.1 6026.8 5928.4 5929.6 5980.4 6037.2 6052.8 5804.2 6029.2 6031.1 6031.8 6036.9 5697.8 5786.8 5791.2 5812.3 5886.6 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | [#1] ext.html Cycles:180 Average:46395.17 Median:30822.81 stddev:82005.34 (266.1%) stddev-sans-first:82163.98 01:05:30 INFO - PROCESS | 4904 | Values: 816.7 835.9 913.5 924.4 924.5 43786.8 44460.8 44681.0 44751.6 44879.6 41945.8 42327.0 45545.8 45595.5 45619.5 9128.1 9260.6 9269.5 9273.0 9273.5 7500.4 7737.3 10660.6 10850.5 10864.2 10921.7 10928.8 10943.2 10946.0 10947.7 10878.6 10888.6 10919.1 10919.8 10922.6 10701.7 10732.5 10769.2 10772.6 10785.2 41750.4 41755.6 41975.2 41985.0 42015.2 4177.6 4187.9 4227.4 4281.7 4309.8 30070.4 30408.2 30751.2 31230.8 31661.0 4249.2 4321.4 4336.7 4366.4 4454.8 222167.0 222366.0 222415.0 223422.0 224859.0 49260.5 49994.0 50416.7 51161.2 51170.0 49672.3 49675.3 49800.3 49960.8 50329.2 16670.7 16761.2 16762.6 16765.4 16769.8 14789.6 14791.6 14854.3 14854.8 14870.9 13177.9 13202.8 13219.9 13261.5 13277.3 5527.4 5545.8 5556.1 5627.7 5644.7 1033.2 1034.2 1040.6 1043.7 1047.6 28475.4 28568.3 28639.0 28720.4 28732.0 1090.8 1098.3 1107.1 1112.3 1136.5 14794.6 15486.4 15495.1 15548.5 15626.7 306648.0 306650.0 306984.0 307507.0 315250.0 56519.4 56679.0 56756.0 56778.1 56865.4 1492.8 1505.5 1514.2 1521.9 1525.8 27486.2 27578.4 28680.6 28739.3 29099.7 36063.6 36131.0 36141.0 36218.6 36245.8 282190.0 283233.0 430475.0 438605.0 442896.0 25716.4 26098.7 27239.2 28000.4 29321.7 40294.0 40607.2 40890.8 40895.0 40912.0 31429.8 31485.4 31585.0 31777.4 32027.5 30939.8 31139.7 31193.5 31236.5 31795.5 34920.8 34961.5 35379.1 35542.3 36075.5 30024.0 30092.8 30255.4 30346.4 30894.4 23576.9 24774.9 24871.3 24954.6 25142.6 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | [#2] jquery.html Cycles:180 Average:37150.32 Median:22927.04 stddev:105664.74 (460.9%) stddev-sans-first:105925.19 01:05:30 INFO - PROCESS | 4904 | Values: 432.8 454.7 459.6 478.7 491.6 19680.8 21400.9 22939.1 22954.9 23186.7 22487.9 22559.9 22915.0 22975.3 23329.5 6703.3 7000.1 7288.8 7500.3 7584.9 7497.2 7643.7 7687.9 7693.1 7750.7 7790.3 7792.1 7793.8 7795.4 7800.8 7506.5 7649.8 7702.0 7716.0 7717.1 7285.8 7505.9 7551.1 7598.2 7606.5 25181.7 25315.5 25530.7 25605.4 25961.0 3181.0 3299.2 3357.1 3378.6 3414.9 15665.6 16185.5 16470.3 16622.5 16926.8 2154.5 2225.1 2264.6 2267.6 2280.1 85398.6 96067.5 99881.5 103227.0 110172.4 17733.8 18338.7 18602.3 18765.4 19791.7 32027.8 32256.8 32345.4 32387.8 32927.8 10757.4 10978.7 11017.1 11021.2 11260.0 9316.4 9341.5 9362.2 9600.1 9624.3 8918.4 9494.1 9642.3 9669.5 9786.4 3381.7 3435.7 3497.8 3912.3 3924.8 965.5 984.2 985.5 995.0 995.9 20756.9 21370.6 21667.5 21708.0 21884.7 1102.9 1124.2 1126.4 1134.7 1152.3 11108.6 11721.3 11842.1 11874.4 11876.9 627922.0 629836.0 641353.0 649819.0 663498.0 89074.0 116350.0 117295.4 119742.4 124725.4 1314.8 1377.8 1380.6 1384.9 1391.7 18449.1 19904.3 19968.2 19969.9 20021.5 52342.7 53279.5 53450.7 53702.2 54075.5 66953.0 67945.0 67994.7 68079.0 68667.0 12649.2 12886.6 12941.5 12993.2 13215.9 13945.9 14064.5 14611.9 14677.4 14690.5 11770.6 12621.3 12760.2 12902.2 13236.7 11583.9 11589.4 11669.5 11753.8 11921.2 12844.3 12858.4 12889.7 12955.4 13021.9 12276.8 12351.1 12543.9 12750.6 12775.3 12378.6 12467.8 12485.0 12551.4 12713.7 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | [#3] mootools.html Cycles:180 Average:7198.03 Median:2468.64 stddev:4538.01 (183.8%) stddev-sans-first:4531.13 01:05:30 INFO - PROCESS | 4904 | Values: 1583.1 1603.4 1614.2 1614.5 1622.7 10428.5 11076.2 11079.1 11085.9 11086.4 10467.1 10687.8 10691.1 10702.3 10854.0 2545.9 2581.7 2583.7 2583.9 2588.5 2451.8 2513.8 2550.9 2568.4 2569.4 2470.4 2509.7 2607.9 2611.3 2611.6 2406.7 2467.0 2470.3 2497.7 2506.1 2441.9 2458.5 2465.4 2470.3 2471.9 11519.7 11752.2 11759.1 11770.9 11775.3 5969.0 6107.7 6107.8 6113.8 6114.6 3386.3 3428.4 3488.4 3532.6 3615.4 1291.7 1294.8 1327.5 1335.4 1346.7 14930.5 15302.0 15433.8 15440.4 16969.2 4851.9 4885.8 5063.2 5076.6 5115.8 10381.1 10620.3 12463.1 13256.3 13258.5 5826.6 5890.1 5898.1 5902.7 5903.6 5465.5 5465.5 5467.5 5558.6 5664.3 4229.4 4283.2 4299.9 4320.8 4330.4 1116.6 1125.5 1134.4 1154.7 1159.9 1032.5 1042.1 1043.4 1050.2 1060.5 7102.7 7155.5 7173.5 7269.5 7325.6 1041.8 1047.8 1060.6 1080.1 1083.7 4545.9 4564.2 4600.6 4645.9 4661.5 14691.3 15320.5 15329.5 15354.7 15364.1 15000.3 15056.0 15222.9 15246.0 15265.2 11004.4 11076.0 11091.6 11093.2 11113.1 6633.2 6660.0 6693.4 6708.8 6742.7 12516.0 13277.5 13286.0 13303.0 13339.0 12469.4 12766.3 13931.7 13940.7 14136.4 11152.3 11313.4 11443.0 11447.3 11453.3 11167.4 11241.7 11337.7 11358.1 11362.2 9256.9 9331.5 9469.6 9481.6 9589.7 9440.7 9543.1 9632.5 9747.8 9891.8 8578.5 8605.7 8609.1 8688.1 8696.1 5907.7 5993.2 6103.6 6129.8 6197.7 6125.6 6128.0 6131.2 6178.4 6206.2 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | [#4] prototype.html Cycles:180 Average:4794.18 Median:5251.42 stddev:1262.39 (24.0%) stddev-sans-first:1222.54 01:05:30 INFO - PROCESS | 4904 | Values: 422.3 425.6 430.8 435.5 444.7 5106.2 5202.6 5276.0 5281.9 5291.6 5137.7 5150.2 5155.8 5172.3 5185.5 4449.6 4450.3 4452.4 4464.2 4484.9 4222.8 4422.7 4485.9 4489.1 4506.7 4400.7 4411.6 4420.4 4422.0 4422.3 4418.5 4427.4 4445.6 4453.7 4458.8 3955.8 4198.2 4222.6 4228.4 4234.6 5033.8 5087.8 5095.6 5124.1 5150.9 5087.2 5120.5 5138.6 5153.2 5181.6 5286.7 5462.9 5505.8 5537.1 5541.5 4593.7 4597.3 4601.6 4604.6 4615.4 5509.3 5566.3 5635.5 5737.2 5840.3 4640.3 4660.1 4664.6 4700.0 4725.6 4710.5 4728.4 4743.5 4756.0 4783.8 4921.6 4980.3 5060.3 5061.9 5077.4 5377.6 5475.0 5480.8 5485.7 5516.6 1641.7 1655.3 1661.8 1671.7 1679.7 1240.6 1261.7 1278.9 1286.0 1297.9 5215.9 5243.4 5264.0 5319.4 5320.7 5542.7 5544.6 5624.9 5633.2 5642.4 5088.3 5298.9 5316.0 5353.7 5366.4 2970.0 2974.2 2987.8 2995.3 3010.8 5661.3 5847.4 5849.3 5879.8 5906.5 5715.5 5734.8 5759.7 5759.8 5763.9 5811.8 5814.9 5831.2 5840.6 5855.7 5375.7 5571.3 5620.9 5624.7 5637.3 5318.7 5530.2 5545.2 5552.2 5608.9 5703.3 5744.9 5763.3 5774.2 5783.3 4630.7 4633.5 4679.3 4727.5 4728.4 5477.7 5569.1 5616.4 5678.8 5697.3 5484.7 5490.6 5539.5 5574.4 5588.9 5476.3 5481.5 5537.2 5573.7 5583.7 5259.4 5396.4 5455.8 5462.1 5479.6 5474.7 5490.8 5508.3 5513.8 5519.7 5198.6 5441.9 5443.9 5460.2 5476.8 01:05:30 INFO - PROCESS | 4904 | 01:05:30 INFO - PROCESS | 4904 | [#5] yui.html Cycles:180 Average:18052.03 Median:4081.09 stddev:21917.21 (537.0%) stddev-sans-first:21940.63 01:05:30 INFO - PROCESS | 4904 | Values: 849.8 866.4 916.2 925.1 945.6 16882.0 18081.4 18175.7 18212.3 18238.2 14637.3 14789.8 14801.0 14864.8 14869.7 697.5 853.8 907.9 917.1 925.0 902.4 908.9 911.0 911.5 911.7 970.5 970.7 970.8 971.9 973.8 956.8 957.4 957.6 959.4 960.4 707.9 709.3 713.1 713.3 714.0 17011.5 17015.1 17708.2 18741.6 18901.6 14400.7 14500.2 14750.2 15000.1 15014.4 13624.9 13686.8 13688.0 13807.9 14021.4 3995.9 4030.6 4035.8 4077.3 4084.9 82326.6 82401.0 82485.2 84196.0 84392.6 13584.5 13646.2 13649.9 13662.4 13822.0 26041.0 26520.6 26883.6 26984.9 27121.7 9087.0 9125.2 9128.0 9128.8 9134.5 4868.8 4885.3 4912.8 5022.0 5031.7 3742.6 3816.0 3828.0 3856.1 3865.6 609.2 617.3 618.0 623.5 625.2 809.9 821.7 834.1 837.3 852.4 10296.8 10811.7 10819.4 10904.8 11295.2 661.9 679.4 719.4 825.6 851.8 4809.4 5322.8 5498.0 5636.5 5804.6 63924.7 64224.7 77833.2 79565.6 80861.8 60128.7 66006.7 67906.0 68128.7 68611.7 59054.4 60568.0 61009.4 62002.7 62065.7 11302.9 11464.3 11590.5 11752.6 11918.7 37408.4 38335.4 41310.0 41951.2 42945.8 50765.5 51671.7 55264.7 55997.7 57714.1 14183.7 14193.4 14322.6 14414.6 14488.4 13622.3 13690.5 13697.1 14411.0 14762.1 12649.2 12750.9 12914.4 13690.8 14675.8 14927.0 14938.9 15007.1 15170.5 16454.4 14014.6 14147.2 14187.9 14270.3 14530.5 10858.3 10866.6 11356.3 11453.2 11491.5 11427.5 12463.8 12729.9 12760.9 13014.0 01:05:30 INFO - PROCESS | 4904 | -------- Summary: end -------- 01:05:30 INFO - PROCESS | 4904 | 01:05:31 INFO - TEST-INFO | 4904: exit 0 01:05:31 INFO - TEST-OK | dromaeo_css | took 1115654ms 01:05:31 INFO - TEST-START | kraken 01:05:31 INFO - operating with platform_type : linux_ 01:05:31 INFO - Initialising browser for kraken test... 01:05:31 INFO - TEST-INFO | started process 5208 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFFJWBO/profile http://localhost:52548/getInfo.html) 01:05:35 INFO - PROCESS | 5208 | __metrics Screen width/height:1600/1200 01:05:35 INFO - PROCESS | 5208 | colorDepth:24 01:05:35 INFO - PROCESS | 5208 | Browser inner width/height: 1024/697 01:05:35 INFO - PROCESS | 5208 | __metrics 01:05:36 INFO - PROCESS | 5208 | 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" 01:05:36 INFO - PROCESS | 5208 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:05:37 INFO - TEST-INFO | 5208: exit 0 01:05:37 INFO - Browser initialized. 01:05:37 INFO - Running cycle 1/1 for kraken test... 01:05:37 INFO - TEST-INFO | started process 5300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFFJWBO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:05:49 INFO - PROCESS | 5300 | RSS: Main: 141180928 01:05:49 INFO - PROCESS | 5300 | 01:05:51 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52548/tests/kraken/driver.html?testName=audio-beat-detection) 01:05:52 INFO - PROCESS | 5300 | RSS: Main: 184328192 01:05:52 INFO - PROCESS | 5300 | 01:05:54 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52548/tests/kraken/driver.html?testName=audio-dft) 01:05:55 INFO - PROCESS | 5300 | RSS: Main: 173907968 01:05:55 INFO - PROCESS | 5300 | 01:05:58 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52548/tests/kraken/driver.html?testName=audio-fft) 01:05:58 INFO - PROCESS | 5300 | RSS: Main: 265609216 01:05:58 INFO - PROCESS | 5300 | 01:06:00 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52548/tests/kraken/driver.html?testName=audio-oscillator) 01:06:01 INFO - PROCESS | 5300 | RSS: Main: 171458560 01:06:01 INFO - PROCESS | 5300 | 01:06:03 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52548/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:06:04 INFO - PROCESS | 5300 | RSS: Main: 175030272 01:06:04 INFO - PROCESS | 5300 | 01:06:07 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52548/tests/kraken/driver.html?testName=imaging-darkroom) 01:06:07 INFO - PROCESS | 5300 | RSS: Main: 212389888 01:06:07 INFO - PROCESS | 5300 | 01:06:11 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52548/tests/kraken/driver.html?testName=imaging-desaturate) 01:06:11 INFO - PROCESS | 5300 | RSS: Main: 212889600 01:06:11 INFO - PROCESS | 5300 | 01:06:14 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52548/tests/kraken/driver.html?testName=json-parse-financial) 01:06:15 INFO - PROCESS | 5300 | RSS: Main: 211197952 01:06:15 INFO - PROCESS | 5300 | 01:06:16 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52548/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:06:17 INFO - PROCESS | 5300 | RSS: Main: 173744128 01:06:17 INFO - PROCESS | 5300 | 01:06:18 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:06:19 INFO - PROCESS | 5300 | RSS: Main: 180789248 01:06:19 INFO - PROCESS | 5300 | 01:06:21 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:06:22 INFO - PROCESS | 5300 | RSS: Main: 190611456 01:06:22 INFO - PROCESS | 5300 | 01:06:24 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:06:25 INFO - PROCESS | 5300 | RSS: Main: 199950336 01:06:25 INFO - PROCESS | 5300 | 01:06:28 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:06:28 INFO - PROCESS | 5300 | RSS: Main: 195952640 01:06:28 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | Cycle 1(1): loaded http://localhost:52548/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52548/tests/kraken/driver.html?testName=ai-astar) 01:06:30 INFO - PROCESS | 5300 | RSS: Main: 245403648 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | __start_tp_report 01:06:30 INFO - PROCESS | 5300 | _x_x_mozilla_page_load 01:06:30 INFO - PROCESS | 5300 | _x_x_mozilla_page_load_details 01:06:30 INFO - PROCESS | 5300 | |i|pagename|runs| 01:06:30 INFO - PROCESS | 5300 | |0;ai-astar;103;111;111;112;113;112;111;110;112;112 01:06:30 INFO - PROCESS | 5300 | |1;audio-beat-detection;141;114;114;152;116;115;113;113;135;115 01:06:30 INFO - PROCESS | 5300 | |2;audio-dft;161;162;164;161;162;162;162;161;163;160 01:06:30 INFO - PROCESS | 5300 | |3;audio-fft;75;76;77;114;77;78;77;76;88;75 01:06:30 INFO - PROCESS | 5300 | |4;audio-oscillator;82;82;109;82;83;83;93;82;82;112 01:06:30 INFO - PROCESS | 5300 | |5;imaging-gaussian-blur;113;113;112;114;113;113;113;113;113;113 01:06:30 INFO - PROCESS | 5300 | |6;imaging-darkroom;155;146;146;146;146;147;147;146;147;146 01:06:30 INFO - PROCESS | 5300 | |7;imaging-desaturate;95;93;94;93;94;94;93;94;93;94 01:06:30 INFO - PROCESS | 5300 | |8;json-parse-financial;54;54;55;55;54;54;54;56;53;54 01:06:30 INFO - PROCESS | 5300 | |9;json-stringify-tinderbox;54;54;54;54;55;54;53;53;53;54 01:06:30 INFO - PROCESS | 5300 | |10;stanford-crypto-aes;85;79;80;81;80;78;80;79;85;75 01:06:30 INFO - PROCESS | 5300 | |11;stanford-crypto-ccm;121;129;127;121;120;120;120;127;158;120 01:06:30 INFO - PROCESS | 5300 | |12;stanford-crypto-pbkdf2;162;154;155;159;158;160;157;157;154;160 01:06:30 INFO - PROCESS | 5300 | |13;stanford-crypto-sha256-iterative;65;63;60;60;61;62;59;58;60;59 01:06:30 INFO - PROCESS | 5300 | __end_tp_report 01:06:30 INFO - PROCESS | 5300 | __start_cc_report 01:06:30 INFO - PROCESS | 5300 | _x_x_mozilla_cycle_collect,1674 01:06:30 INFO - PROCESS | 5300 | __end_cc_report 01:06:30 INFO - PROCESS | 5300 | __startTimestamp1496304390545__endTimestamp 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | ------- Summary: start ------- 01:06:30 INFO - PROCESS | 5300 | Number of tests: 14 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.83 (2.5%) stddev-sans-first:0.88 01:06:30 INFO - PROCESS | 5300 | Values: 103.0 111.0 111.0 112.0 113.0 112.0 111.0 110.0 112.0 112.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:115.50 stddev:14.33 (12.4%) stddev-sans-first:13.60 01:06:30 INFO - PROCESS | 5300 | Values: 141.0 114.0 114.0 152.0 116.0 115.0 113.0 113.0 135.0 115.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 01:06:30 INFO - PROCESS | 5300 | Values: 161.0 162.0 164.0 161.0 162.0 162.0 162.0 161.0 163.0 160.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#3] audio-fft Cycles:10 Average:81.30 Median:77.00 stddev:12.09 (15.7%) stddev-sans-first:12.61 01:06:30 INFO - PROCESS | 5300 | Values: 75.0 76.0 77.0 114.0 77.0 78.0 77.0 76.0 88.0 75.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:82.00 stddev:11.84 (14.4%) stddev-sans-first:12.29 01:06:30 INFO - PROCESS | 5300 | Values: 82.0 82.0 109.0 82.0 83.0 83.0 93.0 82.0 82.0 112.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 01:06:30 INFO - PROCESS | 5300 | Values: 113.0 113.0 112.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:146.50 stddev:2.78 (1.9%) stddev-sans-first:0.50 01:06:30 INFO - PROCESS | 5300 | Values: 155.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 147.0 146.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.53 01:06:30 INFO - PROCESS | 5300 | Values: 95.0 93.0 94.0 93.0 94.0 94.0 93.0 94.0 93.0 94.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 01:06:30 INFO - PROCESS | 5300 | Values: 54.0 54.0 55.0 55.0 54.0 54.0 54.0 56.0 53.0 54.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 01:06:30 INFO - PROCESS | 5300 | Values: 54.0 54.0 54.0 54.0 55.0 54.0 53.0 53.0 53.0 54.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:3.01 (3.8%) stddev-sans-first:2.65 01:06:30 INFO - PROCESS | 5300 | Values: 85.0 79.0 80.0 81.0 80.0 78.0 80.0 79.0 85.0 75.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:124.00 stddev:11.68 (9.4%) stddev-sans-first:12.23 01:06:30 INFO - PROCESS | 5300 | Values: 121.0 129.0 127.0 121.0 120.0 120.0 120.0 127.0 158.0 120.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.60 Median:158.50 stddev:2.72 (1.7%) stddev-sans-first:2.37 01:06:30 INFO - PROCESS | 5300 | Values: 162.0 154.0 155.0 159.0 158.0 160.0 157.0 157.0 154.0 160.0 01:06:30 INFO - PROCESS | 5300 | 01:06:30 INFO - PROCESS | 5300 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:60.50 stddev:2.11 (3.5%) stddev-sans-first:1.56 01:06:30 INFO - PROCESS | 5300 | Values: 65.0 63.0 60.0 60.0 61.0 62.0 59.0 58.0 60.0 59.0 01:06:30 INFO - PROCESS | 5300 | -------- Summary: end -------- 01:06:30 INFO - PROCESS | 5300 | 01:06:31 INFO - TEST-INFO | 5300: exit 0 01:06:31 INFO - TEST-OK | kraken | took 60437ms 01:06:31 INFO - SUITE-END | took 1176s 01:06:31 INFO - Completed test suite (00:19:36) 01:06:31 INFO - javascript benchmark 01:06:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7865.696541757001, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3376.57792775903, 3412.4710669846177, 3419.9487007694875, 3446.0867870014436, 3462.0306314232007, 5661.4055524169935, 5665.206871038048, 5668.234788303573, 5714.0, 5734.971325143374, 5685.687287199203, 5767.596268261213, 5769.0, 5771.682557459338, 5772.0476121439915, 5201.453847346031, 5221.791128354861, 5229.372475302969, 5255.0, 5289.8942021159555, 5015.724135172564, 5063.544281014708, 5307.416184219733, 5317.12267475866, 5318.734063296839, 5188.221766734982, 5206.895862082756, 5209.244659524366, 5221.451747566508, 5229.60777941654, 5285.8942821143555, 5286.31277933868, 5289.021531016774, 5291.3385826771655, 5297.0, 4932.852014439537, 5150.201718733602, 5212.791488340462, 5248.580113590941, 5273.947260527422, 5470.2888624478965, 5588.050031494615, 5617.915731264035, 5642.830715078554, 5644.74598643062, 6073.271207455134, 6110.358412366682, 6127.325994140641, 6137.508999280047, 6145.0167973124535, 5925.762969481216, 6156.8768624627255, 6178.598391104609, 6179.443850053472, 6196.566240363177, 5629.408912064257, 5700.144978253254, 5731.398203188605, 5733.197352370713, 5744.971275143597, 6186.907196392058, 6225.657588832658, 6237.09562113491, 6237.376262373817, 6242.282137554149, 5967.462928336384, 6023.066424704178, 6033.909491357633, 6039.969800151059, 6053.788117415869, 5909.261342332208, 5943.970280148572, 6121.204243448412, 6124.441964285689, 6127.525510585588, 6054.939450605526, 6100.359462256399, 6103.054026625791, 6104.755809767648, 6128.908066379008, 5845.678487683218, 5867.823965281048, 5868.501177399883, 5878.735456904449, 6136.447719705248, 4574.130915126182, 4626.629869610423, 4627.444706635225, 4627.699199552018, 4682.836100736458, 4219.767912764766, 4242.8727138185905, 4244.533101358848, 4249.957500424956, 4267.359896015622, 5619.409961953936, 5654.547636189095, 5806.564507661942, 5815.447532484407, 5818.272715910512, 6088.086786981989, 6096.451319381277, 6114.541409394314, 6116.724747386289, 6133.96933015341, 5790.276215473066, 5797.159411885253, 5800.984827655144, 5804.506616937608, 5804.970975145097, 5059.089363914531, 5233.659812112201, 5235.476452354734, 5239.240310155083, 5241.580673546107, 6193.81418557444, 6218.284897236695, 6225.0, 6235.064740288993, 6240.625562466266, 6036.843601754055, 6043.09353596964, 6047.879042419039, 6055.576109672457, 6058.636481810929, 6229.314775374614, 6252.312245652884, 6263.467605253422, 6286.214223222098, 6302.84242893913, 5735.655860648374, 5759.424057594207, 5945.10823376497, 5972.432618901197, 6010.038393856962, 6148.4773794227995, 6151.446369826737, 6152.907706384497, 6156.045812899008, 6161.168242287413, 6017.909731354121, 6018.217631708025, 6019.0, 6020.488258498077, 6053.061775424816, 5750.712464376681, 5784.50831679312, 5932.1694962706115, 5970.134330521963, 6026.758929642765, 5928.407159284209, 5929.6145750526075, 5980.37206093354, 6037.184980027816, 6052.818415447544, 5804.187413762161, 6029.155918171372, 6031.125486804476, 6031.7587296507645, 6036.9094463582205, 5697.7720891165545, 5786.768529258782, 5791.218185544897, 5812.3025236971835, 5886.558508111823], "value": 5622.072665205495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.6978218059318, 835.8704400817871, 913.5112714697638, 924.4268553496833, 924.5331107790569, 43786.78106609466, 44460.77769611155, 44681.0, 44751.55248447515, 44879.55120448794, 41945.7902710486, 42327.0, 45545.7722711386, 45595.544044559545, 45619.543804562025, 9128.132827381405, 9260.629574817, 9269.536523173849, 9272.953635231848, 9273.489958052305, 7500.362469190125, 7737.342325902305, 10660.626878059269, 10850.457477126112, 10864.185186111034, 10921.672349829478, 10928.836067458995, 10943.179261555375, 10946.0, 10947.726306842313, 10878.61924832631, 10888.564457421693, 10919.12646988239, 10919.781604367869, 10922.617708380249, 10701.732456688567, 10732.463376831127, 10769.246152769309, 10772.569097236179, 10785.191110666732, 41750.37374439386, 41755.582444175474, 41975.1604967902, 41985.0, 42015.159696805895, 4177.624013838738, 4187.937180942288, 4227.408162857172, 4281.6574674026315, 4309.78451077448, 30070.398592028036, 30408.239794005105, 30751.231219219473, 31230.84384578085, 31661.0, 4249.192653395845, 4321.395004699345, 4336.718113322624, 4366.366876802846, 4454.77726113693, 222167.0, 222365.99999999837, 222415.0, 223422.0, 224859.0, 49260.50739492631, 49994.0, 50416.747916260916, 51161.23258151131, 51170.0, 49672.25491617629, 49675.25487117696, 49800.25299620508, 49960.750196249515, 50329.24506132411, 16670.749938750927, 16761.245743941552, 16762.58093547671, 16765.413210537572, 16769.832301676826, 14789.630259243606, 14791.556253312418, 14854.331555080047, 14854.777178342334, 14870.851291487163, 13177.934110329386, 13202.801957970447, 13219.867801321863, 13261.535846245335, 13277.269750163834, 5527.429745168435, 5545.750441230073, 5556.111022236423, 5627.7467513961155, 5644.74598643062, 1033.1528146919454, 1034.1933292032227, 1040.5837664933936, 1043.671243558292, 1047.5966752800227, 28475.43049139047, 28568.285792854927, 28638.97097166671, 28720.425591488052, 28732.0, 1090.8292497929222, 1098.2586753941057, 1107.145531235178, 1112.2881355932209, 1136.4999400263857, 14794.556163315117, 15486.380544778309, 15495.14776687293, 15548.533543993699, 15626.718389138181, 306648.0, 306650.0, 306984.0, 307507.0, 315250.0, 56519.43480565224, 56679.0, 56756.0, 56778.14832777512, 56865.43134568684, 1492.8133983252094, 1505.4881340344336, 1514.1823415355584, 1521.8782497400402, 1525.7940178075817, 27486.17541473759, 27578.44843103167, 28680.569791452726, 28739.28151796222, 29099.7090029097, 36063.63936360708, 36131.0, 36141.0, 36218.63781362153, 36245.81877090598, 282190.0, 283233.0, 430475.0, 438605.0, 442896.0, 25716.357091072125, 26098.739012609632, 27239.182824515297, 28000.43999120047, 29321.7067829319, 40294.0, 40607.18785624212, 40890.79554602208, 40895.0, 40912.0, 31429.8428507856, 31485.370292594478, 31585.0, 31777.364452711277, 32027.519587206676, 30939.84530077335, 31139.68860311368, 31193.53209701811, 31236.531452027783, 31795.523067154474, 34920.82539587286, 34961.475577865844, 35379.11552211215, 35542.28915421729, 36075.45886811752, 30024.0, 30092.849535752182, 30255.394892101594, 30346.393072138875, 30894.382112357176, 23576.882115589997, 24774.876125619256, 24871.253862384157, 24954.62568061517, 25142.62286065674], "value": 18787.686719206424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.7749570223486, 454.68626647613144, 459.5863722649615, 478.7151644771361, 491.62390771060143, 19680.803191968076, 21400.89299553502, 22939.082436702512, 22954.88522557387, 23186.65220021697, 22487.887560562176, 22559.887200563975, 22915.0, 22975.310740677764, 23329.5334093318, 6703.329667033282, 7000.089988301514, 7288.781336559885, 7500.287472690113, 7584.924150758477, 7497.1753107158165, 7643.694252229904, 7687.884681729778, 7693.076830780315, 7750.728724494644, 7790.298873101419, 7792.064952205743, 7793.76618701437, 7795.376369890423, 7800.804979875492, 7506.512076714996, 7649.808754781147, 7702.0, 7716.035495563055, 7717.112532058801, 7285.7814265572115, 7505.924940750577, 7551.056117985252, 7598.164201937782, 7606.505577137468, 25181.748182518124, 25315.493690126095, 25530.744692553024, 25605.3598660035, 25961.0, 3180.9522857157162, 3299.2081900343746, 3357.0600231935096, 3378.577677790276, 3414.9487757683655, 15665.608359790982, 16185.514434566981, 16470.258838352303, 16622.50132496015, 16926.830731692648, 2154.4721543222013, 2225.109956017606, 2264.558411109834, 2267.5918334699745, 2280.1335492512903, 85398.57300713519, 96067.51966240194, 99881.5005924973, 103227.0, 110172.4491377546, 17733.822661773218, 18338.724919126224, 18602.25590976346, 18765.437036888914, 19791.703124453146, 32027.83986080055, 32256.83871580674, 32345.35309293848, 32387.838060809547, 32927.83536082304, 10757.354558726345, 10978.670639880656, 11017.11863050954, 11021.228514004104, 11260.042896353738, 9316.410153907602, 9341.532923353805, 9362.204212641866, 9600.135987761136, 9624.326297159272, 8918.420302680435, 9494.145526902612, 9642.325037247465, 9669.516524173763, 9786.41281523111, 3381.746369022333, 3435.6736110069505, 3497.7901325920525, 3912.256671232457, 3924.823382947717, 965.4882912056621, 984.1831280037552, 985.5417230987625, 994.9602015919427, 995.9203263738883, 20756.896215518827, 21370.572588548453, 21667.458313542287, 21708.0, 21884.671729924063, 1102.9227954043301, 1124.1849658997162, 1126.4367816091954, 1134.7163209197702, 1152.3086148311113, 11108.55565777376, 11721.296722196696, 11842.111841611913, 11874.406279685982, 11876.88123118775, 627922.0, 629836.0, 641353.0, 649819.0, 663498.0, 89074.0, 116350.0, 117295.41352293183, 119742.40128799474, 124725.37637311756, 1314.8487923888874, 1377.8415482219473, 1380.6203294094205, 1384.9099808512415, 1391.7494850926728, 18449.07754612291, 19904.30334938328, 19968.201271948958, 19969.900150499736, 20021.499462513555, 52342.738286308326, 53279.46720532745, 53450.73274633602, 53702.19446708224, 54075.459245407044, 66953.0, 67944.98082528668, 67994.66002669955, 68079.0, 68667.0, 12649.241045537297, 12886.613401597968, 12941.482340706643, 12993.155444896052, 13215.933920330337, 13945.860541394457, 14064.507742229382, 14611.85388146105, 14677.41290348374, 14690.485832995793, 11770.58802941893, 12621.305828179538, 12760.234385936874, 12902.161359511601, 13236.669083272995, 11583.884161158281, 11589.362585057566, 11669.533218671497, 11753.823692644788, 11921.165518413627, 12844.293563854078, 12858.421371038512, 12889.677758056123, 12955.352232388532, 13021.934890325489, 12276.815847762113, 12351.135420520837, 12543.882734409439, 12750.553730619384, 12775.339205903174, 12378.62864114078, 12467.812982805084, 12485.0, 12551.372431378211, 12713.745725085631], "value": 11834.60396873929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1583.1371902313238, 1603.414753614931, 1614.2251719174797, 1614.547926580557, 1622.6511300070483, 10428.530716117773, 11076.224664273484, 11079.113670906328, 11085.889141108586, 11086.445677716123, 10467.057964783167, 10687.78624427511, 10691.091257243124, 10702.250842441032, 10854.0, 2545.9236222913296, 2581.7160112387623, 2583.6641236639302, 2583.9354016149655, 2588.508183445149, 2451.767082127195, 2513.773760361576, 2550.9489810203786, 2568.4092658688514, 2569.4218800769772, 2470.3700556358103, 2509.749025097494, 2607.947841043178, 2611.308003379106, 2611.5560354739646, 2406.651035599837, 2467.0255249176566, 2470.3083136721657, 2497.700275966896, 2506.0602274147195, 2441.9145329913545, 2458.4591389894194, 2465.3713303107593, 2470.258922323314, 2471.9011239550396, 11519.712007199803, 11752.177347585672, 11759.05927525796, 11770.882291177064, 11775.293482391084, 5968.97015514924, 6107.725152368154, 6107.816765497042, 6113.755449782004, 6114.602550834182, 3386.255023894739, 3428.434308339121, 3488.4069708149677, 3532.5760908690863, 3615.439606860941, 1291.7351942851787, 1294.8187253784479, 1327.502186679995, 1335.4391155714527, 1346.6902612399203, 14930.477433289783, 15302.0, 15433.845661543466, 15440.382384704712, 16969.15154242283, 4851.854444366674, 4885.828995985124, 5063.215201643751, 5076.6192535559985, 5115.769790359443, 10381.117605003508, 10620.309679870783, 12463.065270104778, 13256.337183140804, 13258.53595124166, 5826.563007774434, 5890.087036509348, 5898.14476900847, 5902.704864756745, 5903.58674892762, 5465.508104270635, 5465.508104270635, 5467.535259502987, 5558.555315574744, 5664.348599910981, 4229.365595160751, 4283.20760659279, 4299.871003869889, 4320.827166913288, 4330.437043184366, 1116.6315116011697, 1125.5216532973454, 1134.444122380028, 1154.7344110854551, 1159.913006524514, 1032.4992378696477, 1042.0517329230363, 1043.3531210649294, 1050.2333296693457, 1060.4962642744636, 7102.68037938294, 7155.463340249502, 7173.461990350745, 7269.527480713737, 7325.5970921598755, 1041.8385150301715, 1047.800917825626, 1060.591672206206, 1080.1466841195524, 1083.7344850511674, 4545.931811022837, 4564.178447879413, 4600.585947264763, 4645.9303110453375, 4661.487236403993, 14691.338889749986, 15320.540383788504, 15329.46346877854, 15354.692906141814, 15364.078155310717, 15000.324985375684, 15056.0, 15222.923885380502, 15246.0, 15265.160416176996, 11004.449777511092, 11076.0, 11091.611793587186, 11093.223474356879, 11113.11095112389, 6633.204015517976, 6659.966700166662, 6693.431058359899, 6708.765193218409, 6742.69657865407, 12516.0, 13277.468901243841, 13286.0, 13303.0, 13339.0, 12469.438875250455, 12766.29785361814, 13931.651708706959, 13940.721185576434, 14136.363863625957, 11152.275102118336, 11313.377764223047, 11443.027342675632, 11447.25592836463, 11453.255538389978, 11167.441627918734, 11241.66275011751, 11337.659870203906, 11358.0913526916, 11362.204645674723, 9256.861147082935, 9331.486768227813, 9469.621215151317, 9481.620735170793, 9589.664361747306, 9440.669576564787, 9543.141117299476, 9632.518374081128, 9747.805043899225, 9891.752706182115, 8578.485290882567, 8605.698800541952, 8609.139086091342, 8688.13118688126, 8696.130386961506, 5907.70461476933, 5993.160957465871, 6103.572749907464, 6129.754809807558, 6197.690115494297, 6125.632462052291, 6127.969360153349, 6131.202943617302, 6178.443940045418, 6206.224221972254], "value": 5478.824938241539, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.2989836870795, 425.6297021591215, 430.7716910037679, 435.46655347199675, 444.73916446873113, 5106.234064890264, 5202.635815492917, 5276.0, 5281.9207711884355, 5291.603129765269, 5137.743112844361, 5150.201718733593, 5155.7679904404295, 5172.267290457701, 5185.533302002819, 4449.621782148505, 4450.285325951168, 4452.398926144978, 4464.241079016575, 4484.8878778030485, 4222.788860556975, 4422.7125236859665, 4485.887852803689, 4489.102179564084, 4506.729596224237, 4400.73595584266, 4411.558844115595, 4420.359047938047, 4422.0, 4422.292433210687, 4418.5360537143615, 4427.406133791832, 4445.644348452116, 4453.732776033416, 4458.773837194781, 3955.8330292563473, 4198.181354635847, 4222.641075508585, 4228.4080228768, 4234.618884300427, 5033.798648054073, 5087.771050302708, 5095.617828662865, 5124.129692662189, 5150.8881358642775, 5087.186050231945, 5120.487951204887, 5138.58891288696, 5153.2270159476, 5181.5854731621375, 5286.735663216862, 5462.945370546323, 5505.752241149158, 5537.145056406109, 5541.473560011793, 4593.701409408412, 4597.310403439477, 4601.573512211192, 4604.58558729716, 4615.399998000238, 5509.311336082989, 5566.304211973504, 5635.464630860061, 5737.196792449061, 5840.299164100293, 4640.303954406866, 4660.13787449323, 4664.603508701798, 4700.0, 4725.551072648162, 4710.528947105262, 4728.385309909759, 4743.482085732601, 4755.976220118877, 4783.832565860178, 4921.630877684188, 4980.327655766425, 5060.280693690135, 5061.898762024739, 5077.441481437039, 5377.569794416438, 5475.014497390508, 5480.835574932758, 5485.670859748428, 5516.558675305966, 1641.727661062686, 1655.329591515438, 1661.8088919774139, 1671.7492376143675, 1679.6892574873443, 1240.6154092231257, 1261.735035642507, 1278.8912942399813, 1285.9871401286055, 1297.9026573007063, 5215.869603259949, 5243.397009343974, 5264.0, 5319.420081260663, 5320.733963301819, 5542.667439953616, 5544.63959842609, 5624.943750562524, 5633.211350410946, 5642.351129620064, 5088.262201980692, 5298.887233680893, 5316.0, 5353.678779273256, 5366.382865970463, 2969.9851500742357, 2974.2267010576975, 2987.8057926234724, 2995.281132528221, 3010.8494575271147, 5661.2923384576925, 5847.356790753014, 5849.327327357323, 5879.764809407661, 5906.527477801851, 5715.457031581993, 5734.792105039506, 5759.65442073477, 5759.798407055733, 5763.884722305615, 5811.767529298865, 5814.918093410414, 5831.183634291203, 5840.591158618856, 5855.707214639337, 5375.731213439234, 5571.328601419477, 5620.943790562042, 5624.6625202487985, 5637.3235211774845, 5318.7074710891275, 5530.198121272473, 5545.195946587803, 5552.194931734795, 5608.943910560843, 5703.346029651355, 5744.885102298014, 5763.279590051244, 5774.220480235114, 5783.334916484489, 4630.6758526902795, 4633.536646335339, 4679.338834713628, 4727.503612120678, 4728.38530990969, 5477.726113694379, 5569.081101618188, 5616.354119276172, 5678.7728490861555, 5697.316322041382, 5484.670919744828, 5490.588205884655, 5539.529140023144, 5574.358948720949, 5588.916166257591, 5476.315460567429, 5481.451854814646, 5537.169424586345, 5573.693446860461, 5583.69289689071, 5259.4498713369085, 5396.3524377075, 5455.781768729206, 5462.071447853954, 5479.589030822624, 5474.726263686879, 5490.752916118704, 5508.283923089973, 5513.8345849624575, 5519.69641669712, 5198.582659063553, 5441.918371224356, 5443.918341224806, 5460.180972854104, 5476.835694929158], "value": 4431.016897520494, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [849.817289282804, 866.4281574161055, 916.2029034739782, 925.0501398965014, 945.631203830506, 16882.0, 18081.367152149676, 18175.72736408955, 18212.271509139635, 18238.179281932313, 14637.341319640615, 14789.778153327708, 14801.0, 14864.777028344584, 14869.702605947901, 697.4699228586286, 853.8420392227426, 907.8683590879318, 917.0508523677995, 925.0333401595316, 902.3593248793364, 908.9318301127441, 910.968116115936, 911.5260064766318, 911.694582314922, 970.4807927758651, 970.6796757070185, 970.8495183246608, 971.9076687714655, 973.8247115519204, 956.784723437228, 957.3968399908048, 957.6408846682494, 959.4051687953513, 960.3901522533172, 707.9469039822034, 709.3261401668384, 713.1371041040347, 713.304528085118, 714.039616715522, 17011.48965531036, 17015.149242537824, 17708.203130859143, 18741.625167496575, 18901.62196756071, 14400.711985760227, 14500.202488863108, 14750.188739619316, 15000.099994000286, 15014.399424023135, 13624.863751362558, 13686.777627115265, 13688.0, 13807.861921380758, 14021.369038393297, 3995.900102497461, 4030.6170913763144, 4035.8385664573384, 4077.3272410052305, 4084.8570300039655, 82326.58836705839, 82401.0, 82485.17514824835, 84196.0, 84392.57803711004, 13584.456621735217, 13646.181229126285, 13649.863501365058, 13662.385192666354, 13822.032457727862, 26041.0, 26520.60219096715, 26883.596746048825, 26984.865075674497, 27121.728782712315, 9086.954565227265, 9125.224355929688, 9128.0, 9128.81742365149, 9134.543272836332, 4868.78090485929, 4885.267209918469, 4912.828051018198, 5021.974890125599, 5031.698098114125, 3742.607026262258, 3815.961840381616, 3828.0429892526868, 3856.093817952779, 3865.613438656168, 609.2445367743966, 617.2685367839125, 617.9721912513947, 623.5074291309814, 625.2497003595703, 809.8704207326917, 821.650798410673, 834.1075049696888, 837.2757564706485, 852.424613385962, 10296.794064118825, 10811.729706757395, 10819.404932728776, 10904.836427453594, 11295.209335346612, 661.8841702702008, 679.3953381490477, 719.4352433339803, 825.6449726617612, 851.82537579796, 4809.398825146856, 5322.787088516416, 5498.0, 5636.464535869086, 5804.622699524602, 63924.68037659782, 64224.67887660625, 77833.22166778259, 79565.60217198876, 80861.76552937817, 60128.69935650294, 66006.66996665082, 67906.0, 68128.6593567039, 68611.65694171497, 59054.40945590489, 60568.0, 61009.389906101256, 62002.68998655068, 62065.68967155135, 11302.886971130183, 11464.31214127155, 11590.478428470571, 11752.58865939688, 11918.702032449259, 37408.43887341637, 38335.42496862605, 41310.0, 41951.160976780906, 42945.785271073444, 50765.492345076076, 51671.741641291555, 55264.72367638136, 55997.72001139968, 57714.0943019848, 14183.716325673222, 14193.432262709788, 14322.641933951318, 14414.639634009234, 14488.420463181776, 13622.250776207404, 13690.452381904612, 13697.109687870257, 14411.0, 14762.114273143647, 12649.177803442746, 12750.872491275339, 12914.354282286036, 13690.794638080635, 14675.779863302272, 14927.0, 14938.850611493746, 15007.099574025593, 15170.544883653509, 16454.424095156613, 14014.640676397048, 14147.221902795445, 14187.929060354632, 14270.286485675882, 14530.491432799801, 10858.294210876267, 10866.61966831157, 11356.26184298018, 11453.198276120926, 11491.48288327044, 11427.542898284308, 12463.828853100329, 12729.93635031856, 12760.87239127634, 13014.0], "value": 7803.152506644807, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1452.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 112.0, 113.0, 112.0, 111.0, 110.0, 112.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 114.0, 114.0, 152.0, 116.0, 115.0, 113.0, 113.0, 135.0, 115.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 164.0, 161.0, 162.0, 162.0, 162.0, 161.0, 163.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 114.0, 77.0, 78.0, 77.0, 76.0, 88.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 109.0, 82.0, 83.0, 83.0, 93.0, 82.0, 82.0, 112.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0, 56.0, 53.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 53.0, 53.0, 53.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 79.0, 80.0, 81.0, 80.0, 78.0, 80.0, 79.0, 85.0, 75.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 129.0, 127.0, 121.0, 120.0, 120.0, 120.0, 127.0, 158.0, 120.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 154.0, 155.0, 159.0, 158.0, 160.0, 157.0, 157.0, 154.0, 160.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 60.0, 60.0, 61.0, 62.0, 59.0, 58.0, 60.0, 59.0], "value": 60.7, "unit": "score"}]}]} 01:06:31 INFO - Return code: 0 01:06:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:06:31 INFO - # TBPL SUCCESS # 01:06:31 INFO - Running post-action listener: _resource_record_post_action 01:06:31 INFO - [mozharness: 2017-06-01 08:06:31.872463Z] Finished run-tests step (success) 01:06:31 INFO - Running post-run listener: _resource_record_post_run 01:06:31 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 325328896; Read time: 224; Write time: 243252 01:06:31 INFO - TinderboxPrint: CPU usage
13.1% 01:06:31 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 224 01:06:31 INFO - TinderboxPrint: I/O write bytes / time
325,328,896 / 243,252 01:06:31 INFO - TinderboxPrint: CPU idle
8,252.1 (86.9%) 01:06:31 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 01:06:31 INFO - TinderboxPrint: Swap in / out
0 / 0 01:06:31 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 44785664; Read time: 0; Write time: 15248 01:06:31 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 225935360; Read time: 100; Write time: 116908 01:06:32 INFO - Running post-run listener: _upload_blobber_files 01:06:32 INFO - Blob upload gear active. 01:06:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:06:32 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 01:06:32 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'] 01:06:32 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 01:06:32 INFO - (blobuploader) - INFO - Open directory for files ... 01:06:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:06:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:06:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:06:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:06:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:06:33 INFO - (blobuploader) - INFO - Done attempting. 01:06:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:06:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:06:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:06:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:06:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:06:39 INFO - (blobuploader) - INFO - Done attempting. 01:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:06:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:06:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:06:40 INFO - (blobuploader) - INFO - Done attempting. 01:06:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:06:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:06:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:06:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:06:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:06:41 INFO - (blobuploader) - INFO - Done attempting. 01:06:41 INFO - (blobuploader) - INFO - Iteration through files over. 01:06:41 INFO - Return code: 0 01:06:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:06:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:06:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f9842c35c4e888c65ca5fa32a106c47713287b142d281868c6093d3f86c16b3c6c9afd42ac3ef1f0e329da662f0ba6ab5e5e6ebf942c43cea80d6835641bf531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9104e09f30c5cf00bccc4ad6a715b74f8014d4231649c2dd56f651831c25530e85ad43f543ada8a2f1af867c4b0c7321d7387cbf214a434fd701f9ffcc722e0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d5dbf3eb844560b34e48c2c770072c55f28d40afd62e6e0af30177b6407b764e9d499c230fc61880c895204a0770f51d67455feecb6bfbbd55363c380a4e753c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6025944c689dca77af3dc18d8f7269dff94e2535e1771367939ed70b1eca9563d89b621d2a97a6917b03036f705ab8c050f45dcd1aab874f073a30deea12171e"} 01:06:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:06:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:06:41 INFO - Contents: 01:06:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f9842c35c4e888c65ca5fa32a106c47713287b142d281868c6093d3f86c16b3c6c9afd42ac3ef1f0e329da662f0ba6ab5e5e6ebf942c43cea80d6835641bf531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9104e09f30c5cf00bccc4ad6a715b74f8014d4231649c2dd56f651831c25530e85ad43f543ada8a2f1af867c4b0c7321d7387cbf214a434fd701f9ffcc722e0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d5dbf3eb844560b34e48c2c770072c55f28d40afd62e6e0af30177b6407b764e9d499c230fc61880c895204a0770f51d67455feecb6bfbbd55363c380a4e753c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6025944c689dca77af3dc18d8f7269dff94e2535e1771367939ed70b1eca9563d89b621d2a97a6917b03036f705ab8c050f45dcd1aab874f073a30deea12171e"} 01:06:41 INFO - Running post-run listener: copy_logs_to_upload_dir 01:06:41 INFO - Copying logs to upload dir... 01:06:41 INFO - mkdir: /builds/slave/test/build/upload/logs 01:06:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.812511 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-06-01 01:06:41.280519) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 01:06:41.288543) ========= 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=4300f881736b422476174874000001bc-1496301783.65284-1210956103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f9842c35c4e888c65ca5fa32a106c47713287b142d281868c6093d3f86c16b3c6c9afd42ac3ef1f0e329da662f0ba6ab5e5e6ebf942c43cea80d6835641bf531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9104e09f30c5cf00bccc4ad6a715b74f8014d4231649c2dd56f651831c25530e85ad43f543ada8a2f1af867c4b0c7321d7387cbf214a434fd701f9ffcc722e0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d5dbf3eb844560b34e48c2c770072c55f28d40afd62e6e0af30177b6407b764e9d499c230fc61880c895204a0770f51d67455feecb6bfbbd55363c380a4e753c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6025944c689dca77af3dc18d8f7269dff94e2535e1771367939ed70b1eca9563d89b621d2a97a6917b03036f705ab8c050f45dcd1aab874f073a30deea12171e"} build_url:https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011465 build_url: 'https://queue.taskcluster.net/v1/task/AtvSNPKMTf6WKIgJEldMPg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f9842c35c4e888c65ca5fa32a106c47713287b142d281868c6093d3f86c16b3c6c9afd42ac3ef1f0e329da662f0ba6ab5e5e6ebf942c43cea80d6835641bf531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9104e09f30c5cf00bccc4ad6a715b74f8014d4231649c2dd56f651831c25530e85ad43f543ada8a2f1af867c4b0c7321d7387cbf214a434fd701f9ffcc722e0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d5dbf3eb844560b34e48c2c770072c55f28d40afd62e6e0af30177b6407b764e9d499c230fc61880c895204a0770f51d67455feecb6bfbbd55363c380a4e753c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6025944c689dca77af3dc18d8f7269dff94e2535e1771367939ed70b1eca9563d89b621d2a97a6917b03036f705ab8c050f45dcd1aab874f073a30deea12171e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 01:06:41.315190) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:06:41.315485) ========= 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=4300f881736b422476174874000001bc-1496301783.65284-1210956103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004625 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:06:41.396830) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 01:06:41.397147) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 01:06:41.397432) ========= ========= Total master_lag: 0.23 =========