builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-024 starttime: 1495633656.53 results: success (0) revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.528018) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.528471) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.528776) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.544132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.544482) ========= 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=41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-24 06:47:36-- 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% 23.8M=0s 2017-05-24 06:47:36 (23.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081276 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.641981) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.642376) ========= 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=41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020415 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.691522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:36.692370) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7166b44658df0723b16d43b7f884deb73c99f0ba --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 7166b44658df0723b16d43b7f884deb73c99f0ba --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=41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-24 06:47:36,928 truncating revision to first 12 chars 2017-05-24 06:47:36,928 Setting DEBUG logging. 2017-05-24 06:47:36,928 attempt 1/10 2017-05-24 06:47:36,928 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7166b44658df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 06:47:37,237 unpacking tar archive at: mozilla-beta-7166b44658df/testing/mozharness/ program finished with exit code 0 elapsedTime=0.885844 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:37.590392) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:37.593062) ========= script_repo_revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:37.593476) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:37.593760) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 06:47:37.601964) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-05-24 06:47:37.602254) ========= /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=41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:47:37 INFO - MultiFileLogger online at 20170524 06:47:37 in /builds/slave/test 06:47:37 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 06:47:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:47:37 INFO - {'append_to_log': False, 06:47:37 INFO - 'base_work_dir': '/builds/slave/test', 06:47:37 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 06:47:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:47:37 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 06:47:37 INFO - 'buildbot_json_path': 'buildprops.json', 06:47:37 INFO - 'config_files': ('talos/linux_config.py',), 06:47:37 INFO - 'default_actions': ('clobber', 06:47:37 INFO - 'read-buildbot-config', 06:47:37 INFO - 'download-and-extract', 06:47:37 INFO - 'populate-webroot', 06:47:37 INFO - 'create-virtualenv', 06:47:37 INFO - 'install', 06:47:37 INFO - 'run-tests'), 06:47:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:47:37 INFO - 'download_minidump_stackwalk': True, 06:47:37 INFO - 'download_symbols': 'ondemand', 06:47:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:47:37 INFO - 'tooltool.py': '/tools/tooltool.py', 06:47:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:47:37 INFO - '/tools/misc-python/virtualenv.py')}, 06:47:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:47:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:47:37 INFO - 'gecko_profile': False, 06:47:37 INFO - 'gecko_profile_interval': 0, 06:47:37 INFO - 'installer_path': 'installer.exe', 06:47:37 INFO - 'log_level': 'info', 06:47:37 INFO - 'log_name': 'talos', 06:47:37 INFO - 'log_to_console': True, 06:47:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:47:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:47:37 INFO - 'opt_config_files': (), 06:47:37 INFO - 'pip_index': False, 06:47:37 INFO - 'suite': 'dromaeojs', 06:47:37 INFO - 'system_bits': '32', 06:47:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:47:37 INFO - 'title': 'talos-linux64-ix-024', 06:47:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:47:37 INFO - 'use_talos_json': True, 06:47:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:47:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:47:37 INFO - 'work_dir': 'build'} 06:47:37 INFO - [mozharness: 2017-05-24 13:47:37.940781Z] Running clobber step. 06:47:37 INFO - Running pre-action listener: _resource_record_pre_action 06:47:37 INFO - Running main action method: clobber 06:47:37 INFO - rmtree: /builds/slave/test/build 06:47:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:47:38 INFO - Running post-action listener: _resource_record_post_action 06:47:38 INFO - [mozharness: 2017-05-24 13:47:38.970539Z] Finished clobber step (success) 06:47:38 INFO - [mozharness: 2017-05-24 13:47:38.970638Z] Running read-buildbot-config step. 06:47:38 INFO - Running pre-action listener: _resource_record_pre_action 06:47:38 INFO - Running main action method: read_buildbot_config 06:47:38 INFO - Using buildbot properties: 06:47:38 INFO - { 06:47:38 INFO - "project": "", 06:47:38 INFO - "product": "firefox", 06:47:38 INFO - "who": "mpalmgren@mozilla.com", 06:47:38 INFO - "installer_path": "public/build/target.tar.bz2", 06:47:38 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 06:47:38 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 06:47:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 06:47:38 INFO - "stage_platform": "linux64", 06:47:38 INFO - "basedir": "/builds/slave/test", 06:47:38 INFO - "buildnumber": 55, 06:47:38 INFO - "platform": "ubuntu64_hw", 06:47:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:47:38 INFO - "slavebuilddir": "test", 06:47:38 INFO - "taskId": "dnADaS42Rmyr7KTd_PKYlg", 06:47:38 INFO - "branch": "mozilla-beta", 06:47:38 INFO - "script_repo_revision": "production", 06:47:38 INFO - "revision": "7166b44658df0723b16d43b7f884deb73c99f0ba", 06:47:38 INFO - "slavename": "talos-linux64-ix-024", 06:47:38 INFO - "repo_path": "releases/mozilla-beta" 06:47:38 INFO - } 06:47:38 INFO - Finding installer, test and symbols from parent task. 06:47:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnADaS42Rmyr7KTd_PKYlg'}, attempt #1 06:47:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnADaS42Rmyr7KTd_PKYlg'}, attempt #1 06:47:39 INFO - Task dependencies: D0yyKakvTQy6GsmaX_-iqg 06:47:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg'}, attempt #1 06:47:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2 06:47:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/test_packages.json 06:47:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.crashreporter-symbols.zip 06:47:40 INFO - Running post-action listener: _resource_record_post_action 06:47:40 INFO - [mozharness: 2017-05-24 13:47:40.351628Z] Finished read-buildbot-config step (success) 06:47:40 INFO - [mozharness: 2017-05-24 13:47:40.351845Z] Running download-and-extract step. 06:47:40 INFO - Running pre-action listener: _resource_record_pre_action 06:47:40 INFO - Running main action method: download_and_extract 06:47:40 INFO - mkdir: /builds/slave/test/build/tests 06:47: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')]} 06:47:40 INFO - trying https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.test_packages.json 06:47:40 INFO - Downloading https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:47:40 INFO - Downloaded 986 bytes. 06:47:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:47:40 INFO - Using the following test package requirements: 06:47:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:47:40 INFO - u'common': [u'target.common.tests.zip'], 06:47:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:47:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:47:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:47:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:47:40 INFO - u'mozbase': [u'target.common.tests.zip'], 06:47:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:47:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:47:40 INFO - u'web-platform': [u'target.common.tests.zip', 06:47:40 INFO - u'target.web-platform.tests.zip'], 06:47:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:47:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:47:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.common.tests.zip 06:47:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:47:40 INFO - Fetch https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.common.tests.zip into memory 06:47:42 INFO - Expected file size: 59712587 06:47:42 INFO - Obtained file size: 59712587 06:47:51 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:47:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.common.tests.zip 06:47:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:47:51 INFO - Fetch https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.common.tests.zip into memory 06:47:53 INFO - Expected file size: 59712587 06:47:53 INFO - Obtained file size: 59712587 06:48:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.talos.tests.zip 06:48:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:48:03 INFO - Fetch https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.talos.tests.zip into memory 06:48:04 INFO - Expected file size: 14404449 06:48:04 INFO - Obtained file size: 14404449 06:48:05 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')]} 06:48:05 INFO - trying https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2 06:48:05 INFO - Downloading https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:48:06 INFO - Downloaded 59709218 bytes. 06:48:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2 06:48:06 INFO - mkdir: /builds/slave/test/properties 06:48:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:48:06 INFO - Writing to file /builds/slave/test/properties/build_url 06:48:06 INFO - Contents: 06:48:06 INFO - build_url:https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2 06:48:06 INFO - Running post-action listener: _resource_record_post_action 06:48:06 INFO - Running post-action listener: set_extra_try_arguments 06:48:06 INFO - [mozharness: 2017-05-24 13:48:06.239178Z] Finished download-and-extract step (success) 06:48:06 INFO - [mozharness: 2017-05-24 13:48:06.239274Z] Running populate-webroot step. 06:48:06 INFO - Running pre-action listener: _resource_record_pre_action 06:48:06 INFO - Running main action method: populate_webroot 06:48:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:48:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:48:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:48:06 INFO - '--tppagecycles', 06:48:06 INFO - '7'], 06:48:06 INFO - 'tests': ['tsvgm']}}, 06:48:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:48:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:48:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:48:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:48:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:48:06 INFO - 'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:48:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:48:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:48:06 INFO - 'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['damp', 'tps']}, 06:48:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:48:06 INFO - 'tests': ['damp', 'tps']}, 06:48:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['dromaeo_dom']}, 06:48:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:48:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['basic_compositor_video']}, 06:48:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:48:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['a11yr', 06:48:06 INFO - 'ts_paint', 06:48:06 INFO - 'tpaint', 06:48:06 INFO - 'sessionrestore', 06:48:06 INFO - 'sessionrestore_no_auto_restore', 06:48:06 INFO - 'tabpaint']}, 06:48:06 INFO - 'other-e10s': {'tests': ['a11yr', 06:48:06 INFO - 'ts_paint', 06:48:06 INFO - 'tpaint', 06:48:06 INFO - 'sessionrestore', 06:48:06 INFO - 'sessionrestore_no_auto_restore', 06:48:06 INFO - 'tabpaint']}, 06:48:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:48:06 INFO - 'ts_paint', 06:48:06 INFO - 'tpaint', 06:48:06 INFO - 'sessionrestore', 06:48:06 INFO - 'sessionrestore_no_auto_restore', 06:48:06 INFO - 'tabpaint']}, 06:48:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:48:06 INFO - 'ts_paint', 06:48:06 INFO - 'tpaint', 06:48:06 INFO - 'sessionrestore', 06:48:06 INFO - 'sessionrestore_no_auto_restore', 06:48:06 INFO - 'tabpaint']}, 06:48:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['a11yr', 06:48:06 INFO - 'ts_paint', 06:48:06 INFO - 'tpaint', 06:48:06 INFO - 'sessionrestore', 06:48:06 INFO - 'sessionrestore_no_auto_restore', 06:48:06 INFO - 'tabpaint']}, 06:48:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['a11yr', 06:48:06 INFO - 'ts_paint', 06:48:06 INFO - 'tpaint', 06:48:06 INFO - 'sessionrestore', 06:48:06 INFO - 'sessionrestore_no_auto_restore', 06:48:06 INFO - 'tabpaint']}, 06:48:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['tsvgx', 06:48:06 INFO - 'tsvgr_opacity', 06:48:06 INFO - 'tart', 06:48:06 INFO - 'tscrollx', 06:48:06 INFO - 'cart', 06:48:06 INFO - 'tsvg_static']}, 06:48:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:48:06 INFO - 'tsvgr_opacity', 06:48:06 INFO - 'tart', 06:48:06 INFO - 'tscrollx', 06:48:06 INFO - 'cart', 06:48:06 INFO - 'tsvg_static']}, 06:48:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:48:06 INFO - 'talos_options': ['--disable-e10s'], 06:48:06 INFO - 'tests': ['tp5o']}, 06:48:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:48:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:48:06 INFO - 'talos_options': ['--disable-e10s', 06:48:06 INFO - '--xperf_path', 06:48:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:06 INFO - 'tests': ['tp5n']}, 06:48:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:48:06 INFO - 'talos_options': ['--xperf_path', 06:48:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:48:06 INFO - 'tests': ['tp5n']}}, 06:48:06 INFO - 'talos.zip': {'path': '', 06:48:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:48:06 INFO - Running post-action listener: _resource_record_post_action 06:48:06 INFO - [mozharness: 2017-05-24 13:48:06.252338Z] Finished populate-webroot step (success) 06:48:06 INFO - [mozharness: 2017-05-24 13:48:06.252427Z] Running create-virtualenv step. 06:48:06 INFO - Running pre-action listener: _resource_record_pre_action 06:48:06 INFO - Running main action method: create_virtualenv 06:48:06 INFO - Creating virtualenv /builds/slave/test/build/venv 06:48:06 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 06:48:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:48:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:48:06 INFO - Using real prefix '/usr' 06:48:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:48:06 INFO - Installing distribute.............................................................................................................................................................................................done. 06:48:08 INFO - Installing pip.................done. 06:48:08 INFO - Return code: 0 06:48:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:48:09 INFO - Reading from file tmpfile_stdout 06:48:09 INFO - Output received: 06:48:09 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) 06:48:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:48:09 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')]} 06:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:09 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')]} 06:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:09 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:09 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 06:48:09 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 06:48:09 INFO - Using env: {'DISPLAY': ':0', 06:48:09 INFO - 'HOME': '/home/cltbld', 06:48:09 INFO - 'LANG': 'en_US.UTF-8', 06:48:09 INFO - 'LANGUAGE': 'en_US:en', 06:48:09 INFO - 'LOGNAME': 'cltbld', 06:48:09 INFO - 'MAIL': '/var/mail/cltbld', 06:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:09 INFO - 'MOZ_NO_REMOTE': '1', 06:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:09 INFO - 'NO_EM_RESTART': '1', 06:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:09 INFO - 'PWD': '/builds/slave/test', 06:48:09 INFO - 'SHELL': '/bin/bash', 06:48:09 INFO - 'SHLVL': '1', 06:48:09 INFO - 'TERM': 'linux', 06:48:09 INFO - 'TMOUT': '86400', 06:48:09 INFO - 'USER': 'cltbld', 06:48:09 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:09 INFO - '_': '/tools/buildbot/bin/python'} 06:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:09 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 06:48:09 INFO - Cleaning up... 06:48:09 INFO - Return code: 0 06:48:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:48:09 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')]} 06:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:09 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')]} 06:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:09 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:09 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 06:48:09 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 06:48:09 INFO - Using env: {'DISPLAY': ':0', 06:48:09 INFO - 'HOME': '/home/cltbld', 06:48:09 INFO - 'LANG': 'en_US.UTF-8', 06:48:09 INFO - 'LANGUAGE': 'en_US:en', 06:48:09 INFO - 'LOGNAME': 'cltbld', 06:48:09 INFO - 'MAIL': '/var/mail/cltbld', 06:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:09 INFO - 'MOZ_NO_REMOTE': '1', 06:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:09 INFO - 'NO_EM_RESTART': '1', 06:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:09 INFO - 'PWD': '/builds/slave/test', 06:48:09 INFO - 'SHELL': '/bin/bash', 06:48:09 INFO - 'SHLVL': '1', 06:48:09 INFO - 'TERM': 'linux', 06:48:09 INFO - 'TMOUT': '86400', 06:48:09 INFO - 'USER': 'cltbld', 06:48:09 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:09 INFO - '_': '/tools/buildbot/bin/python'} 06:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:09 INFO - Downloading/unpacking psutil>=3.1.1 06:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:48:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:10 INFO - Installing collected packages: psutil 06:48:10 INFO - Running setup.py install for psutil 06:48:10 INFO - building 'psutil._psutil_linux' extension 06:48:10 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 06:48:10 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 06:48:10 INFO - building 'psutil._psutil_posix' extension 06:48:10 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 06:48:11 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 06:48:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:11 INFO - Successfully installed psutil 06:48:11 INFO - Cleaning up... 06:48:11 INFO - Return code: 0 06:48:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:48:11 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')]} 06:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:11 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')]} 06:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:11 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:11 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 06:48:11 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 06:48:11 INFO - Using env: {'DISPLAY': ':0', 06:48:11 INFO - 'HOME': '/home/cltbld', 06:48:11 INFO - 'LANG': 'en_US.UTF-8', 06:48:11 INFO - 'LANGUAGE': 'en_US:en', 06:48:11 INFO - 'LOGNAME': 'cltbld', 06:48:11 INFO - 'MAIL': '/var/mail/cltbld', 06:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:11 INFO - 'MOZ_NO_REMOTE': '1', 06:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:11 INFO - 'NO_EM_RESTART': '1', 06:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:11 INFO - 'PWD': '/builds/slave/test', 06:48:11 INFO - 'SHELL': '/bin/bash', 06:48:11 INFO - 'SHLVL': '1', 06:48:11 INFO - 'TERM': 'linux', 06:48:11 INFO - 'TMOUT': '86400', 06:48:11 INFO - 'USER': 'cltbld', 06:48:11 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:11 INFO - '_': '/tools/buildbot/bin/python'} 06:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:48:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:48:12 INFO - Installing collected packages: mozsystemmonitor 06:48:12 INFO - Running setup.py install for mozsystemmonitor 06:48:12 INFO - Successfully installed mozsystemmonitor 06:48:12 INFO - Cleaning up... 06:48:12 INFO - Return code: 0 06:48:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:48:12 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')]} 06:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:12 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')]} 06:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:12 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:12 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 06:48:12 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 06:48:12 INFO - Using env: {'DISPLAY': ':0', 06:48:12 INFO - 'HOME': '/home/cltbld', 06:48:12 INFO - 'LANG': 'en_US.UTF-8', 06:48:12 INFO - 'LANGUAGE': 'en_US:en', 06:48:12 INFO - 'LOGNAME': 'cltbld', 06:48:12 INFO - 'MAIL': '/var/mail/cltbld', 06:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:12 INFO - 'MOZ_NO_REMOTE': '1', 06:48:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:12 INFO - 'NO_EM_RESTART': '1', 06:48:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:12 INFO - 'PWD': '/builds/slave/test', 06:48:12 INFO - 'SHELL': '/bin/bash', 06:48:12 INFO - 'SHLVL': '1', 06:48:12 INFO - 'TERM': 'linux', 06:48:12 INFO - 'TMOUT': '86400', 06:48:12 INFO - 'USER': 'cltbld', 06:48:12 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:12 INFO - '_': '/tools/buildbot/bin/python'} 06:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:12 INFO - Downloading/unpacking jsonschema==2.5.1 06:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:48:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:14 INFO - Downloading functools32-3.2.3-2.tar.gz 06:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:48:14 INFO - warning: no files found matching '*.txt' 06:48:14 INFO - no previously-included directories found matching 'build' 06:48:14 INFO - no previously-included directories found matching 'dist' 06:48:14 INFO - no previously-included directories found matching '.git*' 06:48:14 INFO - Installing collected packages: jsonschema, functools32 06:48:14 INFO - Running setup.py install for functools32 06:48:14 INFO - warning: no files found matching '*.txt' 06:48:14 INFO - no previously-included directories found matching 'build' 06:48:14 INFO - no previously-included directories found matching 'dist' 06:48:14 INFO - no previously-included directories found matching '.git*' 06:48:14 INFO - Successfully installed jsonschema functools32 06:48:14 INFO - Cleaning up... 06:48:14 INFO - Return code: 0 06:48:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:48:14 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')]} 06:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:14 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')]} 06:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:14 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:14 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 06:48:14 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 06:48:14 INFO - Using env: {'DISPLAY': ':0', 06:48:14 INFO - 'HOME': '/home/cltbld', 06:48:14 INFO - 'LANG': 'en_US.UTF-8', 06:48:14 INFO - 'LANGUAGE': 'en_US:en', 06:48:14 INFO - 'LOGNAME': 'cltbld', 06:48:14 INFO - 'MAIL': '/var/mail/cltbld', 06:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:14 INFO - 'MOZ_NO_REMOTE': '1', 06:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:14 INFO - 'NO_EM_RESTART': '1', 06:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:14 INFO - 'PWD': '/builds/slave/test', 06:48:14 INFO - 'SHELL': '/bin/bash', 06:48:14 INFO - 'SHLVL': '1', 06:48:14 INFO - 'TERM': 'linux', 06:48:14 INFO - 'TMOUT': '86400', 06:48:14 INFO - 'USER': 'cltbld', 06:48:14 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:14 INFO - '_': '/tools/buildbot/bin/python'} 06:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:48:14 INFO - Cleaning up... 06:48:14 INFO - Return code: 0 06:48:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:48:14 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')]} 06:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:14 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')]} 06:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:14 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:14 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 06:48:14 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 06:48:14 INFO - Using env: {'DISPLAY': ':0', 06:48:14 INFO - 'HOME': '/home/cltbld', 06:48:14 INFO - 'LANG': 'en_US.UTF-8', 06:48:14 INFO - 'LANGUAGE': 'en_US:en', 06:48:14 INFO - 'LOGNAME': 'cltbld', 06:48:14 INFO - 'MAIL': '/var/mail/cltbld', 06:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:14 INFO - 'MOZ_NO_REMOTE': '1', 06:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:14 INFO - 'NO_EM_RESTART': '1', 06:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:14 INFO - 'PWD': '/builds/slave/test', 06:48:14 INFO - 'SHELL': '/bin/bash', 06:48:14 INFO - 'SHLVL': '1', 06:48:14 INFO - 'TERM': 'linux', 06:48:14 INFO - 'TMOUT': '86400', 06:48:14 INFO - 'USER': 'cltbld', 06:48:14 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:14 INFO - '_': '/tools/buildbot/bin/python'} 06:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:14 INFO - Downloading/unpacking blobuploader==1.2.4 06:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:15 INFO - Downloading blobuploader-1.2.4.tar.gz 06:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:48:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:48:15 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 06:48:15 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 06:48:15 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 06:48:15 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 06:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:48:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:16 INFO - Downloading docopt-0.6.1.tar.gz 06:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:48:16 INFO - Installing collected packages: blobuploader, requests, docopt 06:48:16 INFO - Running setup.py install for blobuploader 06:48:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:48:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:48:16 INFO - Running setup.py install for requests 06:48:16 INFO - Running setup.py install for docopt 06:48:17 INFO - Successfully installed blobuploader requests docopt 06:48:17 INFO - Cleaning up... 06:48:17 INFO - Return code: 0 06:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:17 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')]} 06:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:17 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')]} 06:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:17 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:17 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 06:48:17 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 06:48:17 INFO - Using env: {'DISPLAY': ':0', 06:48:17 INFO - 'HOME': '/home/cltbld', 06:48:17 INFO - 'LANG': 'en_US.UTF-8', 06:48:17 INFO - 'LANGUAGE': 'en_US:en', 06:48:17 INFO - 'LOGNAME': 'cltbld', 06:48:17 INFO - 'MAIL': '/var/mail/cltbld', 06:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:17 INFO - 'MOZ_NO_REMOTE': '1', 06:48:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:17 INFO - 'NO_EM_RESTART': '1', 06:48:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:17 INFO - 'PWD': '/builds/slave/test', 06:48:17 INFO - 'SHELL': '/bin/bash', 06:48:17 INFO - 'SHLVL': '1', 06:48:17 INFO - 'TERM': 'linux', 06:48:17 INFO - 'TMOUT': '86400', 06:48:17 INFO - 'USER': 'cltbld', 06:48:17 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:17 INFO - '_': '/tools/buildbot/bin/python'} 06:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:17 INFO - Running setup.py (path:/tmp/pip-61qk6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:17 INFO - Running setup.py (path:/tmp/pip-NIHnGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:17 INFO - Running setup.py (path:/tmp/pip-fup96l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:17 INFO - Running setup.py (path:/tmp/pip-ewh2Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:17 INFO - Running setup.py (path:/tmp/pip-KVPYpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:17 INFO - Running setup.py (path:/tmp/pip-6d44PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:17 INFO - Running setup.py (path:/tmp/pip-7gopU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:17 INFO - Running setup.py (path:/tmp/pip-2ceiDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:17 INFO - Running setup.py (path:/tmp/pip-6onda9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:18 INFO - Running setup.py (path:/tmp/pip-LsIyF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:18 INFO - Running setup.py (path:/tmp/pip-xOegrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:18 INFO - Running setup.py (path:/tmp/pip-BWjXSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:18 INFO - Running setup.py (path:/tmp/pip-3JolWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:18 INFO - Running setup.py (path:/tmp/pip-KrtKD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:18 INFO - Running setup.py (path:/tmp/pip-TsQ7bF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:18 INFO - Running setup.py (path:/tmp/pip-hWB3JY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:18 INFO - Running setup.py (path:/tmp/pip-HTHoA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:48:18 INFO - Running setup.py install for manifestparser 06:48:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Running setup.py install for mozcrash 06:48:18 INFO - Running setup.py install for mozdebug 06:48:18 INFO - Running setup.py install for mozdevice 06:48:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for mozfile 06:48:19 INFO - Running setup.py install for mozhttpd 06:48:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for mozinfo 06:48:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for mozInstall 06:48:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for mozleak 06:48:19 INFO - Running setup.py install for mozlog 06:48:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for moznetwork 06:48:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for mozprocess 06:48:19 INFO - Running setup.py install for mozprofile 06:48:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for mozrunner 06:48:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:48:20 INFO - Running setup.py install for mozscreenshot 06:48:20 INFO - Running setup.py install for moztest 06:48:20 INFO - Running setup.py install for mozversion 06:48:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:48:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:48:20 INFO - Cleaning up... 06:48:20 INFO - Return code: 0 06:48:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48: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')]} 06:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:20 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:20 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 06:48:20 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 06:48:20 INFO - Using env: {'DISPLAY': ':0', 06:48:20 INFO - 'HOME': '/home/cltbld', 06:48:20 INFO - 'LANG': 'en_US.UTF-8', 06:48:20 INFO - 'LANGUAGE': 'en_US:en', 06:48:20 INFO - 'LOGNAME': 'cltbld', 06:48:20 INFO - 'MAIL': '/var/mail/cltbld', 06:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:20 INFO - 'MOZ_NO_REMOTE': '1', 06:48:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:20 INFO - 'NO_EM_RESTART': '1', 06:48:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:20 INFO - 'PWD': '/builds/slave/test', 06:48:20 INFO - 'SHELL': '/bin/bash', 06:48:20 INFO - 'SHLVL': '1', 06:48:20 INFO - 'TERM': 'linux', 06:48:20 INFO - 'TMOUT': '86400', 06:48:20 INFO - 'USER': 'cltbld', 06:48:20 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:20 INFO - '_': '/tools/buildbot/bin/python'} 06:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:20 INFO - Running setup.py (path:/tmp/pip-Z0RIOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:20 INFO - Running setup.py (path:/tmp/pip-teYEA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:20 INFO - Running setup.py (path:/tmp/pip-uUyax2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:20 INFO - Running setup.py (path:/tmp/pip-OzaGkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:20 INFO - Running setup.py (path:/tmp/pip-mcrNe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:20 INFO - Running setup.py (path:/tmp/pip-22_btt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:21 INFO - Running setup.py (path:/tmp/pip-TlmJ_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:21 INFO - Running setup.py (path:/tmp/pip-15Blu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:21 INFO - Running setup.py (path:/tmp/pip-qA2XUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:21 INFO - Running setup.py (path:/tmp/pip-_FiAdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:21 INFO - Running setup.py (path:/tmp/pip-UluWIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:21 INFO - Running setup.py (path:/tmp/pip-zDWZG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:21 INFO - Running setup.py (path:/tmp/pip-QFC22a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:21 INFO - Running setup.py (path:/tmp/pip-PMepAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:21 INFO - Running setup.py (path:/tmp/pip-_DSNtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:21 INFO - Running setup.py (path:/tmp/pip-sKLzoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:21 INFO - Running setup.py (path:/tmp/pip-bG58x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:21 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)) 06:48:21 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)) 06:48:21 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)) 06:48:21 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)) 06:48:21 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)) 06:48:21 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)) 06:48:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:23 INFO - Downloading blessings-1.6.tar.gz 06:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:48:23 INFO - Installing collected packages: blessings 06:48:23 INFO - Running setup.py install for blessings 06:48:23 INFO - Successfully installed blessings 06:48:23 INFO - Cleaning up... 06:48:23 INFO - Return code: 0 06:48:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:23 INFO - Reading from file tmpfile_stdout 06:48:23 INFO - Current package versions: 06:48:23 INFO - argparse == 1.2.1 06:48:23 INFO - blessings == 1.6 06:48:23 INFO - blobuploader == 1.2.4 06:48:23 INFO - docopt == 0.6.1 06:48:23 INFO - functools32 == 3.2.3-2 06:48:23 INFO - jsonschema == 2.5.1 06:48:23 INFO - manifestparser == 1.1 06:48:23 INFO - mozInstall == 1.12 06:48:23 INFO - mozcrash == 1.0 06:48:23 INFO - mozdebug == 0.1 06:48:23 INFO - mozdevice == 0.49 06:48:23 INFO - mozfile == 1.2 06:48:23 INFO - mozhttpd == 0.7 06:48:23 INFO - mozinfo == 0.9 06:48:23 INFO - mozleak == 0.1 06:48:23 INFO - mozlog == 3.4 06:48:23 INFO - moznetwork == 0.27 06:48:23 INFO - mozprocess == 0.25 06:48:23 INFO - mozprofile == 0.28 06:48:23 INFO - mozrunner == 6.13 06:48:23 INFO - mozscreenshot == 0.1 06:48:23 INFO - mozsystemmonitor == 0.3 06:48:23 INFO - moztest == 0.8 06:48:23 INFO - mozversion == 1.4 06:48:23 INFO - psutil == 3.1.1 06:48:23 INFO - requests == 1.2.3 06:48:23 INFO - wsgiref == 0.1.2 06:48:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:23 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')]} 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:23 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')]} 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:23 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:23 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 06:48:23 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 06:48:23 INFO - Using env: {'DISPLAY': ':0', 06:48:23 INFO - 'HOME': '/home/cltbld', 06:48:23 INFO - 'LANG': 'en_US.UTF-8', 06:48:23 INFO - 'LANGUAGE': 'en_US:en', 06:48:23 INFO - 'LOGNAME': 'cltbld', 06:48:23 INFO - 'MAIL': '/var/mail/cltbld', 06:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:23 INFO - 'MOZ_NO_REMOTE': '1', 06:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:23 INFO - 'NO_EM_RESTART': '1', 06:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:23 INFO - 'PWD': '/builds/slave/test', 06:48:23 INFO - 'SHELL': '/bin/bash', 06:48:23 INFO - 'SHLVL': '1', 06:48:23 INFO - 'TERM': 'linux', 06:48:23 INFO - 'TMOUT': '86400', 06:48:23 INFO - 'USER': 'cltbld', 06:48:23 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:23 INFO - '_': '/tools/buildbot/bin/python'} 06:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 INFO - Cleaning up... 06:48:23 INFO - Return code: 0 06:48:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:48:23 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')]} 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:23 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')]} 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:23 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 0x29241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2962a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc2310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, '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': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', '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 06:48:23 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 06:48:23 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 06:48:23 INFO - Using env: {'DISPLAY': ':0', 06:48:23 INFO - 'HOME': '/home/cltbld', 06:48:23 INFO - 'LANG': 'en_US.UTF-8', 06:48:23 INFO - 'LANGUAGE': 'en_US:en', 06:48:23 INFO - 'LOGNAME': 'cltbld', 06:48:23 INFO - 'MAIL': '/var/mail/cltbld', 06:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:23 INFO - 'MOZ_NO_REMOTE': '1', 06:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:23 INFO - 'NO_EM_RESTART': '1', 06:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:23 INFO - 'PWD': '/builds/slave/test', 06:48:23 INFO - 'SHELL': '/bin/bash', 06:48:23 INFO - 'SHLVL': '1', 06:48:23 INFO - 'TERM': 'linux', 06:48:23 INFO - 'TMOUT': '86400', 06:48:23 INFO - 'USER': 'cltbld', 06:48:23 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:23 INFO - '_': '/tools/buildbot/bin/python'} 06:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:48:23 INFO - Cleaning up... 06:48:23 INFO - Return code: 0 06:48:23 INFO - Running post-action listener: _resource_record_post_action 06:48:23 INFO - Running post-action listener: _start_resource_monitoring 06:48:23 INFO - Starting resource monitoring. 06:48:23 INFO - [mozharness: 2017-05-24 13:48:23.983504Z] Finished create-virtualenv step (success) 06:48:23 INFO - [mozharness: 2017-05-24 13:48:23.984023Z] Running install step. 06:48:23 INFO - Running pre-action listener: _resource_record_pre_action 06:48:23 INFO - Running main action method: install 06:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:24 INFO - Reading from file tmpfile_stdout 06:48:24 INFO - Detecting whether we're running mozinstall >=1.0... 06:48:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:48:24 INFO - Reading from file tmpfile_stdout 06:48:24 INFO - Output received: 06:48:24 INFO - Usage: mozinstall [options] installer 06:48:24 INFO - Options: 06:48:24 INFO - -h, --help show this help message and exit 06:48:24 INFO - -d DEST, --destination=DEST 06:48:24 INFO - Directory to install application into. [default: 06:48:24 INFO - "/builds/slave/test"] 06:48:24 INFO - --app=APP Application being installed. [default: firefox] 06:48:24 INFO - mkdir: /builds/slave/test/build/application 06:48:24 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'] 06:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:48:38 INFO - Reading from file tmpfile_stdout 06:48:38 INFO - Output received: 06:48:38 INFO - /builds/slave/test/build/application/firefox/firefox 06:48:38 INFO - Running post-action listener: _resource_record_post_action 06:48:38 INFO - [mozharness: 2017-05-24 13:48:38.257657Z] Finished install step (success) 06:48:38 INFO - [mozharness: 2017-05-24 13:48:38.257990Z] Running run-tests step. 06:48:38 INFO - Running pre-action listener: _resource_record_pre_action 06:48:38 INFO - Running main action method: run_tests 06:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:48:38 INFO - Python 2.7.3 06:48:38 INFO - Return code: 0 06:48:38 INFO - grabbing minidump binary from tooltool 06:48:38 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')]} 06:48:38 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 0x2bbf400>, '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 0x2bc03b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:48:38 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 06:48:38 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 06:48:38 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 06:48:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:48:38 INFO - Return code: 0 06:48:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:48:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:48:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:48:38 INFO - ENV: RUST_BACKTRACE is now 1 06:48:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:48:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:48:38 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/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 06:48:38 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/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 06:48:38 INFO - Using env: {'DISPLAY': ':0', 06:48:38 INFO - 'HOME': '/home/cltbld', 06:48:38 INFO - 'LANG': 'en_US.UTF-8', 06:48:38 INFO - 'LANGUAGE': 'en_US:en', 06:48:38 INFO - 'LOGNAME': 'cltbld', 06:48:38 INFO - 'MAIL': '/var/mail/cltbld', 06:48:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:48:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:38 INFO - 'MOZ_NO_REMOTE': '1', 06:48:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:48:38 INFO - 'NO_EM_RESTART': '1', 06:48:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:38 INFO - 'PWD': '/builds/slave/test', 06:48:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:48:38 INFO - 'RUST_BACKTRACE': '1', 06:48:38 INFO - 'SHELL': '/bin/bash', 06:48:38 INFO - 'SHLVL': '1', 06:48:38 INFO - 'TERM': 'linux', 06:48:38 INFO - 'TMOUT': '86400', 06:48:38 INFO - 'USER': 'cltbld', 06:48:38 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702', 06:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:38 INFO - '_': '/tools/buildbot/bin/python'} 06:48:38 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/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 06:48:38 INFO - mozversion application_buildid: 20170524125506 06:48:38 INFO - mozversion application_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 06:48:38 INFO - mozversion application_display_name: Firefox 06:48:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:48:38 INFO - mozversion application_name: Firefox 06:48:38 INFO - mozversion application_remotingname: firefox 06:48:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:48:38 INFO - mozversion application_vendor: Mozilla 06:48:38 INFO - mozversion application_version: 54.0 06:48:38 INFO - mozversion platform_buildid: 20170524125506 06:48:38 INFO - mozversion platform_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 06:48:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:48:38 INFO - mozversion platform_version: 54.0 06:48:38 INFO - using testdate: 1495633718 06:48:38 INFO - actual date: 1495633718 06:48:38 INFO - starting webserver on 'localhost:56363' 06:48:38 INFO - SUITE-START | Running 2 tests 06:48:38 INFO - TEST-START | dromaeo_css 06:48:38 INFO - operating with platform_type : linux_ 06:48:38 INFO - Initialising browser for dromaeo_css test... 06:48:38 INFO - TEST-INFO | started process 8061 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUG8sS3/profile http://localhost:56363/getInfo.html) 06:48:42 INFO - PROCESS | 8061 | __metrics Screen width/height:1600/1200 06:48:42 INFO - PROCESS | 8061 | colorDepth:24 06:48:42 INFO - PROCESS | 8061 | Browser inner width/height: 1024/697 06:48:42 INFO - PROCESS | 8061 | __metrics 06:48:43 INFO - PROCESS | 8061 | 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" 06:48:43 INFO - PROCESS | 8061 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:48:44 INFO - TEST-INFO | 8061: exit 0 06:48:44 INFO - Browser initialized. 06:48:44 INFO - Running cycle 1/1 for dromaeo_css test... 06:48:44 INFO - TEST-INFO | started process 8150 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUG8sS3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:48:56 INFO - PROCESS | 8150 | RSS: Main: 141156352 06:48:56 INFO - PROCESS | 8150 | 06:51:58 INFO - PROCESS | 8150 | Cycle 1(1): loaded http://localhost:56363/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56363/tests/dromaeo/cssquery-ext.html) 06:51:59 INFO - PROCESS | 8150 | RSS: Main: 232566784 06:51:59 INFO - PROCESS | 8150 | 06:55:01 INFO - PROCESS | 8150 | Cycle 1(1): loaded http://localhost:56363/tests/dromaeo/cssquery-ext.html (next: http://localhost:56363/tests/dromaeo/cssquery-jquery.html) 06:55:02 INFO - PROCESS | 8150 | RSS: Main: 223887360 06:55:02 INFO - PROCESS | 8150 | 06:58:04 INFO - PROCESS | 8150 | Cycle 1(1): loaded http://localhost:56363/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56363/tests/dromaeo/cssquery-mootools.html) 06:58:04 INFO - PROCESS | 8150 | RSS: Main: 199213056 06:58:04 INFO - PROCESS | 8150 | 07:01:06 INFO - PROCESS | 8150 | Cycle 1(1): loaded http://localhost:56363/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56363/tests/dromaeo/cssquery-prototype.html) 07:01:07 INFO - PROCESS | 8150 | RSS: Main: 171192320 07:01:07 INFO - PROCESS | 8150 | 07:04:10 INFO - PROCESS | 8150 | Cycle 1(1): loaded http://localhost:56363/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56363/tests/dromaeo/cssquery-yui.html) 07:04:10 INFO - PROCESS | 8150 | RSS: Main: 171585536 07:04:10 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | Cycle 1(1): loaded http://localhost:56363/tests/dromaeo/cssquery-yui.html (next: http://localhost:56363/tests/dromaeo/cssquery-dojo.html) 07:07:13 INFO - PROCESS | 8150 | RSS: Main: 261808128 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | __start_tp_report 07:07:13 INFO - PROCESS | 8150 | _x_x_mozilla_page_load 07:07:13 INFO - PROCESS | 8150 | _x_x_mozilla_page_load_details 07:07:13 INFO - PROCESS | 8150 | |i|pagename|runs| 07:07:13 INFO - PROCESS | 8150 | |0;dojo.html;3408.522806807047;3428.417169081257;3441.5698037745296;3446.5691788526433;3478.513008178855;5726.1697053927155;5767.509761670263;5778.7977420790285;5781.21953536273;5797.217375654285;5834.066549352105;5873;5877.235959325281;5879.735411906464;5895.292564892219;5190.299309593194;5191.870203244911;5206.661566998133;5259.86850328741;5270.235815806705;5058.747062646871;5081.187010078388;5121.180611102224;5296;5313.203019547061;5212.582993360541;5216.426193118754;5221.817236396727;5239.580833533328;5240.816571420001;5237.423883372826;5241.711705856176;5242.5281724644965;5248.160294352923;5272.868178295535;4959.8512044638355;5055.191169412913;5146.536811686966;5154.149565321717;5203.453637368059;5607.355154157242;5640.2667653205235;5697.230873832021;5707.942920570824;5709.286339207599;6148.108524264001;6176.938230617681;6188.040853667689;6195.659238741868;6202.193714817045;6018.21763170785;6228.937710622881;6244.9687751561405;6249.625022498664;6252.812415627538;5731.226284451587;5739;5772.105323674838;5836.737346819404;5848.766049358063;6302.180716506825;6328.240611126695;6332.050192471166;6334.873302533923;6337.841553961188;5928.822135335947;6061.060535616986;6120.234970628671;6129.969350153221;6132.6933653317155;6110.327863935053;6116.67056464255;6126.24286675468;6134.355892631299;6141.84645383869;6161.507079433634;6202.317745048041;6204.410580994799;6208.50331973441;6212.658303793335;5996.490298324691;6054.545909056838;6059.36376680451;6100.450959413674;6135.32511423743;4728;4749.762511874393;4752.1921273383605;4757.286407038971;4771.785269662873;4132.8140233689555;4156.771377574262;4158.979205103955;4193.39195816611;4264.040590867092;5949.25634295713;5963.970180149072;5968.22413086296;5976.701164941823;5998.640081595118;6256.217972753406;6259.374062593704;6263.843403914848;6271.592346497463;6272.8118156455375;5745.827625171251;5750.281214848144;5762.538996880239;5959.433853783884;5965.970170149121;5283.841484755463;5294.285271488377;5309.097453432925;5310.415854256029;5322.121849894801;6378.84052898672;6384.968075159594;6395.42441180296;6403.96798016007;6418.358164183544;6036.426539478743;6131.356207598138;6197.4732147767945;6203.751849926133;6211.192544969125;6286.842828929313;6304.5586808922935;6320.241571011508;6332.430081292706;6368.044793280859;6131.101423540661;6133.1510485532735;6135.508280839814;6135.630783975068;6143.911070235962;6188.381161883955;6250.062490626442;6255.343188965275;6255.624662520263;6261.3112557617715;5904.084866845646;5940.61386009908;5944.940550594439;5969.134475501115;6062.909056364247;5974.850628734316;5981.371955944737;6039.849003774941;6067.393260673897;6075.878482430238;5805.390434004537;5888.587798854039;6015.789447369321;6029.487493563096;6029.668368239789;6012.038073908154;6026.90959635597;6076.392360763888;6077.392260774064;6080.574359794772;5956.702164891652;5960.612560183574;5965.075413310944;5974.40255974399;6006.249218847644 07:07:13 INFO - PROCESS | 8150 | |1;ext.html;788.117308614352;851.5316575883263;857.6912311567835;927.2303987690218;931.3573634192411;31600.683993160063;43908.341374879405;44162.337564936555;44241.778791106;44632.776836115816;44585.33122003172;44828.32757508632;45059.098818023704;45061.77469112658;45126.77436612821;7726.304632583066;7880.054393472791;9249.953750231256;9396.906030939672;9400.012998635148;10782;11006;11013.118950483942;11021.118310535177;11024.173187010967;11108;11150.275232109927;11150.609728659498;11169.329840209573;11171.050460710849;11058.723531911766;11077.889221107765;11101.44492775362;11120.721981950435;11130.888691113067;10767.24629275951;10791.676249712522;10810.621628242974;10846.403447810368;10860.837087443695;42637.57362426367;42758.57241427608;42890.57109428897;42891.78554107241;42904.141917161796;4228.323468245097;4278.443802305712;4310.698251122422;4416.492103408117;4486.820527178909;30147.547786783216;31043.68956310453;31196.844015780003;31788.20529486758;32220.838895805606;4357.193919124972;4384.517703052662;4444.266695995157;4522.977385113053;4531.207038768202;241113;242847;243113;243130;243602;54897.725511372984;55004.449955500735;55201.72399138059;55391;55519.44480555224;56758.71620641871;57216.141757873665;57276;57288.14067788987;57438.138427923615;16352.100634464981;16656.083915384526;16697.582560436087;16729.665406691798;16741.49775506737;13015.609531714064;13228.537001204912;14891.851081489047;14896.478623248135;14899.404023839143;12252.81620775689;12289.69275768113;12330.260184388966;13378.46486140563;13622.863771362358;5409.891802163935;5471.37079235885;5487.286652735199;5551.555875529948;5560.749766260528;1018.1956254558816;1026.6919924022764;1026.9383836969805;1031.339942436841;1032.963846265377;28251.43497130087;28398.432031359258;28837.711622883504;28860.567091493223;29012.274693132418;1077.7736675298117;1085.5983286184162;1090.6128324444867;1113.7605414835687;1124.8425220469142;14162.645933851736;16654.58363540897;16820.915895420443;17315.220815063352;17441.215145318492;326223;326505;326932;327643;327994;59281.40718592759;59338.70330648319;59432.70283648641;59576.70211649001;59786.70106649526;1488.0625206120076;1524.336913442656;1528.7706843973272;1538.415402147177;1548.612846788303;27810.582841256994;28318.716812832434;28589.142325730263;31481.527777082905;32021.67978320187;50931.236031458815;51398;51562.74218628883;51583.742081289354;51619.22571161511;341783;344909;345183;345519;445768;40154.59845401509;41331.7933410343;41421;41781;42000.789996049825;49887;50158.49841501538;50460.49539504705;50654;50741.492585073676;37028;37094.258114837015;37228.813855930544;37617.24765504729;37762.43356349602;37285.81357093306;37301.81349093237;37452.812735936146;38062.42906356351;38764.806175970065;43813;43974.340384894895;44024.55975440205;44300.33549496696;45319.773401132785;35695.82152089223;35976.63104163314;36175.27649447049;36237;36827.26345473129;29030.419391611627;29409;29556.704432955394;29563.55654665225;29837.70162298349 07:07:13 INFO - PROCESS | 8150 | |2;jquery.html;425.45966622389545;479.81527112061855;483.06044742974905;491.9704817710938;498.70077953228065;18586.90706546469;19695.31066412676;21280.14879404824;21360.145594176254;21425.035873385696;23036.769632303673;23059.538809223806;23458.530829383446;23465.296041118752;23625.76374236257;6696.3638454347;6730.326967303279;6740.494462915276;6872.072270243528;6883.552569083018;6731.8317042074095;6877.4841886858685;6889.517733758639;6905.274946130659;6908.032875397424;6603.273639899608;6716.46268298535;6745.460363170934;6746.19045714515;6755.662216889161;6628.900566491507;6719.563228390188;6740.966295168518;6760.425363844054;6819.181698196224;6592.274849766522;6608.207015158164;6621.702023408958;6626.867462650768;6629.635370054645;26449.206523804125;26494.205173844817;26873.193804185907;26881.924723011056;27044.459110817872;3185.515298067511;3219.9356012879844;3242.2542815152647;3311.983440082784;3314.6188188358165;15627.92186039074;17027.914860425743;17211;17363.826361736472;17521.649567008717;2079.9272025479036;2172.6849606806923;2190.113004233288;2467.95064098717;2583.1604728463326;110440;113394;113832;114226;115021.424892875;14429.818742844715;16502.93713285839;18408.631827363377;18513.90743046276;18635.44093677192;32381;32524.186895327806;32524.861629842842;32564.83717581397;33134.502982455284;11111.888881111247;11139.33164010162;11155;11212.943935280271;11305.660830174944;9380;9393.765155871159;9477.952610236905;9488.430694158373;9728.221742260603;9399.201067909304;9612.75968100803;9743.561539730728;9784.804303913881;9804.068613481708;3837.3860182371245;3903.590123037097;3918.5885482024546;3956.9010774730286;3961.2275606256503;978.8874279457812;984.3060642247342;987.8468837330225;990.8414653655525;1015.5886865819355;20779.064942077643;21100.155993760385;21166.365009049445;21231.36305910825;21270.893645531673;987.1658448610913;992.731962370156;1012.9898701012895;1014.325473560089;1022.99488502557;10706.78586428267;11547.076233901267;11646.184767066225;11797.410129493663;11808;601578;608882;612867;624919;632057;103707;104094;105884;106470.46764766127;108805.45597272122;1275.3113318807739;1297.7858653322096;1339.5780329196273;1358.4362489566918;1382.7925811128612;14282.714345713235;18819;19088.904555477133;19259.422217333504;19260.229590816773;45958.54041459542;49382.01235975188;52882.471175287756;53035.73482132565;53802.192967106304;64438.35561644324;64535;64590;65512.34487655253;65694;12656.11407201487;12694.365281736062;12764.40444944382;12773.680657983625;12820.166689165175;14237.786433203302;14271.857281427052;14337.498187563386;14427.711445771236;14503.202323872289;12126.454309556268;12425.937870310952;12483.93758031204;12583.559575414816;12660.303683297507;10491.422971736627;10543.841842372523;10665.146788256765;11051.336919784839;11435.59975400857;12209.450574724337;12358.567450139562;12408.937955310166;12554.811677824657;12821.743565128458;11747.412629368668;11778.46996885125;11825.46785394642;11842.644720658394;12256.387180640753;12061.879381206427;12260.754784904073;12412.317322546985;12521.31132787669;12562.366892303942 07:07:13 INFO - PROCESS | 8150 | |3;mootools.html;1565.7494800831869;1584.2949887300156;1615.8788090893183;1628.2021809313437;1628.7068327701008;10993.725156871082;11788.823167652481;11815.40922953851;11819.231749936249;11827.172097953136;11170;11477.655670329883;11487.310761354325;11516.481758320875;11547.942260288708;2511.2089691747087;2548;2551.783098436635;2552.310876063462;2553.131935142051;2449.510097980402;2513.447041650834;2555.56555385584;2559.8592077435733;2564.653771740819;2498.3254521279246;2542.224621490445;2583.8966441342322;2587.5213085579135;2590.896364145432;2400.015993442689;2453.2394957563124;2466.580681284168;2468.938276543083;2476.3561474016706;2398.616221404567;2428.1986944308337;2432.622943443769;2433.415980164765;2436.025589764091;11432.37121958292;11660.242084264495;11665.883341166564;11682.065434765198;11697.12271579635;5994.910076348859;6153.907691384633;6155.969220153916;6158.8152355428965;6166.784162554334;3486.4247399179103;3567.94648080279;3593.82030898454;3677.1174918019483;3721.2371463849845;1395.644110751762;2258.9209377671705;2263.196565219356;2306.342692332694;2307.919222827193;14641.121532708072;15069.321880515403;16235.83764162367;17109.144542772938;17180.742288865677;5007.148784706608;5152.046871328741;5221.242919776611;5302.125149350314;5306.867328316745;14129.929350353388;14131.010829241854;14150.150990940576;14166.64583385403;14168.433262669583;6280.9685951569945;6317.084022816758;6318.620882746958;6320.146780184708;6320.71556779946;5659.43405659439;5724.971375143097;5785.566082543826;5837.854053648693;5842.678652674143;4392.538783427758;4462.799174037176;4494.52807455219;4548.681592288508;4551.431071116111;1174.2895548193392;1205.5780476833038;1223.9816402753788;1225.4852961756085;1239;1083.0793825248477;1108.772701596171;1111.2554588425774;1114.7380365614074;1116.380408873067;7066.787996360117;7098.3256590623805;7099.3965512930945;7125.073740413713;7127.394171495377;1080.4273734920505;1091.4651820608003;1106.2145876427826;1113.0817075912405;1117.7596816684452;4371.475422949266;4501.819927202875;4524.366588677588;4555.544445555418;4626.537346265414;14316.785248221286;14917.850821491646;14927.25363731809;14943.551693449213;14950.401983920521;13598.32008399576;14154;14695.191764452844;14818.333175006936;14911.850881491046;8762.386632935633;10656.147508199325;10754.946225268981;10830.945845270724;10861;6327.240731112296;7217.206107328295;7498.62506874665;7560.206178351194;7599.430042746927;12140.15018948665;12222.388880555758;12242.938785306016;12253.877461225273;12400.441980110736;12506.74986500283;12662.050346223887;12675.366231688564;12733.55432559856;12746.362681866056;9544.236461082957;9936.304458687822;10151.64469243573;10161.441120738431;10170.74573135648;11157.72105697364;11424.657260282205;11444.599439019594;11450.026747726537;11462.083033357478;9483.336166468282;9557.092076252746;9687.9031209687;9772.9022709772;9916.355436896578;9059.547022648972;9135.086491350812;9229.86155207659;9305.534723263674;9309.581068851783;8309.792255193668;8396.916030839613;8403.369747268856;8434.662613495391;8493.830123397622;6538.934610653832;6664.633445160563;6736.124303840469;6747.122874026345;6808.965955170391;6420.967895160495;6527.706253218522;6590.110335104891;6638.435732962752;6738.225104113089 07:07:13 INFO - PROCESS | 8150 | |4;prototype.html;424.64329962831215;453.6484224725837;454.4138061900151;457.5378867343982;458.72018068977917;5338.733063346832;5341.813036543722;5346.903884703626;5349.679019258838;5360.606242376981;5136.476304032694;5211.218317252414;5295.947040529593;5303.787848486056;5330.893382132355;4227.471566054251;4241.660667146636;4247.5964783345535;4250.404943307939;4282.50751163617;4260.297050986584;4289;4295.656347492193;4301.8064187111495;4331.3719510670935;4140.012793858949;4256.170046840852;4279.422277992462;4285.742855428669;4285.935710964338;4241.936370954453;4249.68127390447;4251.725326950098;4285.849995250167;4309.676774241913;3907.1990242000606;3913.4912461380122;3919.98040009801;3957.465742124834;3966.464527288808;5203.19350500673;5277.525022747971;5317.388500322474;5334.22653715213;5334.253204551366;4749.762511874427;5000.1999680051385;5043.293938848564;5052.747362631891;5083.364579427572;5494.835154945318;5538.6399884007415;5591.2731344925305;5603.887922241533;5636.210930469738;4337.826486940518;4345.41336919515;4357.968878787109;4377.562243775629;4391.231534481453;5528.894221155785;5550.5282051025715;5601.887962240773;5651.1523271509195;5824.2625965796005;4727.172744769651;4742.288656701522;4747.8575642730775;4765.975315307225;4812.494688057704;4865.854024379274;4885.047415753894;4891.997140586172;4894.853154405374;4899.946511500045;5040.697558146523;5115.693058416507;5122.487751224848;5179.539718633619;5193.896122077613;5545.750441230154;5558.1093234735845;5571.804986825442;5596.776128954796;5608.439156084441;1654.3548016273662;1654.8664165046982;1661.0199982010672;1667.5247554446923;1681.8318168183084;1256.2588073036952;1280.6872955220788;1290.4064130500076;1301.568274897605;1315.7960516120017;5398.64908780928;5443.074677304868;5488.91766623501;5510.641808282529;5510.90585577478;5568.777248910079;5742.655440673573;5778.306603207642;5778.393268706809;5786.045302525036;5371.045924741376;5379.73101344939;5414.214938833847;5459.372172200247;5459.563234941195;2987.775916806248;2993.7098119938264;3013.0565785068507;3021.743151832119;3030.7876849260033;5526.253955715927;5780.364159942403;5863.237779088691;5878.735456904449;5895.204147440127;5812.44781745742;5821.359650438449;5822.912656310159;5837.241158649348;5844.795432159939;5862.14998825168;5879.648140743651;5891.734871930687;5908.3751968209945;5924.763009479659;5416.404195538409;5682.857014401973;5735.311762588516;5735.4551317624755;5741.153427203867;5452.645578037414;5612.663240205601;5657.9151312731165;5673.205751194753;5675.31896172462;5578.2748242728185;5759.94240057594;5766.826995190151;5788.697543052779;5829.94170058294;5038.924416133834;5071.467495913023;5094.7707353169935;5106;5116.874287656709;5456.345238571397;5614.9719251403485;5702.97346477638;5717.3996730342715;5731.455511726379;5534.837684086387;5538.556915446674;5550;5577.637453565505;5583.302087239095;5526.64597173695;5548.750306236309;5606.24315717373;5612.074007788669;5650.604457687922;5315.893682126413;5537.6465002373025;5609.289166804164;5615.624172077867;5621.887562248814;5544.916826247691;5561.610687252015;5581.079121944834;5606.85982850432;5621.381648018633;5253.45391860485;5441.918371224514;5460.890782184255;5479.369872464717;5542.3626282977975 07:07:13 INFO - PROCESS | 8150 | |5;yui.html;849.8895143631327;869.4435561240807;906.4606559097339;925.6575067225126;945.4658118163237;17498.30006799728;17728.556786080353;18288.45134645961;18444.723329150074;18497.537561560952;17483.12584370783;17669.823301766977;17688.292468301253;17751.20119594618;17767.73348399772;888.04535124741;888.3781353052857;908.8227795579865;910.2172131966504;913.6071489259616;875.479089941487;884.7213127864702;884.995575022124;886.410536992899;886.5478605911;957.1003256938455;958.7555173430766;961.4423634292128;961.725908116187;962.9711108666717;944.5135755086136;945.938513996588;947.2564037230741;948.127722495306;956.4787190980932;695.5305169010898;698.2145086777375;701.123595505618;701.3687681087063;701.5404909784099;18173.909130454394;18174.18216180262;18334.358297459657;18849.811501884942;19144.712829307573;15589.766153507706;15818.446354377656;15823.920880395524;16125.758113628304;16810.934167284027;13299.66750831237;13404.46382144713;13755.79366309506;13895;13897.444102235999;3976.4631774710333;4035.4955630546183;4036.495438070241;4055.1078762672164;4138.234426631053;84953;85362.57318713366;87158.5642071792;88754;88924.5553772227;12582.496700132075;12600.684982875502;12652.746945061048;12802.743945121232;12984.610461685976;24834.00663973457;24848.130315438873;24862.12982545602;24885;25183;7990.200979901963;8118.472299300526;8344.415890887702;8468.364872634576;8741.169588889044;4722.797733456507;4848.9757551212015;5001.174806157016;5021.096202683479;5185.014847179099;3708.554973403209;3814.828332725034;3816.3893776995546;3819.7135214858995;3823.1780167264174;651.3519048546726;667.346000919102;671.1241829412565;682.5426963934077;683.8495530983175;638.3425072175573;640.6540468147149;644.5584774429531;659.2583343738295;662.4898827902584;11104.222704410613;11149.944250278859;11463.369514676606;11536.76926461466;11784.70538236551;820.9753707388788;824.464770829701;834.3241974001079;849.961751721174;853.8676505141713;5455.208994695747;5630.577706672016;5660.15097735343;5697.401772813878;5717.514011309002;81791;81871.5906420464;85118;85135;87861;49346.50653493491;61861.38138618646;66960.33039669541;67466.66266668636;67525.32474675191;60210.698946504985;61099.083513747326;61228;61432.385676142665;61517;11282.492287846902;11864.525418983145;11873.862704713234;12131.514739410324;12186.329751863732;41098;41991.16017679568;42153.78923105365;42155.15689686128;42812.143757125305;47065.52934470611;48160.518394815605;48253.75873120612;48354.75822620864;49976.75011625064;12058.457369418573;12329.321887296284;12339.383030848601;12343.321117338633;12706.9199118076;15561.37754489853;15879.841201587837;16204.756928645844;16321.836781632031;17056.06191659421;15270.847291526943;15300.923495382453;15302.923485382502;15835.604109897346;16443.506694799176;14697.779533307223;15001.6999660004;15502.147381894105;15527.301271442586;15533.41002923369;14875.776863346842;15558.299876505358;15788;15885;15934.760978585562;10430.42632655181;10532.631357902437;10615.946920265658;11045;11184.94407527957;11291.491882865126;12118.818217726564;12193.695157620776;12433.502659893504;12469.189502682297 07:07:13 INFO - PROCESS | 8150 | __end_tp_report 07:07:13 INFO - PROCESS | 8150 | __start_cc_report 07:07:13 INFO - PROCESS | 8150 | _x_x_mozilla_cycle_collect,1336 07:07:13 INFO - PROCESS | 8150 | __end_cc_report 07:07:13 INFO - PROCESS | 8150 | __startTimestamp1495634833469__endTimestamp 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | ------- Summary: start ------- 07:07:13 INFO - PROCESS | 8150 | Number of tests: 6 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | [#0] dojo.html Cycles:165 Average:5734.21 Median:5969.13 stddev:649.42 (10.9%) stddev-sans-first:625.26 07:07:13 INFO - PROCESS | 8150 | Values: 3408.5 3428.4 3441.6 3446.6 3478.5 5726.2 5767.5 5778.8 5781.2 5797.2 5834.1 5873.0 5877.2 5879.7 5895.3 5190.3 5191.9 5206.7 5259.9 5270.2 5058.7 5081.2 5121.2 5296.0 5313.2 5212.6 5216.4 5221.8 5239.6 5240.8 5237.4 5241.7 5242.5 5248.2 5272.9 4959.9 5055.2 5146.5 5154.1 5203.5 5607.4 5640.3 5697.2 5707.9 5709.3 6148.1 6176.9 6188.0 6195.7 6202.2 6018.2 6228.9 6245.0 6249.6 6252.8 5731.2 5739.0 5772.1 5836.7 5848.8 6302.2 6328.2 6332.1 6334.9 6337.8 5928.8 6061.1 6120.2 6130.0 6132.7 6110.3 6116.7 6126.2 6134.4 6141.8 6161.5 6202.3 6204.4 6208.5 6212.7 5996.5 6054.5 6059.4 6100.5 6135.3 4728.0 4749.8 4752.2 4757.3 4771.8 4132.8 4156.8 4159.0 4193.4 4264.0 5949.3 5964.0 5968.2 5976.7 5998.6 6256.2 6259.4 6263.8 6271.6 6272.8 5745.8 5750.3 5762.5 5959.4 5966.0 5283.8 5294.3 5309.1 5310.4 5322.1 6378.8 6385.0 6395.4 6404.0 6418.4 6036.4 6131.4 6197.5 6203.8 6211.2 6286.8 6304.6 6320.2 6332.4 6368.0 6131.1 6133.2 6135.5 6135.6 6143.9 6188.4 6250.1 6255.3 6255.6 6261.3 5904.1 5940.6 5944.9 5969.1 6062.9 5974.9 5981.4 6039.8 6067.4 6075.9 5805.4 5888.6 6015.8 6029.5 6029.7 6012.0 6026.9 6076.4 6077.4 6080.6 5956.7 5960.6 5965.1 5974.4 6006.2 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | [#1] ext.html Cycles:180 Average:49590.08 Median:35836.23 stddev:82816.68 (231.1%) stddev-sans-first:82967.94 07:07:13 INFO - PROCESS | 8150 | Values: 788.1 851.5 857.7 927.2 931.4 31600.7 43908.3 44162.3 44241.8 44632.8 44585.3 44828.3 45059.1 45061.8 45126.8 7726.3 7880.1 9250.0 9396.9 9400.0 10782.0 11006.0 11013.1 11021.1 11024.2 11108.0 11150.3 11150.6 11169.3 11171.1 11058.7 11077.9 11101.4 11120.7 11130.9 10767.2 10791.7 10810.6 10846.4 10860.8 42637.6 42758.6 42890.6 42891.8 42904.1 4228.3 4278.4 4310.7 4416.5 4486.8 30147.5 31043.7 31196.8 31788.2 32220.8 4357.2 4384.5 4444.3 4523.0 4531.2 241113.0 242847.0 243113.0 243130.0 243602.0 54897.7 55004.4 55201.7 55391.0 55519.4 56758.7 57216.1 57276.0 57288.1 57438.1 16352.1 16656.1 16697.6 16729.7 16741.5 13015.6 13228.5 14891.9 14896.5 14899.4 12252.8 12289.7 12330.3 13378.5 13622.9 5409.9 5471.4 5487.3 5551.6 5560.7 1018.2 1026.7 1026.9 1031.3 1033.0 28251.4 28398.4 28837.7 28860.6 29012.3 1077.8 1085.6 1090.6 1113.8 1124.8 14162.6 16654.6 16820.9 17315.2 17441.2 326223.0 326505.0 326932.0 327643.0 327994.0 59281.4 59338.7 59432.7 59576.7 59786.7 1488.1 1524.3 1528.8 1538.4 1548.6 27810.6 28318.7 28589.1 31481.5 32021.7 50931.2 51398.0 51562.7 51583.7 51619.2 341783.0 344909.0 345183.0 345519.0 445768.0 40154.6 41331.8 41421.0 41781.0 42000.8 49887.0 50158.5 50460.5 50654.0 50741.5 37028.0 37094.3 37228.8 37617.2 37762.4 37285.8 37301.8 37452.8 38062.4 38764.8 43813.0 43974.3 44024.6 44300.3 45319.8 35695.8 35976.6 36175.3 36237.0 36827.3 29030.4 29409.0 29556.7 29563.6 29837.7 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | [#2] jquery.html Cycles:180 Average:36217.28 Median:2181.40 stddev:101555.27 (4655.5%) stddev-sans-first:101804.60 07:07:13 INFO - PROCESS | 8150 | Values: 425.5 479.8 483.1 492.0 498.7 18586.9 19695.3 21280.1 21360.1 21425.0 23036.8 23059.5 23458.5 23465.3 23625.8 6696.4 6730.3 6740.5 6872.1 6883.6 6731.8 6877.5 6889.5 6905.3 6908.0 6603.3 6716.5 6745.5 6746.2 6755.7 6628.9 6719.6 6741.0 6760.4 6819.2 6592.3 6608.2 6621.7 6626.9 6629.6 26449.2 26494.2 26873.2 26881.9 27044.5 3185.5 3219.9 3242.3 3312.0 3314.6 15627.9 17027.9 17211.0 17363.8 17521.6 2079.9 2172.7 2190.1 2468.0 2583.2 110440.0 113394.0 113832.0 114226.0 115021.4 14429.8 16502.9 18408.6 18513.9 18635.4 32381.0 32524.2 32524.9 32564.8 33134.5 11111.9 11139.3 11155.0 11212.9 11305.7 9380.0 9393.8 9478.0 9488.4 9728.2 9399.2 9612.8 9743.6 9784.8 9804.1 3837.4 3903.6 3918.6 3956.9 3961.2 978.9 984.3 987.8 990.8 1015.6 20779.1 21100.2 21166.4 21231.4 21270.9 987.2 992.7 1013.0 1014.3 1023.0 10706.8 11547.1 11646.2 11797.4 11808.0 601578.0 608882.0 612867.0 624919.0 632057.0 103707.0 104094.0 105884.0 106470.5 108805.5 1275.3 1297.8 1339.6 1358.4 1382.8 14282.7 18819.0 19088.9 19259.4 19260.2 45958.5 49382.0 52882.5 53035.7 53802.2 64438.4 64535.0 64590.0 65512.3 65694.0 12656.1 12694.4 12764.4 12773.7 12820.2 14237.8 14271.9 14337.5 14427.7 14503.2 12126.5 12425.9 12483.9 12583.6 12660.3 10491.4 10543.8 10665.1 11051.3 11435.6 12209.5 12358.6 12408.9 12554.8 12821.7 11747.4 11778.5 11825.5 11842.6 12256.4 12061.9 12260.8 12412.3 12521.3 12562.4 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | [#3] mootools.html Cycles:180 Average:7261.69 Median:2467.76 stddev:4463.04 (180.9%) stddev-sans-first:4455.03 07:07:13 INFO - PROCESS | 8150 | Values: 1565.7 1584.3 1615.9 1628.2 1628.7 10993.7 11788.8 11815.4 11819.2 11827.2 11170.0 11477.7 11487.3 11516.5 11547.9 2511.2 2548.0 2551.8 2552.3 2553.1 2449.5 2513.4 2555.6 2559.9 2564.7 2498.3 2542.2 2583.9 2587.5 2590.9 2400.0 2453.2 2466.6 2468.9 2476.4 2398.6 2428.2 2432.6 2433.4 2436.0 11432.4 11660.2 11665.9 11682.1 11697.1 5994.9 6153.9 6156.0 6158.8 6166.8 3486.4 3567.9 3593.8 3677.1 3721.2 1395.6 2258.9 2263.2 2306.3 2307.9 14641.1 15069.3 16235.8 17109.1 17180.7 5007.1 5152.0 5221.2 5302.1 5306.9 14129.9 14131.0 14150.2 14166.6 14168.4 6281.0 6317.1 6318.6 6320.1 6320.7 5659.4 5725.0 5785.6 5837.9 5842.7 4392.5 4462.8 4494.5 4548.7 4551.4 1174.3 1205.6 1224.0 1225.5 1239.0 1083.1 1108.8 1111.3 1114.7 1116.4 7066.8 7098.3 7099.4 7125.1 7127.4 1080.4 1091.5 1106.2 1113.1 1117.8 4371.5 4501.8 4524.4 4555.5 4626.5 14316.8 14917.9 14927.3 14943.6 14950.4 13598.3 14154.0 14695.2 14818.3 14911.9 8762.4 10656.1 10754.9 10830.9 10861.0 6327.2 7217.2 7498.6 7560.2 7599.4 12140.2 12222.4 12242.9 12253.9 12400.4 12506.7 12662.1 12675.4 12733.6 12746.4 9544.2 9936.3 10151.6 10161.4 10170.7 11157.7 11424.7 11444.6 11450.0 11462.1 9483.3 9557.1 9687.9 9772.9 9916.4 9059.5 9135.1 9229.9 9305.5 9309.6 8309.8 8396.9 8403.4 8434.7 8493.8 6538.9 6664.6 6736.1 6747.1 6809.0 6421.0 6527.7 6590.1 6638.4 6738.2 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | [#4] prototype.html Cycles:180 Average:4827.61 Median:5355.14 stddev:1295.37 (24.2%) stddev-sans-first:1256.14 07:07:13 INFO - PROCESS | 8150 | Values: 424.6 453.6 454.4 457.5 458.7 5338.7 5341.8 5346.9 5349.7 5360.6 5136.5 5211.2 5295.9 5303.8 5330.9 4227.5 4241.7 4247.6 4250.4 4282.5 4260.3 4289.0 4295.7 4301.8 4331.4 4140.0 4256.2 4279.4 4285.7 4285.9 4241.9 4249.7 4251.7 4285.8 4309.7 3907.2 3913.5 3920.0 3957.5 3966.5 5203.2 5277.5 5317.4 5334.2 5334.3 4749.8 5000.2 5043.3 5052.7 5083.4 5494.8 5538.6 5591.3 5603.9 5636.2 4337.8 4345.4 4358.0 4377.6 4391.2 5528.9 5550.5 5601.9 5651.2 5824.3 4727.2 4742.3 4747.9 4766.0 4812.5 4865.9 4885.0 4892.0 4894.9 4899.9 5040.7 5115.7 5122.5 5179.5 5193.9 5545.8 5558.1 5571.8 5596.8 5608.4 1654.4 1654.9 1661.0 1667.5 1681.8 1256.3 1280.7 1290.4 1301.6 1315.8 5398.6 5443.1 5488.9 5510.6 5510.9 5568.8 5742.7 5778.3 5778.4 5786.0 5371.0 5379.7 5414.2 5459.4 5459.6 2987.8 2993.7 3013.1 3021.7 3030.8 5526.3 5780.4 5863.2 5878.7 5895.2 5812.4 5821.4 5822.9 5837.2 5844.8 5862.1 5879.6 5891.7 5908.4 5924.8 5416.4 5682.9 5735.3 5735.5 5741.2 5452.6 5612.7 5657.9 5673.2 5675.3 5578.3 5759.9 5766.8 5788.7 5829.9 5038.9 5071.5 5094.8 5106.0 5116.9 5456.3 5615.0 5703.0 5717.4 5731.5 5534.8 5538.6 5550.0 5577.6 5583.3 5526.6 5548.8 5606.2 5612.1 5650.6 5315.9 5537.6 5609.3 5615.6 5621.9 5544.9 5561.6 5581.1 5606.9 5621.4 5253.5 5441.9 5460.9 5479.4 5542.4 07:07:13 INFO - PROCESS | 8150 | 07:07:13 INFO - PROCESS | 8150 | [#5] yui.html Cycles:180 Average:18404.34 Median:23064.70 stddev:22646.03 (98.2%) stddev-sans-first:22671.19 07:07:13 INFO - PROCESS | 8150 | Values: 849.9 869.4 906.5 925.7 945.5 17498.3 17728.6 18288.5 18444.7 18497.5 17483.1 17669.8 17688.3 17751.2 17767.7 888.0 888.4 908.8 910.2 913.6 875.5 884.7 885.0 886.4 886.5 957.1 958.8 961.4 961.7 963.0 944.5 945.9 947.3 948.1 956.5 695.5 698.2 701.1 701.4 701.5 18173.9 18174.2 18334.4 18849.8 19144.7 15589.8 15818.4 15823.9 16125.8 16810.9 13299.7 13404.5 13755.8 13895.0 13897.4 3976.5 4035.5 4036.5 4055.1 4138.2 84953.0 85362.6 87158.6 88754.0 88924.6 12582.5 12600.7 12652.7 12802.7 12984.6 24834.0 24848.1 24862.1 24885.0 25183.0 7990.2 8118.5 8344.4 8468.4 8741.2 4722.8 4849.0 5001.2 5021.1 5185.0 3708.6 3814.8 3816.4 3819.7 3823.2 651.4 667.3 671.1 682.5 683.8 638.3 640.7 644.6 659.3 662.5 11104.2 11149.9 11463.4 11536.8 11784.7 821.0 824.5 834.3 850.0 853.9 5455.2 5630.6 5660.2 5697.4 5717.5 81791.0 81871.6 85118.0 85135.0 87861.0 49346.5 61861.4 66960.3 67466.7 67525.3 60210.7 61099.1 61228.0 61432.4 61517.0 11282.5 11864.5 11873.9 12131.5 12186.3 41098.0 41991.2 42153.8 42155.2 42812.1 47065.5 48160.5 48253.8 48354.8 49976.8 12058.5 12329.3 12339.4 12343.3 12706.9 15561.4 15879.8 16204.8 16321.8 17056.1 15270.8 15300.9 15302.9 15835.6 16443.5 14697.8 15001.7 15502.1 15527.3 15533.4 14875.8 15558.3 15788.0 15885.0 15934.8 10430.4 10532.6 10615.9 11045.0 11184.9 11291.5 12118.8 12193.7 12433.5 12469.2 07:07:13 INFO - PROCESS | 8150 | -------- Summary: end -------- 07:07:13 INFO - PROCESS | 8150 | 07:07:14 INFO - TEST-INFO | 8150: exit 0 07:07:14 INFO - TEST-OK | dromaeo_css | took 1115635ms 07:07:14 INFO - TEST-START | kraken 07:07:14 INFO - operating with platform_type : linux_ 07:07:14 INFO - Initialising browser for kraken test... 07:07:14 INFO - TEST-INFO | started process 8457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpigGP1E/profile http://localhost:56363/getInfo.html) 07:07:18 INFO - PROCESS | 8457 | __metrics Screen width/height:1600/1200 07:07:18 INFO - PROCESS | 8457 | colorDepth:24 07:07:18 INFO - PROCESS | 8457 | Browser inner width/height: 1024/697 07:07:18 INFO - PROCESS | 8457 | __metrics 07:07:18 INFO - PROCESS | 8457 | 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" 07:07:18 INFO - PROCESS | 8457 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:07:19 INFO - TEST-INFO | 8457: exit 0 07:07:19 INFO - Browser initialized. 07:07:19 INFO - Running cycle 1/1 for kraken test... 07:07:19 INFO - TEST-INFO | started process 8547 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpigGP1E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:07:31 INFO - PROCESS | 8547 | RSS: Main: 140931072 07:07:31 INFO - PROCESS | 8547 | 07:07:34 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56363/tests/kraken/driver.html?testName=audio-beat-detection) 07:07:35 INFO - PROCESS | 8547 | RSS: Main: 183824384 07:07:35 INFO - PROCESS | 8547 | 07:07:37 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56363/tests/kraken/driver.html?testName=audio-dft) 07:07:38 INFO - PROCESS | 8547 | RSS: Main: 173985792 07:07:38 INFO - PROCESS | 8547 | 07:07:40 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56363/tests/kraken/driver.html?testName=audio-fft) 07:07:41 INFO - PROCESS | 8547 | RSS: Main: 265383936 07:07:41 INFO - PROCESS | 8547 | 07:07:43 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56363/tests/kraken/driver.html?testName=audio-oscillator) 07:07:44 INFO - PROCESS | 8547 | RSS: Main: 171712512 07:07:44 INFO - PROCESS | 8547 | 07:07:46 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56363/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:07:46 INFO - PROCESS | 8547 | RSS: Main: 174227456 07:07:46 INFO - PROCESS | 8547 | 07:07:49 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56363/tests/kraken/driver.html?testName=imaging-darkroom) 07:07:50 INFO - PROCESS | 8547 | RSS: Main: 209178624 07:07:50 INFO - PROCESS | 8547 | 07:07:53 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56363/tests/kraken/driver.html?testName=imaging-desaturate) 07:07:54 INFO - PROCESS | 8547 | RSS: Main: 206368768 07:07:54 INFO - PROCESS | 8547 | 07:07:57 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56363/tests/kraken/driver.html?testName=json-parse-financial) 07:07:57 INFO - PROCESS | 8547 | RSS: Main: 208949248 07:07:57 INFO - PROCESS | 8547 | 07:07:59 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56363/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:07:59 INFO - PROCESS | 8547 | RSS: Main: 171794432 07:07:59 INFO - PROCESS | 8547 | 07:08:01 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:08:02 INFO - PROCESS | 8547 | RSS: Main: 179007488 07:08:02 INFO - PROCESS | 8547 | 07:08:04 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:08:04 INFO - PROCESS | 8547 | RSS: Main: 188579840 07:08:04 INFO - PROCESS | 8547 | 07:08:07 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:08:07 INFO - PROCESS | 8547 | RSS: Main: 197062656 07:08:07 INFO - PROCESS | 8547 | 07:08:10 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:08:11 INFO - PROCESS | 8547 | RSS: Main: 194281472 07:08:11 INFO - PROCESS | 8547 | 07:08:12 INFO - PROCESS | 8547 | Cycle 1(1): loaded http://localhost:56363/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56363/tests/kraken/driver.html?testName=ai-astar) 07:08:13 INFO - PROCESS | 8547 | RSS: Main: 243400704 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | __start_tp_report 07:08:13 INFO - PROCESS | 8547 | _x_x_mozilla_page_load 07:08:13 INFO - PROCESS | 8547 | _x_x_mozilla_page_load_details 07:08:13 INFO - PROCESS | 8547 | |i|pagename|runs| 07:08:13 INFO - PROCESS | 8547 | |0;ai-astar;103;110;111;111;110;112;111;110;110;111 07:08:13 INFO - PROCESS | 8547 | |1;audio-beat-detection;141;114;114;145;114;116;116;115;131;116 07:08:13 INFO - PROCESS | 8547 | |2;audio-dft;162;161;160;160;161;160;161;161;160;160 07:08:13 INFO - PROCESS | 8547 | |3;audio-fft;74;76;75;119;76;76;75;75;89;75 07:08:13 INFO - PROCESS | 8547 | |4;audio-oscillator;82;85;104;81;83;82;95;81;83;102 07:08:13 INFO - PROCESS | 8547 | |5;imaging-gaussian-blur;113;113;112;114;113;112;113;112;114;112 07:08:13 INFO - PROCESS | 8547 | |6;imaging-darkroom;147;146;146;145;147;146;146;146;147;145 07:08:13 INFO - PROCESS | 8547 | |7;imaging-desaturate;93;94;94;94;95;93;94;94;94;93 07:08:13 INFO - PROCESS | 8547 | |8;json-parse-financial;57;54;55;54;56;54;54;56;56;55 07:08:13 INFO - PROCESS | 8547 | |9;json-stringify-tinderbox;52;52;52;52;52;52;52;53;53;53 07:08:13 INFO - PROCESS | 8547 | |10;stanford-crypto-aes;75;74;79;85;75;83;75;78;79;79 07:08:13 INFO - PROCESS | 8547 | |11;stanford-crypto-ccm;125;135;126;119;119;118;119;128;165;120 07:08:13 INFO - PROCESS | 8547 | |12;stanford-crypto-pbkdf2;158;160;159;157;156;158;158;158;157;160 07:08:13 INFO - PROCESS | 8547 | |13;stanford-crypto-sha256-iterative;63;65;59;60;60;62;60;60;59;58 07:08:13 INFO - PROCESS | 8547 | __end_tp_report 07:08:13 INFO - PROCESS | 8547 | __start_cc_report 07:08:13 INFO - PROCESS | 8547 | _x_x_mozilla_cycle_collect,1635 07:08:13 INFO - PROCESS | 8547 | __end_cc_report 07:08:13 INFO - PROCESS | 8547 | __startTimestamp1495634893021__endTimestamp 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | ------- Summary: start ------- 07:08:13 INFO - PROCESS | 8547 | Number of tests: 14 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.51 (2.3%) stddev-sans-first:0.71 07:08:13 INFO - PROCESS | 8547 | Values: 103.0 110.0 111.0 111.0 110.0 112.0 111.0 110.0 110.0 111.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:116.00 stddev:12.11 (10.4%) stddev-sans-first:10.76 07:08:13 INFO - PROCESS | 8547 | Values: 141.0 114.0 114.0 145.0 114.0 116.0 116.0 115.0 131.0 116.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.53 07:08:13 INFO - PROCESS | 8547 | Values: 162.0 161.0 160.0 160.0 161.0 160.0 161.0 161.0 160.0 160.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.50 stddev:14.05 (18.6%) stddev-sans-first:14.67 07:08:13 INFO - PROCESS | 8547 | Values: 74.0 76.0 75.0 119.0 76.0 76.0 75.0 75.0 89.0 75.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:82.50 stddev:9.00 (10.9%) stddev-sans-first:9.30 07:08:13 INFO - PROCESS | 8547 | Values: 82.0 85.0 104.0 81.0 83.0 82.0 95.0 81.0 83.0 102.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 07:08:13 INFO - PROCESS | 8547 | Values: 113.0 113.0 112.0 114.0 113.0 112.0 113.0 112.0 114.0 112.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 07:08:13 INFO - PROCESS | 8547 | Values: 147.0 146.0 146.0 145.0 147.0 146.0 146.0 146.0 147.0 145.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 07:08:13 INFO - PROCESS | 8547 | Values: 93.0 94.0 94.0 94.0 95.0 93.0 94.0 94.0 94.0 93.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.50 stddev:1.10 (2.0%) stddev-sans-first:0.93 07:08:13 INFO - PROCESS | 8547 | Values: 57.0 54.0 55.0 54.0 56.0 54.0 54.0 56.0 56.0 55.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#9] json-stringify-tinderbox Cycles:10 Average:52.30 Median:52.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 07:08:13 INFO - PROCESS | 8547 | Values: 52.0 52.0 52.0 52.0 52.0 52.0 52.0 53.0 53.0 53.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:79.00 stddev:3.65 (4.6%) stddev-sans-first:3.68 07:08:13 INFO - PROCESS | 8547 | Values: 75.0 74.0 79.0 85.0 75.0 83.0 75.0 78.0 79.0 79.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:125.50 stddev:14.28 (11.4%) stddev-sans-first:15.12 07:08:13 INFO - PROCESS | 8547 | Values: 125.0 135.0 126.0 119.0 119.0 118.0 119.0 128.0 165.0 120.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.10 Median:158.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 07:08:13 INFO - PROCESS | 8547 | Values: 158.0 160.0 159.0 157.0 156.0 158.0 158.0 158.0 157.0 160.0 07:08:13 INFO - PROCESS | 8547 | 07:08:13 INFO - PROCESS | 8547 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:60.00 stddev:2.12 (3.5%) stddev-sans-first:2.06 07:08:13 INFO - PROCESS | 8547 | Values: 63.0 65.0 59.0 60.0 60.0 62.0 60.0 60.0 59.0 58.0 07:08:13 INFO - PROCESS | 8547 | -------- Summary: end -------- 07:08:13 INFO - PROCESS | 8547 | 07:08:14 INFO - TEST-INFO | 8547: exit 0 07:08:14 INFO - TEST-OK | kraken | took 59957ms 07:08:14 INFO - SUITE-END | took 1175s 07:08:14 INFO - Completed test suite (00:19:35) 07:08:14 INFO - javascript benchmark 07:08:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7989.654227538909, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.522806807047, 3428.417169081257, 3441.5698037745296, 3446.5691788526433, 3478.513008178855, 5726.1697053927155, 5767.509761670263, 5778.7977420790285, 5781.21953536273, 5797.217375654285, 5834.066549352105, 5873.0, 5877.235959325281, 5879.735411906464, 5895.292564892219, 5190.299309593194, 5191.870203244911, 5206.661566998133, 5259.86850328741, 5270.235815806705, 5058.747062646871, 5081.187010078388, 5121.180611102224, 5296.0, 5313.203019547061, 5212.582993360541, 5216.426193118754, 5221.817236396727, 5239.580833533328, 5240.816571420001, 5237.423883372826, 5241.711705856176, 5242.5281724644965, 5248.160294352923, 5272.868178295535, 4959.8512044638355, 5055.191169412913, 5146.536811686966, 5154.149565321717, 5203.453637368059, 5607.355154157242, 5640.2667653205235, 5697.230873832021, 5707.942920570824, 5709.286339207599, 6148.108524264001, 6176.938230617681, 6188.040853667689, 6195.659238741868, 6202.193714817045, 6018.21763170785, 6228.937710622881, 6244.9687751561405, 6249.625022498664, 6252.812415627538, 5731.226284451587, 5739.0, 5772.105323674838, 5836.737346819404, 5848.766049358063, 6302.180716506825, 6328.240611126695, 6332.050192471166, 6334.873302533923, 6337.841553961188, 5928.822135335947, 6061.060535616986, 6120.234970628671, 6129.969350153221, 6132.6933653317155, 6110.327863935053, 6116.67056464255, 6126.24286675468, 6134.355892631299, 6141.84645383869, 6161.507079433634, 6202.317745048041, 6204.410580994799, 6208.50331973441, 6212.658303793335, 5996.490298324691, 6054.545909056838, 6059.36376680451, 6100.450959413674, 6135.32511423743, 4728.0, 4749.762511874393, 4752.1921273383605, 4757.286407038971, 4771.785269662873, 4132.8140233689555, 4156.771377574262, 4158.979205103955, 4193.39195816611, 4264.040590867092, 5949.25634295713, 5963.970180149072, 5968.22413086296, 5976.701164941823, 5998.640081595118, 6256.217972753406, 6259.374062593704, 6263.843403914848, 6271.592346497463, 6272.8118156455375, 5745.827625171251, 5750.281214848144, 5762.538996880239, 5959.433853783884, 5965.970170149121, 5283.841484755463, 5294.285271488377, 5309.097453432925, 5310.415854256029, 5322.121849894801, 6378.84052898672, 6384.968075159594, 6395.42441180296, 6403.96798016007, 6418.358164183544, 6036.426539478743, 6131.356207598138, 6197.4732147767945, 6203.751849926133, 6211.192544969125, 6286.842828929313, 6304.5586808922935, 6320.241571011508, 6332.430081292706, 6368.044793280859, 6131.101423540661, 6133.1510485532735, 6135.508280839814, 6135.630783975068, 6143.911070235962, 6188.381161883955, 6250.062490626442, 6255.343188965275, 6255.624662520263, 6261.3112557617715, 5904.084866845646, 5940.61386009908, 5944.940550594439, 5969.134475501115, 6062.909056364247, 5974.850628734316, 5981.371955944737, 6039.849003774941, 6067.393260673897, 6075.878482430238, 5805.390434004537, 5888.587798854039, 6015.789447369321, 6029.487493563096, 6029.668368239789, 6012.038073908154, 6026.90959635597, 6076.392360763888, 6077.392260774064, 6080.574359794772, 5956.702164891652, 5960.612560183574, 5965.075413310944, 5974.40255974399, 6006.249218847644], "value": 5691.079163083796, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.117308614352, 851.5316575883263, 857.6912311567835, 927.2303987690218, 931.3573634192411, 31600.683993160063, 43908.341374879405, 44162.337564936555, 44241.778791106, 44632.776836115816, 44585.33122003172, 44828.32757508632, 45059.098818023704, 45061.77469112658, 45126.77436612821, 7726.304632583066, 7880.054393472791, 9249.953750231256, 9396.906030939672, 9400.012998635148, 10782.0, 11006.0, 11013.118950483942, 11021.118310535177, 11024.173187010967, 11108.0, 11150.275232109927, 11150.609728659498, 11169.329840209573, 11171.050460710849, 11058.723531911766, 11077.889221107765, 11101.44492775362, 11120.721981950435, 11130.888691113067, 10767.24629275951, 10791.676249712522, 10810.621628242974, 10846.403447810368, 10860.837087443695, 42637.57362426367, 42758.57241427608, 42890.57109428897, 42891.78554107241, 42904.141917161796, 4228.323468245097, 4278.443802305712, 4310.698251122422, 4416.492103408117, 4486.820527178909, 30147.547786783216, 31043.68956310453, 31196.844015780003, 31788.20529486758, 32220.838895805606, 4357.193919124972, 4384.517703052662, 4444.266695995157, 4522.977385113053, 4531.207038768202, 241113.0, 242847.0, 243113.0, 243130.0, 243602.0, 54897.725511372984, 55004.449955500735, 55201.72399138059, 55391.0, 55519.44480555224, 56758.71620641871, 57216.141757873665, 57276.0, 57288.14067788987, 57438.138427923615, 16352.100634464981, 16656.083915384526, 16697.582560436087, 16729.665406691798, 16741.49775506737, 13015.609531714064, 13228.537001204912, 14891.851081489047, 14896.478623248135, 14899.404023839143, 12252.81620775689, 12289.69275768113, 12330.260184388966, 13378.46486140563, 13622.863771362358, 5409.891802163935, 5471.37079235885, 5487.286652735199, 5551.555875529948, 5560.749766260528, 1018.1956254558816, 1026.6919924022764, 1026.9383836969805, 1031.339942436841, 1032.963846265377, 28251.43497130087, 28398.432031359258, 28837.711622883504, 28860.567091493223, 29012.274693132418, 1077.7736675298117, 1085.5983286184162, 1090.6128324444867, 1113.7605414835687, 1124.8425220469142, 14162.645933851736, 16654.58363540897, 16820.915895420443, 17315.220815063352, 17441.215145318492, 326223.0, 326505.0, 326932.0, 327643.0, 327994.0, 59281.40718592759, 59338.70330648319, 59432.70283648641, 59576.70211649001, 59786.70106649526, 1488.0625206120076, 1524.336913442656, 1528.7706843973272, 1538.415402147177, 1548.612846788303, 27810.582841256994, 28318.716812832434, 28589.142325730263, 31481.527777082905, 32021.67978320187, 50931.236031458815, 51398.0, 51562.74218628883, 51583.742081289354, 51619.22571161511, 341783.0, 344909.0, 345183.0, 345519.0, 445768.0, 40154.59845401509, 41331.7933410343, 41421.0, 41781.0, 42000.789996049825, 49887.0, 50158.49841501538, 50460.49539504705, 50654.0, 50741.492585073676, 37028.0, 37094.258114837015, 37228.813855930544, 37617.24765504729, 37762.43356349602, 37285.81357093306, 37301.81349093237, 37452.812735936146, 38062.42906356351, 38764.806175970065, 43813.0, 43974.340384894895, 44024.55975440205, 44300.33549496696, 45319.773401132785, 35695.82152089223, 35976.63104163314, 36175.27649447049, 36237.0, 36827.26345473129, 29030.419391611627, 29409.0, 29556.704432955394, 29563.55654665225, 29837.70162298349], "value": 20113.60741429028, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.45966622389545, 479.81527112061855, 483.06044742974905, 491.9704817710938, 498.70077953228065, 18586.90706546469, 19695.31066412676, 21280.14879404824, 21360.145594176254, 21425.035873385696, 23036.769632303673, 23059.538809223806, 23458.530829383446, 23465.296041118752, 23625.76374236257, 6696.3638454347, 6730.326967303279, 6740.494462915276, 6872.072270243528, 6883.552569083018, 6731.8317042074095, 6877.4841886858685, 6889.517733758639, 6905.274946130659, 6908.032875397424, 6603.273639899608, 6716.46268298535, 6745.460363170934, 6746.19045714515, 6755.662216889161, 6628.900566491507, 6719.563228390188, 6740.966295168518, 6760.425363844054, 6819.181698196224, 6592.274849766522, 6608.207015158164, 6621.702023408958, 6626.867462650768, 6629.635370054645, 26449.206523804125, 26494.205173844817, 26873.193804185907, 26881.924723011056, 27044.459110817872, 3185.515298067511, 3219.9356012879844, 3242.2542815152647, 3311.983440082784, 3314.6188188358165, 15627.92186039074, 17027.914860425743, 17211.0, 17363.826361736472, 17521.649567008717, 2079.9272025479036, 2172.6849606806923, 2190.113004233288, 2467.95064098717, 2583.1604728463326, 110440.0, 113394.0, 113832.0, 114226.0, 115021.424892875, 14429.818742844715, 16502.93713285839, 18408.631827363377, 18513.90743046276, 18635.44093677192, 32381.0, 32524.186895327806, 32524.861629842842, 32564.83717581397, 33134.502982455284, 11111.888881111247, 11139.33164010162, 11155.0, 11212.943935280271, 11305.660830174944, 9380.0, 9393.765155871159, 9477.952610236905, 9488.430694158373, 9728.221742260603, 9399.201067909304, 9612.75968100803, 9743.561539730728, 9784.804303913881, 9804.068613481708, 3837.3860182371245, 3903.590123037097, 3918.5885482024546, 3956.9010774730286, 3961.2275606256503, 978.8874279457812, 984.3060642247342, 987.8468837330225, 990.8414653655525, 1015.5886865819355, 20779.064942077643, 21100.155993760385, 21166.365009049445, 21231.36305910825, 21270.893645531673, 987.1658448610913, 992.731962370156, 1012.9898701012895, 1014.325473560089, 1022.99488502557, 10706.78586428267, 11547.076233901267, 11646.184767066225, 11797.410129493663, 11808.0, 601578.0, 608882.0, 612867.0, 624919.0, 632057.0, 103707.0, 104094.0, 105884.0, 106470.46764766127, 108805.45597272122, 1275.3113318807739, 1297.7858653322096, 1339.5780329196273, 1358.4362489566918, 1382.7925811128612, 14282.714345713235, 18819.0, 19088.904555477133, 19259.422217333504, 19260.229590816773, 45958.54041459542, 49382.01235975188, 52882.471175287756, 53035.73482132565, 53802.192967106304, 64438.35561644324, 64535.0, 64590.0, 65512.34487655253, 65694.0, 12656.11407201487, 12694.365281736062, 12764.40444944382, 12773.680657983625, 12820.166689165175, 14237.786433203302, 14271.857281427052, 14337.498187563386, 14427.711445771236, 14503.202323872289, 12126.454309556268, 12425.937870310952, 12483.93758031204, 12583.559575414816, 12660.303683297507, 10491.422971736627, 10543.841842372523, 10665.146788256765, 11051.336919784839, 11435.59975400857, 12209.450574724337, 12358.567450139562, 12408.937955310166, 12554.811677824657, 12821.743565128458, 11747.412629368668, 11778.46996885125, 11825.46785394642, 11842.644720658394, 12256.387180640753, 12061.879381206427, 12260.754784904073, 12412.317322546985, 12521.31132787669, 12562.366892303942], "value": 11511.119890796585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1565.7494800831869, 1584.2949887300156, 1615.8788090893183, 1628.2021809313437, 1628.7068327701008, 10993.725156871082, 11788.823167652481, 11815.40922953851, 11819.231749936249, 11827.172097953136, 11170.0, 11477.655670329883, 11487.310761354325, 11516.481758320875, 11547.942260288708, 2511.2089691747087, 2548.0, 2551.783098436635, 2552.310876063462, 2553.131935142051, 2449.510097980402, 2513.447041650834, 2555.56555385584, 2559.8592077435733, 2564.653771740819, 2498.3254521279246, 2542.224621490445, 2583.8966441342322, 2587.5213085579135, 2590.896364145432, 2400.015993442689, 2453.2394957563124, 2466.580681284168, 2468.938276543083, 2476.3561474016706, 2398.616221404567, 2428.1986944308337, 2432.622943443769, 2433.415980164765, 2436.025589764091, 11432.37121958292, 11660.242084264495, 11665.883341166564, 11682.065434765198, 11697.12271579635, 5994.910076348859, 6153.907691384633, 6155.969220153916, 6158.8152355428965, 6166.784162554334, 3486.4247399179103, 3567.94648080279, 3593.82030898454, 3677.1174918019483, 3721.2371463849845, 1395.644110751762, 2258.9209377671705, 2263.196565219356, 2306.342692332694, 2307.919222827193, 14641.121532708072, 15069.321880515403, 16235.83764162367, 17109.144542772938, 17180.742288865677, 5007.148784706608, 5152.046871328741, 5221.242919776611, 5302.125149350314, 5306.867328316745, 14129.929350353388, 14131.010829241854, 14150.150990940576, 14166.64583385403, 14168.433262669583, 6280.9685951569945, 6317.084022816758, 6318.620882746958, 6320.146780184708, 6320.71556779946, 5659.43405659439, 5724.971375143097, 5785.566082543826, 5837.854053648693, 5842.678652674143, 4392.538783427758, 4462.799174037176, 4494.52807455219, 4548.681592288508, 4551.431071116111, 1174.2895548193392, 1205.5780476833038, 1223.9816402753788, 1225.4852961756085, 1239.0, 1083.0793825248477, 1108.772701596171, 1111.2554588425774, 1114.7380365614074, 1116.380408873067, 7066.787996360117, 7098.3256590623805, 7099.3965512930945, 7125.073740413713, 7127.394171495377, 1080.4273734920505, 1091.4651820608003, 1106.2145876427826, 1113.0817075912405, 1117.7596816684452, 4371.475422949266, 4501.819927202875, 4524.366588677588, 4555.544445555418, 4626.537346265414, 14316.785248221286, 14917.850821491646, 14927.25363731809, 14943.551693449213, 14950.401983920521, 13598.32008399576, 14154.0, 14695.191764452844, 14818.333175006936, 14911.850881491046, 8762.386632935633, 10656.147508199325, 10754.946225268981, 10830.945845270724, 10861.0, 6327.240731112296, 7217.206107328295, 7498.62506874665, 7560.206178351194, 7599.430042746927, 12140.15018948665, 12222.388880555758, 12242.938785306016, 12253.877461225273, 12400.441980110736, 12506.74986500283, 12662.050346223887, 12675.366231688564, 12733.55432559856, 12746.362681866056, 9544.236461082957, 9936.304458687822, 10151.64469243573, 10161.441120738431, 10170.74573135648, 11157.72105697364, 11424.657260282205, 11444.599439019594, 11450.026747726537, 11462.083033357478, 9483.336166468282, 9557.092076252746, 9687.9031209687, 9772.9022709772, 9916.355436896578, 9059.547022648972, 9135.086491350812, 9229.86155207659, 9305.534723263674, 9309.581068851783, 8309.792255193668, 8396.916030839613, 8403.369747268856, 8434.662613495391, 8493.830123397622, 6538.934610653832, 6664.633445160563, 6736.124303840469, 6747.122874026345, 6808.965955170391, 6420.967895160495, 6527.706253218522, 6590.110335104891, 6638.435732962752, 6738.225104113089], "value": 5619.81205727394, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.64329962831215, 453.6484224725837, 454.4138061900151, 457.5378867343982, 458.72018068977917, 5338.733063346832, 5341.813036543722, 5346.903884703626, 5349.679019258838, 5360.606242376981, 5136.476304032694, 5211.218317252414, 5295.947040529593, 5303.787848486056, 5330.893382132355, 4227.471566054251, 4241.660667146636, 4247.5964783345535, 4250.404943307939, 4282.50751163617, 4260.297050986584, 4289.0, 4295.656347492193, 4301.8064187111495, 4331.3719510670935, 4140.012793858949, 4256.170046840852, 4279.422277992462, 4285.742855428669, 4285.935710964338, 4241.936370954453, 4249.68127390447, 4251.725326950098, 4285.849995250167, 4309.676774241913, 3907.1990242000606, 3913.4912461380122, 3919.98040009801, 3957.465742124834, 3966.464527288808, 5203.19350500673, 5277.525022747971, 5317.388500322474, 5334.22653715213, 5334.253204551366, 4749.762511874427, 5000.1999680051385, 5043.293938848564, 5052.747362631891, 5083.364579427572, 5494.835154945318, 5538.6399884007415, 5591.2731344925305, 5603.887922241533, 5636.210930469738, 4337.826486940518, 4345.41336919515, 4357.968878787109, 4377.562243775629, 4391.231534481453, 5528.894221155785, 5550.5282051025715, 5601.887962240773, 5651.1523271509195, 5824.2625965796005, 4727.172744769651, 4742.288656701522, 4747.8575642730775, 4765.975315307225, 4812.494688057704, 4865.854024379274, 4885.047415753894, 4891.997140586172, 4894.853154405374, 4899.946511500045, 5040.697558146523, 5115.693058416507, 5122.487751224848, 5179.539718633619, 5193.896122077613, 5545.750441230154, 5558.1093234735845, 5571.804986825442, 5596.776128954796, 5608.439156084441, 1654.3548016273662, 1654.8664165046982, 1661.0199982010672, 1667.5247554446923, 1681.8318168183084, 1256.2588073036952, 1280.6872955220788, 1290.4064130500076, 1301.568274897605, 1315.7960516120017, 5398.64908780928, 5443.074677304868, 5488.91766623501, 5510.641808282529, 5510.90585577478, 5568.777248910079, 5742.655440673573, 5778.306603207642, 5778.393268706809, 5786.045302525036, 5371.045924741376, 5379.73101344939, 5414.214938833847, 5459.372172200247, 5459.563234941195, 2987.775916806248, 2993.7098119938264, 3013.0565785068507, 3021.743151832119, 3030.7876849260033, 5526.253955715927, 5780.364159942403, 5863.237779088691, 5878.735456904449, 5895.204147440127, 5812.44781745742, 5821.359650438449, 5822.912656310159, 5837.241158649348, 5844.795432159939, 5862.14998825168, 5879.648140743651, 5891.734871930687, 5908.3751968209945, 5924.763009479659, 5416.404195538409, 5682.857014401973, 5735.311762588516, 5735.4551317624755, 5741.153427203867, 5452.645578037414, 5612.663240205601, 5657.9151312731165, 5673.205751194753, 5675.31896172462, 5578.2748242728185, 5759.94240057594, 5766.826995190151, 5788.697543052779, 5829.94170058294, 5038.924416133834, 5071.467495913023, 5094.7707353169935, 5106.0, 5116.874287656709, 5456.345238571397, 5614.9719251403485, 5702.97346477638, 5717.3996730342715, 5731.455511726379, 5534.837684086387, 5538.556915446674, 5550.0, 5577.637453565505, 5583.302087239095, 5526.64597173695, 5548.750306236309, 5606.24315717373, 5612.074007788669, 5650.604457687922, 5315.893682126413, 5537.6465002373025, 5609.289166804164, 5615.624172077867, 5621.887562248814, 5544.916826247691, 5561.610687252015, 5581.079121944834, 5606.85982850432, 5621.381648018633, 5253.45391860485, 5441.918371224514, 5460.890782184255, 5479.369872464717, 5542.3626282977975], "value": 4459.3913202960375, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [849.8895143631327, 869.4435561240807, 906.4606559097339, 925.6575067225126, 945.4658118163237, 17498.30006799728, 17728.556786080353, 18288.45134645961, 18444.723329150074, 18497.537561560952, 17483.12584370783, 17669.823301766977, 17688.292468301253, 17751.20119594618, 17767.73348399772, 888.04535124741, 888.3781353052857, 908.8227795579865, 910.2172131966504, 913.6071489259616, 875.479089941487, 884.7213127864702, 884.995575022124, 886.410536992899, 886.5478605911, 957.1003256938455, 958.7555173430766, 961.4423634292128, 961.725908116187, 962.9711108666717, 944.5135755086136, 945.938513996588, 947.2564037230741, 948.127722495306, 956.4787190980932, 695.5305169010898, 698.2145086777375, 701.123595505618, 701.3687681087063, 701.5404909784099, 18173.909130454394, 18174.18216180262, 18334.358297459657, 18849.811501884942, 19144.712829307573, 15589.766153507706, 15818.446354377656, 15823.920880395524, 16125.758113628304, 16810.934167284027, 13299.66750831237, 13404.46382144713, 13755.79366309506, 13895.0, 13897.444102235999, 3976.4631774710333, 4035.4955630546183, 4036.495438070241, 4055.1078762672164, 4138.234426631053, 84953.0, 85362.57318713366, 87158.5642071792, 88754.0, 88924.5553772227, 12582.496700132075, 12600.684982875502, 12652.746945061048, 12802.743945121232, 12984.610461685976, 24834.00663973457, 24848.130315438873, 24862.12982545602, 24885.0, 25183.0, 7990.200979901963, 8118.472299300526, 8344.415890887702, 8468.364872634576, 8741.169588889044, 4722.797733456507, 4848.9757551212015, 5001.174806157016, 5021.096202683479, 5185.014847179099, 3708.554973403209, 3814.828332725034, 3816.3893776995546, 3819.7135214858995, 3823.1780167264174, 651.3519048546726, 667.346000919102, 671.1241829412565, 682.5426963934077, 683.8495530983175, 638.3425072175573, 640.6540468147149, 644.5584774429531, 659.2583343738295, 662.4898827902584, 11104.222704410613, 11149.944250278859, 11463.369514676606, 11536.76926461466, 11784.70538236551, 820.9753707388788, 824.464770829701, 834.3241974001079, 849.961751721174, 853.8676505141713, 5455.208994695747, 5630.577706672016, 5660.15097735343, 5697.401772813878, 5717.514011309002, 81791.0, 81871.5906420464, 85118.0, 85135.0, 87861.0, 49346.50653493491, 61861.38138618646, 66960.33039669541, 67466.66266668636, 67525.32474675191, 60210.698946504985, 61099.083513747326, 61228.0, 61432.385676142665, 61517.0, 11282.492287846902, 11864.525418983145, 11873.862704713234, 12131.514739410324, 12186.329751863732, 41098.0, 41991.16017679568, 42153.78923105365, 42155.15689686128, 42812.143757125305, 47065.52934470611, 48160.518394815605, 48253.75873120612, 48354.75822620864, 49976.75011625064, 12058.457369418573, 12329.321887296284, 12339.383030848601, 12343.321117338633, 12706.9199118076, 15561.37754489853, 15879.841201587837, 16204.756928645844, 16321.836781632031, 17056.06191659421, 15270.847291526943, 15300.923495382453, 15302.923485382502, 15835.604109897346, 16443.506694799176, 14697.779533307223, 15001.6999660004, 15502.147381894105, 15527.301271442586, 15533.41002923369, 14875.776863346842, 15558.299876505358, 15788.0, 15885.0, 15934.760978585562, 10430.42632655181, 10532.631357902437, 10615.946920265658, 11045.0, 11184.94407527957, 11291.491882865126, 12118.818217726564, 12193.695157620776, 12433.502659893504, 12469.189502682297], "value": 7876.423737138699, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1445.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 111.0, 111.0, 110.0, 112.0, 111.0, 110.0, 110.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 114.0, 114.0, 145.0, 114.0, 116.0, 116.0, 115.0, 131.0, 116.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 161.0, 160.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 119.0, 76.0, 76.0, 75.0, 75.0, 89.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 104.0, 81.0, 83.0, 82.0, 95.0, 81.0, 83.0, 102.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 114.0, 113.0, 112.0, 113.0, 112.0, 114.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 55.0, 54.0, 56.0, 54.0, 54.0, 56.0, 56.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 53.0, 53.0, 53.0], "value": 52.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 79.0, 85.0, 75.0, 83.0, 75.0, 78.0, 79.0, 79.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 135.0, 126.0, 119.0, 119.0, 118.0, 119.0, 128.0, 165.0, 120.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 159.0, 157.0, 156.0, 158.0, 158.0, 158.0, 157.0, 160.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 59.0, 60.0, 60.0, 62.0, 60.0, 60.0, 59.0, 58.0], "value": 60.6, "unit": "score"}]}]} 07:08:14 INFO - Return code: 0 07:08:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:08:14 INFO - # TBPL SUCCESS # 07:08:14 INFO - Running post-action listener: _resource_record_post_action 07:08:14 INFO - [mozharness: 2017-05-24 14:08:14.360905Z] Finished run-tests step (success) 07:08:14 INFO - Running post-run listener: _resource_record_post_run 07:08:14 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327639040; Read time: 12; Write time: 157688 07:08:14 INFO - TinderboxPrint: CPU usage
13.1% 07:08:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:08:14 INFO - TinderboxPrint: I/O write bytes / time
327,639,040 / 157,688 07:08:14 INFO - TinderboxPrint: CPU idle
8,254.5 (86.9%) 07:08:14 INFO - TinderboxPrint: CPU user
1,199.1 (12.6%) 07:08:14 INFO - TinderboxPrint: Swap in / out
0 / 0 07:08:14 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 30687232; Read time: 0; Write time: 8220 07:08:14 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 296951808; Read time: 0; Write time: 149468 07:08:14 INFO - Running post-run listener: _upload_blobber_files 07:08:14 INFO - Blob upload gear active. 07:08:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:08:14 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 07:08:14 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'] 07:08:14 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 07:08:15 INFO - (blobuploader) - INFO - Open directory for files ... 07:08:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:08:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:08:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:18 INFO - (blobuploader) - INFO - Done attempting. 07:08:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:08:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:08:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:19 INFO - (blobuploader) - INFO - Done attempting. 07:08:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:08:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:21 INFO - (blobuploader) - INFO - Done attempting. 07:08:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:08:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:21 INFO - (blobuploader) - INFO - Done attempting. 07:08:21 INFO - (blobuploader) - INFO - Iteration through files over. 07:08:21 INFO - Return code: 0 07:08:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:08:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:08:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9c3c3e8bb656519a124cd0594e61e3d6ffa9196ce51e34cf822411565a65cc67bf560e3aa026581e5394812593009d5058b90a824cd92a3bb17cc3d1a6af57e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95349a674f6463d29bd8447e2b5e70a0c16856ec1d1eb4c6cc137ab2f8e0d1f89c28340d588a0150d9256b3a736c484f57e865a9160d989a61128d267d07b198", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4703555d2db3b54934780bf0ad72bf704a47260d8525d409e6b9bffb3983a02b6432674e4ead18c730af5f6ee2c936ccbbbc0a6b8a2d355921436fe35fdc084a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff907c9459e5aad05d95aee688f3e07e0e7e9ce7f933e5c5d460f0d70f87e7b7e8a9cc4665ccb70c946f1e979247b9909a61c40f6f8ac8a2269473cd78b399fc"} 07:08:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:08:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:08:21 INFO - Contents: 07:08:21 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9c3c3e8bb656519a124cd0594e61e3d6ffa9196ce51e34cf822411565a65cc67bf560e3aa026581e5394812593009d5058b90a824cd92a3bb17cc3d1a6af57e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95349a674f6463d29bd8447e2b5e70a0c16856ec1d1eb4c6cc137ab2f8e0d1f89c28340d588a0150d9256b3a736c484f57e865a9160d989a61128d267d07b198", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4703555d2db3b54934780bf0ad72bf704a47260d8525d409e6b9bffb3983a02b6432674e4ead18c730af5f6ee2c936ccbbbc0a6b8a2d355921436fe35fdc084a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff907c9459e5aad05d95aee688f3e07e0e7e9ce7f933e5c5d460f0d70f87e7b7e8a9cc4665ccb70c946f1e979247b9909a61c40f6f8ac8a2269473cd78b399fc"} 07:08:21 INFO - Running post-run listener: copy_logs_to_upload_dir 07:08:21 INFO - Copying logs to upload dir... 07:08:21 INFO - mkdir: /builds/slave/test/build/upload/logs 07:08:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.144853 ========= master_lag: -0.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-05-24 07:08:21.539012) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 07:08:21.546090) ========= 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=41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9c3c3e8bb656519a124cd0594e61e3d6ffa9196ce51e34cf822411565a65cc67bf560e3aa026581e5394812593009d5058b90a824cd92a3bb17cc3d1a6af57e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95349a674f6463d29bd8447e2b5e70a0c16856ec1d1eb4c6cc137ab2f8e0d1f89c28340d588a0150d9256b3a736c484f57e865a9160d989a61128d267d07b198", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4703555d2db3b54934780bf0ad72bf704a47260d8525d409e6b9bffb3983a02b6432674e4ead18c730af5f6ee2c936ccbbbc0a6b8a2d355921436fe35fdc084a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff907c9459e5aad05d95aee688f3e07e0e7e9ce7f933e5c5d460f0d70f87e7b7e8a9cc4665ccb70c946f1e979247b9909a61c40f6f8ac8a2269473cd78b399fc"} build_url:https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/D0yyKakvTQy6GsmaX_-iqg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9c3c3e8bb656519a124cd0594e61e3d6ffa9196ce51e34cf822411565a65cc67bf560e3aa026581e5394812593009d5058b90a824cd92a3bb17cc3d1a6af57e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95349a674f6463d29bd8447e2b5e70a0c16856ec1d1eb4c6cc137ab2f8e0d1f89c28340d588a0150d9256b3a736c484f57e865a9160d989a61128d267d07b198", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4703555d2db3b54934780bf0ad72bf704a47260d8525d409e6b9bffb3983a02b6432674e4ead18c730af5f6ee2c936ccbbbc0a6b8a2d355921436fe35fdc084a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ff907c9459e5aad05d95aee688f3e07e0e7e9ce7f933e5c5d460f0d70f87e7b7e8a9cc4665ccb70c946f1e979247b9909a61c40f6f8ac8a2269473cd78b399fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 07:08:21.572881) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:08:21.573215) ========= 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=41a7e32711b8e08598a63237000001d6-1495633656.81770-1334521702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:08:21.652435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 07:08:21.655804) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 07:08:21.656127) ========= ========= Total master_lag: -0.06 =========