builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1494880680.66 results: success (0) revision: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:00.664957) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:00.665365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:00.665639) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:00.841705) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:00.842090) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1494878244.585531-499719470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-15 13:38:01-- 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% 226M=0s 2017-05-15 13:38:01 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145662 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.058240) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.058563) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1494878244.585531-499719470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020268 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.125308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.125754) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9bef2dfbd584c0f3ae52953bfc3aed64e308960d --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 9bef2dfbd584c0f3ae52953bfc3aed64e308960d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1494878244.585531-499719470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-15 13:38:01,831 truncating revision to first 12 chars 2017-05-15 13:38:01,831 Setting DEBUG logging. 2017-05-15 13:38:01,832 attempt 1/10 2017-05-15 13:38:01,832 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9bef2dfbd584?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 13:38:02,141 unpacking tar archive at: mozilla-beta-9bef2dfbd584/testing/mozharness/ program finished with exit code 0 elapsedTime=0.628670 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.789370) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.789685) ========= script_repo_revision: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.790085) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.792436) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 13:38:01.805269) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-05-15 13:38:01.805695) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1494878244.585531-499719470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:38:02 INFO - MultiFileLogger online at 20170515 13:38:02 in /builds/slave/test 13:38:02 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 13:38:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:38:02 INFO - {'append_to_log': False, 13:38:02 INFO - 'base_work_dir': '/builds/slave/test', 13:38:02 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 13:38:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:38:02 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 13:38:02 INFO - 'buildbot_json_path': 'buildprops.json', 13:38:02 INFO - 'config_files': ('talos/linux_config.py',), 13:38:02 INFO - 'default_actions': ('clobber', 13:38:02 INFO - 'read-buildbot-config', 13:38:02 INFO - 'download-and-extract', 13:38:02 INFO - 'populate-webroot', 13:38:02 INFO - 'create-virtualenv', 13:38:02 INFO - 'install', 13:38:02 INFO - 'run-tests'), 13:38:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:38:02 INFO - 'download_minidump_stackwalk': True, 13:38:02 INFO - 'download_symbols': 'ondemand', 13:38:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:38:02 INFO - 'tooltool.py': '/tools/tooltool.py', 13:38:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:38:02 INFO - '/tools/misc-python/virtualenv.py')}, 13:38:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:38:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:38:02 INFO - 'gecko_profile': False, 13:38:02 INFO - 'gecko_profile_interval': 0, 13:38:02 INFO - 'installer_path': 'installer.exe', 13:38:02 INFO - 'log_level': 'info', 13:38:02 INFO - 'log_name': 'talos', 13:38:02 INFO - 'log_to_console': True, 13:38:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:38:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:38:02 INFO - 'opt_config_files': (), 13:38:02 INFO - 'pip_index': False, 13:38:02 INFO - 'suite': 'dromaeojs', 13:38:02 INFO - 'system_bits': '32', 13:38:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:38:02 INFO - 'title': 'talos-linux64-ix-045', 13:38:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:38:02 INFO - 'use_talos_json': True, 13:38:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:38:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:38:02 INFO - 'work_dir': 'build'} 13:38:02 INFO - [mozharness: 2017-05-15 20:38:02.594718Z] Running clobber step. 13:38:02 INFO - Running pre-action listener: _resource_record_pre_action 13:38:02 INFO - Running main action method: clobber 13:38:02 INFO - rmtree: /builds/slave/test/build 13:38:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:38:04 INFO - Running post-action listener: _resource_record_post_action 13:38:04 INFO - [mozharness: 2017-05-15 20:38:04.135455Z] Finished clobber step (success) 13:38:04 INFO - [mozharness: 2017-05-15 20:38:04.135552Z] Running read-buildbot-config step. 13:38:04 INFO - Running pre-action listener: _resource_record_pre_action 13:38:04 INFO - Running main action method: read_buildbot_config 13:38:04 INFO - Using buildbot properties: 13:38:04 INFO - { 13:38:04 INFO - "project": "", 13:38:04 INFO - "product": "firefox", 13:38:04 INFO - "who": "ryanvm@gmail.com", 13:38:04 INFO - "installer_path": "public/build/target.tar.bz2", 13:38:04 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:38:04 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 13:38:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 13:38:04 INFO - "stage_platform": "linux64", 13:38:04 INFO - "basedir": "/builds/slave/test", 13:38:04 INFO - "buildnumber": 44, 13:38:04 INFO - "platform": "ubuntu64_hw", 13:38:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:38:04 INFO - "slavebuilddir": "test", 13:38:04 INFO - "taskId": "YCoBQCf9RJidUwK14f_0hQ", 13:38:04 INFO - "branch": "mozilla-beta", 13:38:04 INFO - "script_repo_revision": "production", 13:38:04 INFO - "revision": "9bef2dfbd584c0f3ae52953bfc3aed64e308960d", 13:38:04 INFO - "slavename": "talos-linux64-ix-045", 13:38:04 INFO - "repo_path": "releases/mozilla-beta" 13:38:04 INFO - } 13:38:04 INFO - Finding installer, test and symbols from parent task. 13:38:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YCoBQCf9RJidUwK14f_0hQ'}, attempt #1 13:38:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YCoBQCf9RJidUwK14f_0hQ'}, attempt #1 13:38:05 INFO - Task dependencies: A9i1TindSjaG0vI0rRD8Tg 13:38:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg'}, attempt #1 13:38:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2 13:38:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/test_packages.json 13:38:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.crashreporter-symbols.zip 13:38:05 INFO - Running post-action listener: _resource_record_post_action 13:38:05 INFO - [mozharness: 2017-05-15 20:38:05.494983Z] Finished read-buildbot-config step (success) 13:38:05 INFO - [mozharness: 2017-05-15 20:38:05.495199Z] Running download-and-extract step. 13:38:05 INFO - Running pre-action listener: _resource_record_pre_action 13:38:05 INFO - Running main action method: download_and_extract 13:38:05 INFO - mkdir: /builds/slave/test/build/tests 13:38: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')]} 13:38:05 INFO - trying https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.test_packages.json 13:38:05 INFO - Downloading https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:38:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:38:06 INFO - Downloaded 986 bytes. 13:38:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:38:06 INFO - Using the following test package requirements: 13:38:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:38:06 INFO - u'common': [u'target.common.tests.zip'], 13:38:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:38:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:38:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:38:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:38:06 INFO - u'mozbase': [u'target.common.tests.zip'], 13:38:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:38:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:38:06 INFO - u'web-platform': [u'target.common.tests.zip', 13:38:06 INFO - u'target.web-platform.tests.zip'], 13:38:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:38:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:38:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.common.tests.zip 13:38:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:38:06 INFO - Fetch https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.common.tests.zip into memory 13:38:08 INFO - Expected file size: 59544110 13:38:08 INFO - Obtained file size: 59544110 13:38:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:38:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.common.tests.zip 13:38:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:38:17 INFO - Fetch https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.common.tests.zip into memory 13:38:19 INFO - Expected file size: 59544110 13:38:19 INFO - Obtained file size: 59544110 13:38:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.talos.tests.zip 13:38:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:38:30 INFO - Fetch https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.talos.tests.zip into memory 13:38:31 INFO - Expected file size: 14404449 13:38:31 INFO - Obtained file size: 14404449 13:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:31 INFO - trying https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2 13:38:31 INFO - Downloading https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:38:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:38:33 INFO - Downloaded 59922860 bytes. 13:38:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2 13:38:33 INFO - mkdir: /builds/slave/test/properties 13:38:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:38:33 INFO - Writing to file /builds/slave/test/properties/build_url 13:38:33 INFO - Contents: 13:38:33 INFO - build_url:https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2 13:38:33 INFO - Running post-action listener: _resource_record_post_action 13:38:33 INFO - Running post-action listener: set_extra_try_arguments 13:38:33 INFO - [mozharness: 2017-05-15 20:38:33.788685Z] Finished download-and-extract step (success) 13:38:33 INFO - [mozharness: 2017-05-15 20:38:33.788889Z] Running populate-webroot step. 13:38:33 INFO - Running pre-action listener: _resource_record_pre_action 13:38:33 INFO - Running main action method: populate_webroot 13:38:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:38:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:38:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:38:33 INFO - '--tppagecycles', 13:38:33 INFO - '7'], 13:38:33 INFO - 'tests': ['tsvgm']}}, 13:38:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:38:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:38:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:38:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:38:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:38:33 INFO - 'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:38:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:38:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:38:33 INFO - 'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['damp', 'tps']}, 13:38:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:38:33 INFO - 'tests': ['damp', 'tps']}, 13:38:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['dromaeo_dom']}, 13:38:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:38:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['basic_compositor_video']}, 13:38:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:38:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['a11yr', 13:38:33 INFO - 'ts_paint', 13:38:33 INFO - 'tpaint', 13:38:33 INFO - 'sessionrestore', 13:38:33 INFO - 'sessionrestore_no_auto_restore', 13:38:33 INFO - 'tabpaint']}, 13:38:33 INFO - 'other-e10s': {'tests': ['a11yr', 13:38:33 INFO - 'ts_paint', 13:38:33 INFO - 'tpaint', 13:38:33 INFO - 'sessionrestore', 13:38:33 INFO - 'sessionrestore_no_auto_restore', 13:38:33 INFO - 'tabpaint']}, 13:38:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:38:33 INFO - 'ts_paint', 13:38:33 INFO - 'tpaint', 13:38:33 INFO - 'sessionrestore', 13:38:33 INFO - 'sessionrestore_no_auto_restore', 13:38:33 INFO - 'tabpaint']}, 13:38:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:38:33 INFO - 'ts_paint', 13:38:33 INFO - 'tpaint', 13:38:33 INFO - 'sessionrestore', 13:38:33 INFO - 'sessionrestore_no_auto_restore', 13:38:33 INFO - 'tabpaint']}, 13:38:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['a11yr', 13:38:33 INFO - 'ts_paint', 13:38:33 INFO - 'tpaint', 13:38:33 INFO - 'sessionrestore', 13:38:33 INFO - 'sessionrestore_no_auto_restore', 13:38:33 INFO - 'tabpaint']}, 13:38:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['a11yr', 13:38:33 INFO - 'ts_paint', 13:38:33 INFO - 'tpaint', 13:38:33 INFO - 'sessionrestore', 13:38:33 INFO - 'sessionrestore_no_auto_restore', 13:38:33 INFO - 'tabpaint']}, 13:38:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['tsvgx', 13:38:33 INFO - 'tsvgr_opacity', 13:38:33 INFO - 'tart', 13:38:33 INFO - 'tscrollx', 13:38:33 INFO - 'cart', 13:38:33 INFO - 'tsvg_static']}, 13:38:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:38:33 INFO - 'tsvgr_opacity', 13:38:33 INFO - 'tart', 13:38:33 INFO - 'tscrollx', 13:38:33 INFO - 'cart', 13:38:33 INFO - 'tsvg_static']}, 13:38:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:38:33 INFO - 'talos_options': ['--disable-e10s'], 13:38:33 INFO - 'tests': ['tp5o']}, 13:38:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:38:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:38:33 INFO - 'talos_options': ['--disable-e10s', 13:38:33 INFO - '--xperf_path', 13:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:38:33 INFO - 'tests': ['tp5n']}, 13:38:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:38:33 INFO - 'talos_options': ['--xperf_path', 13:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:38:33 INFO - 'tests': ['tp5n']}}, 13:38:33 INFO - 'talos.zip': {'path': '', 13:38:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:38:33 INFO - Running post-action listener: _resource_record_post_action 13:38:33 INFO - [mozharness: 2017-05-15 20:38:33.802221Z] Finished populate-webroot step (success) 13:38:33 INFO - [mozharness: 2017-05-15 20:38:33.802310Z] Running create-virtualenv step. 13:38:33 INFO - Running pre-action listener: _resource_record_pre_action 13:38:33 INFO - Running main action method: create_virtualenv 13:38:33 INFO - Creating virtualenv /builds/slave/test/build/venv 13:38:33 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 13:38:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:38:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:38:33 INFO - Using real prefix '/usr' 13:38:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:38:34 INFO - Installing distribute.............................................................................................................................................................................................done. 13:38:36 INFO - Installing pip.................done. 13:38:36 INFO - Return code: 0 13:38:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:38:36 INFO - Reading from file tmpfile_stdout 13:38:36 INFO - Output received: 13:38:36 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) 13:38:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:38:36 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')]} 13:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:36 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')]} 13:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:36 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:36 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 13:38:36 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 13:38:36 INFO - Using env: {'DISPLAY': ':0', 13:38:36 INFO - 'HOME': '/home/cltbld', 13:38:36 INFO - 'LANG': 'en_US.UTF-8', 13:38:36 INFO - 'LANGUAGE': 'en_US:en', 13:38:36 INFO - 'LOGNAME': 'cltbld', 13:38:36 INFO - 'MAIL': '/var/mail/cltbld', 13:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:36 INFO - 'MOZ_NO_REMOTE': '1', 13:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:36 INFO - 'NO_EM_RESTART': '1', 13:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:36 INFO - 'PWD': '/builds/slave/test', 13:38:36 INFO - 'SHELL': '/bin/bash', 13:38:36 INFO - 'SHLVL': '1', 13:38:36 INFO - 'TERM': 'linux', 13:38:36 INFO - 'TMOUT': '86400', 13:38:36 INFO - 'USER': 'cltbld', 13:38:36 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:36 INFO - '_': '/tools/buildbot/bin/python'} 13:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:36 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 13:38:36 INFO - Cleaning up... 13:38:36 INFO - Return code: 0 13:38:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:38:36 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')]} 13:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:36 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')]} 13:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:36 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:36 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 13:38:36 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 13:38:36 INFO - Using env: {'DISPLAY': ':0', 13:38:36 INFO - 'HOME': '/home/cltbld', 13:38:36 INFO - 'LANG': 'en_US.UTF-8', 13:38:36 INFO - 'LANGUAGE': 'en_US:en', 13:38:36 INFO - 'LOGNAME': 'cltbld', 13:38:36 INFO - 'MAIL': '/var/mail/cltbld', 13:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:36 INFO - 'MOZ_NO_REMOTE': '1', 13:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:36 INFO - 'NO_EM_RESTART': '1', 13:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:36 INFO - 'PWD': '/builds/slave/test', 13:38:36 INFO - 'SHELL': '/bin/bash', 13:38:36 INFO - 'SHLVL': '1', 13:38:36 INFO - 'TERM': 'linux', 13:38:36 INFO - 'TMOUT': '86400', 13:38:36 INFO - 'USER': 'cltbld', 13:38:36 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:36 INFO - '_': '/tools/buildbot/bin/python'} 13:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:37 INFO - Downloading/unpacking psutil>=3.1.1 13:38:37 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 13:38:37 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 13:38:37 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 13:38:37 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 13:38:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:38:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:38:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:38:38 INFO - Installing collected packages: psutil 13:38:38 INFO - Running setup.py install for psutil 13:38:38 INFO - building 'psutil._psutil_linux' extension 13:38:38 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 13:38:38 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 13:38:38 INFO - building 'psutil._psutil_posix' extension 13:38:38 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 13:38:38 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 13:38:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:38:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:38:38 INFO - Successfully installed psutil 13:38:38 INFO - Cleaning up... 13:38:38 INFO - Return code: 0 13:38:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:38: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')]} 13:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38: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')]} 13:38:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:38 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:38 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 13:38:38 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 13:38:38 INFO - Using env: {'DISPLAY': ':0', 13:38:38 INFO - 'HOME': '/home/cltbld', 13:38:38 INFO - 'LANG': 'en_US.UTF-8', 13:38:38 INFO - 'LANGUAGE': 'en_US:en', 13:38:38 INFO - 'LOGNAME': 'cltbld', 13:38:38 INFO - 'MAIL': '/var/mail/cltbld', 13:38:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:38 INFO - 'MOZ_NO_REMOTE': '1', 13:38:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:38 INFO - 'NO_EM_RESTART': '1', 13:38:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:38 INFO - 'PWD': '/builds/slave/test', 13:38:38 INFO - 'SHELL': '/bin/bash', 13:38:38 INFO - 'SHLVL': '1', 13:38:38 INFO - 'TERM': 'linux', 13:38:38 INFO - 'TMOUT': '86400', 13:38:38 INFO - 'USER': 'cltbld', 13:38:38 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:38 INFO - '_': '/tools/buildbot/bin/python'} 13:38:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:38:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:38:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:38:40 INFO - Installing collected packages: mozsystemmonitor 13:38:40 INFO - Running setup.py install for mozsystemmonitor 13:38:40 INFO - Successfully installed mozsystemmonitor 13:38:40 INFO - Cleaning up... 13:38:40 INFO - Return code: 0 13:38:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:38: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')]} 13:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38: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')]} 13:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:38:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:38:40 INFO - Using env: {'DISPLAY': ':0', 13:38:40 INFO - 'HOME': '/home/cltbld', 13:38:40 INFO - 'LANG': 'en_US.UTF-8', 13:38:40 INFO - 'LANGUAGE': 'en_US:en', 13:38:40 INFO - 'LOGNAME': 'cltbld', 13:38:40 INFO - 'MAIL': '/var/mail/cltbld', 13:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:40 INFO - 'MOZ_NO_REMOTE': '1', 13:38:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:40 INFO - 'NO_EM_RESTART': '1', 13:38:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:40 INFO - 'PWD': '/builds/slave/test', 13:38:40 INFO - 'SHELL': '/bin/bash', 13:38:40 INFO - 'SHLVL': '1', 13:38:40 INFO - 'TERM': 'linux', 13:38:40 INFO - 'TMOUT': '86400', 13:38:40 INFO - 'USER': 'cltbld', 13:38:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:40 INFO - '_': '/tools/buildbot/bin/python'} 13:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:40 INFO - Downloading/unpacking jsonschema==2.5.1 13:38:40 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 13:38:40 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 13:38:40 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 13:38:40 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 13:38:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:38:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:38:41 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 13:38:41 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 13:38:41 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 13:38:41 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 13:38:41 INFO - Downloading functools32-3.2.3-2.tar.gz 13:38:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:38:41 INFO - warning: no files found matching '*.txt' 13:38:41 INFO - no previously-included directories found matching 'build' 13:38:41 INFO - no previously-included directories found matching 'dist' 13:38:41 INFO - no previously-included directories found matching '.git*' 13:38:41 INFO - Installing collected packages: jsonschema, functools32 13:38:41 INFO - Running setup.py install for functools32 13:38:41 INFO - warning: no files found matching '*.txt' 13:38:41 INFO - no previously-included directories found matching 'build' 13:38:41 INFO - no previously-included directories found matching 'dist' 13:38:41 INFO - no previously-included directories found matching '.git*' 13:38:41 INFO - Successfully installed jsonschema functools32 13:38:41 INFO - Cleaning up... 13:38:41 INFO - Return code: 0 13:38:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:38:41 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')]} 13:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:41 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')]} 13:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:41 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:41 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 13:38:41 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 13:38:41 INFO - Using env: {'DISPLAY': ':0', 13:38:41 INFO - 'HOME': '/home/cltbld', 13:38:41 INFO - 'LANG': 'en_US.UTF-8', 13:38:41 INFO - 'LANGUAGE': 'en_US:en', 13:38:41 INFO - 'LOGNAME': 'cltbld', 13:38:41 INFO - 'MAIL': '/var/mail/cltbld', 13:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:41 INFO - 'MOZ_NO_REMOTE': '1', 13:38:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:41 INFO - 'NO_EM_RESTART': '1', 13:38:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:41 INFO - 'PWD': '/builds/slave/test', 13:38:41 INFO - 'SHELL': '/bin/bash', 13:38:41 INFO - 'SHLVL': '1', 13:38:41 INFO - 'TERM': 'linux', 13:38:41 INFO - 'TMOUT': '86400', 13:38:41 INFO - 'USER': 'cltbld', 13:38:41 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:41 INFO - '_': '/tools/buildbot/bin/python'} 13:38:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:38:42 INFO - Cleaning up... 13:38:42 INFO - Return code: 0 13:38:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:38:42 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')]} 13:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:42 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')]} 13:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:42 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:42 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 13:38:42 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 13:38:42 INFO - Using env: {'DISPLAY': ':0', 13:38:42 INFO - 'HOME': '/home/cltbld', 13:38:42 INFO - 'LANG': 'en_US.UTF-8', 13:38:42 INFO - 'LANGUAGE': 'en_US:en', 13:38:42 INFO - 'LOGNAME': 'cltbld', 13:38:42 INFO - 'MAIL': '/var/mail/cltbld', 13:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:42 INFO - 'MOZ_NO_REMOTE': '1', 13:38:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:42 INFO - 'NO_EM_RESTART': '1', 13:38:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:42 INFO - 'PWD': '/builds/slave/test', 13:38:42 INFO - 'SHELL': '/bin/bash', 13:38:42 INFO - 'SHLVL': '1', 13:38:42 INFO - 'TERM': 'linux', 13:38:42 INFO - 'TMOUT': '86400', 13:38:42 INFO - 'USER': 'cltbld', 13:38:42 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:42 INFO - '_': '/tools/buildbot/bin/python'} 13:38:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:42 INFO - Downloading/unpacking blobuploader==1.2.4 13:38:42 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 13:38:42 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 13:38:42 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 13:38:42 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 13:38:43 INFO - Downloading blobuploader-1.2.4.tar.gz 13:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:38:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:38:43 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 13:38:43 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 13:38:43 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 13:38:43 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 13:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:38:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:38:43 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 13:38:43 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 13:38:43 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 13:38:43 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 13:38:43 INFO - Downloading docopt-0.6.1.tar.gz 13:38:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:38:44 INFO - Installing collected packages: blobuploader, requests, docopt 13:38:44 INFO - Running setup.py install for blobuploader 13:38:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:38:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:38:44 INFO - Running setup.py install for requests 13:38:44 INFO - Running setup.py install for docopt 13:38:44 INFO - Successfully installed blobuploader requests docopt 13:38:44 INFO - Cleaning up... 13:38:44 INFO - Return code: 0 13:38:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:38:44 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')]} 13:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:44 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')]} 13:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:44 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:44 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 13:38:44 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 13:38:44 INFO - Using env: {'DISPLAY': ':0', 13:38:44 INFO - 'HOME': '/home/cltbld', 13:38:44 INFO - 'LANG': 'en_US.UTF-8', 13:38:44 INFO - 'LANGUAGE': 'en_US:en', 13:38:44 INFO - 'LOGNAME': 'cltbld', 13:38:44 INFO - 'MAIL': '/var/mail/cltbld', 13:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:44 INFO - 'MOZ_NO_REMOTE': '1', 13:38:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:44 INFO - 'NO_EM_RESTART': '1', 13:38:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:44 INFO - 'PWD': '/builds/slave/test', 13:38:44 INFO - 'SHELL': '/bin/bash', 13:38:44 INFO - 'SHLVL': '1', 13:38:44 INFO - 'TERM': 'linux', 13:38:44 INFO - 'TMOUT': '86400', 13:38:44 INFO - 'USER': 'cltbld', 13:38:44 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:44 INFO - '_': '/tools/buildbot/bin/python'} 13:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:38:44 INFO - Running setup.py (path:/tmp/pip-9wSEX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:38:44 INFO - Running setup.py (path:/tmp/pip-xYwLXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:38:45 INFO - Running setup.py (path:/tmp/pip-0qHpWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:38:45 INFO - Running setup.py (path:/tmp/pip-jLstnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:38:45 INFO - Running setup.py (path:/tmp/pip-sOwQRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:38:45 INFO - Running setup.py (path:/tmp/pip-14RVgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:38:45 INFO - Running setup.py (path:/tmp/pip-9So06Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:38:45 INFO - Running setup.py (path:/tmp/pip-7SrBLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:38:45 INFO - Running setup.py (path:/tmp/pip-sQtIG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:38:45 INFO - Running setup.py (path:/tmp/pip-GuZCgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:38:45 INFO - Running setup.py (path:/tmp/pip-wY5X4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:38:45 INFO - Running setup.py (path:/tmp/pip-hm9D8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:38:45 INFO - Running setup.py (path:/tmp/pip-oG57PC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:38:45 INFO - Running setup.py (path:/tmp/pip-iNMNbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:45 INFO - Running setup.py (path:/tmp/pip-J9kFcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:38:45 INFO - Running setup.py (path:/tmp/pip-RSiJWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:38:46 INFO - Running setup.py (path:/tmp/pip-w_6X7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:38:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:38:46 INFO - Running setup.py install for manifestparser 13:38:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Running setup.py install for mozcrash 13:38:46 INFO - Running setup.py install for mozdebug 13:38:46 INFO - Running setup.py install for mozdevice 13:38:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Running setup.py install for mozfile 13:38:46 INFO - Running setup.py install for mozhttpd 13:38:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Running setup.py install for mozinfo 13:38:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Running setup.py install for mozInstall 13:38:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:38:46 INFO - Running setup.py install for mozleak 13:38:46 INFO - Running setup.py install for mozlog 13:38:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:38:47 INFO - Running setup.py install for moznetwork 13:38:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:38:47 INFO - Running setup.py install for mozprocess 13:38:47 INFO - Running setup.py install for mozprofile 13:38:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:38:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:38:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:38:47 INFO - Running setup.py install for mozrunner 13:38:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:38:47 INFO - Running setup.py install for mozscreenshot 13:38:47 INFO - Running setup.py install for moztest 13:38:47 INFO - Running setup.py install for mozversion 13:38:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:38:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:38:47 INFO - Cleaning up... 13:38:47 INFO - Return code: 0 13:38:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:38:47 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')]} 13:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:47 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')]} 13:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:47 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:47 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 13:38:47 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 13:38:47 INFO - Using env: {'DISPLAY': ':0', 13:38:47 INFO - 'HOME': '/home/cltbld', 13:38:47 INFO - 'LANG': 'en_US.UTF-8', 13:38:47 INFO - 'LANGUAGE': 'en_US:en', 13:38:47 INFO - 'LOGNAME': 'cltbld', 13:38:47 INFO - 'MAIL': '/var/mail/cltbld', 13:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:47 INFO - 'MOZ_NO_REMOTE': '1', 13:38:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:47 INFO - 'NO_EM_RESTART': '1', 13:38:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:47 INFO - 'PWD': '/builds/slave/test', 13:38:47 INFO - 'SHELL': '/bin/bash', 13:38:47 INFO - 'SHLVL': '1', 13:38:47 INFO - 'TERM': 'linux', 13:38:47 INFO - 'TMOUT': '86400', 13:38:47 INFO - 'USER': 'cltbld', 13:38:47 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:47 INFO - '_': '/tools/buildbot/bin/python'} 13:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:38:48 INFO - Running setup.py (path:/tmp/pip-q6RlNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:38:48 INFO - Running setup.py (path:/tmp/pip-gMtZS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:38:48 INFO - Running setup.py (path:/tmp/pip-dW2U71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:38:48 INFO - Running setup.py (path:/tmp/pip-oYSJ7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:38:48 INFO - Running setup.py (path:/tmp/pip-fI5L5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:38:48 INFO - Running setup.py (path:/tmp/pip-4LUhiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:38:48 INFO - Running setup.py (path:/tmp/pip-SvbFm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:38:48 INFO - Running setup.py (path:/tmp/pip-VFzPYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:38:48 INFO - Running setup.py (path:/tmp/pip-YbA3tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:38:48 INFO - Running setup.py (path:/tmp/pip-AjOQcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:38:48 INFO - Running setup.py (path:/tmp/pip-o8nnQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:38:48 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)) 13:38:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:38:48 INFO - Running setup.py (path:/tmp/pip-Rp1hOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:38:49 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)) 13:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:38:49 INFO - Running setup.py (path:/tmp/pip-DUlzmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:38:49 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)) 13:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:38:49 INFO - Running setup.py (path:/tmp/pip-6b4a5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:38:49 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)) 13:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:49 INFO - Running setup.py (path:/tmp/pip-Xy8ypC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:38:49 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)) 13:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:38:49 INFO - Running setup.py (path:/tmp/pip-mejH72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:38:49 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)) 13:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:38:49 INFO - Running setup.py (path:/tmp/pip-ceOng4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:38:49 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)) 13:38:49 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)) 13:38:49 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)) 13:38:49 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)) 13:38:49 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)) 13:38:49 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)) 13:38:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:38:49 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 13:38:49 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 13:38:49 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 13:38:49 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 13:38:50 INFO - Downloading blessings-1.6.tar.gz 13:38:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:38:50 INFO - Installing collected packages: blessings 13:38:50 INFO - Running setup.py install for blessings 13:38:50 INFO - Successfully installed blessings 13:38:50 INFO - Cleaning up... 13:38:50 INFO - Return code: 0 13:38:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:38:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:38:50 INFO - Reading from file tmpfile_stdout 13:38:50 INFO - Current package versions: 13:38:50 INFO - argparse == 1.2.1 13:38:50 INFO - blessings == 1.6 13:38:50 INFO - blobuploader == 1.2.4 13:38:50 INFO - docopt == 0.6.1 13:38:50 INFO - functools32 == 3.2.3-2 13:38:50 INFO - jsonschema == 2.5.1 13:38:50 INFO - manifestparser == 1.1 13:38:50 INFO - mozInstall == 1.12 13:38:50 INFO - mozcrash == 1.0 13:38:50 INFO - mozdebug == 0.1 13:38:50 INFO - mozdevice == 0.49 13:38:50 INFO - mozfile == 1.2 13:38:50 INFO - mozhttpd == 0.7 13:38:50 INFO - mozinfo == 0.9 13:38:50 INFO - mozleak == 0.1 13:38:50 INFO - mozlog == 3.4 13:38:50 INFO - moznetwork == 0.27 13:38:50 INFO - mozprocess == 0.25 13:38:50 INFO - mozprofile == 0.28 13:38:50 INFO - mozrunner == 6.13 13:38:50 INFO - mozscreenshot == 0.1 13:38:50 INFO - mozsystemmonitor == 0.3 13:38:50 INFO - moztest == 0.8 13:38:50 INFO - mozversion == 1.4 13:38:50 INFO - psutil == 3.1.1 13:38:50 INFO - requests == 1.2.3 13:38:50 INFO - wsgiref == 0.1.2 13:38:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:50 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:50 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 13:38:50 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 13:38:50 INFO - Using env: {'DISPLAY': ':0', 13:38:50 INFO - 'HOME': '/home/cltbld', 13:38:50 INFO - 'LANG': 'en_US.UTF-8', 13:38:50 INFO - 'LANGUAGE': 'en_US:en', 13:38:50 INFO - 'LOGNAME': 'cltbld', 13:38:50 INFO - 'MAIL': '/var/mail/cltbld', 13:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:50 INFO - 'MOZ_NO_REMOTE': '1', 13:38:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:50 INFO - 'NO_EM_RESTART': '1', 13:38:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:50 INFO - 'PWD': '/builds/slave/test', 13:38:50 INFO - 'SHELL': '/bin/bash', 13:38:50 INFO - 'SHLVL': '1', 13:38:50 INFO - 'TERM': 'linux', 13:38:50 INFO - 'TMOUT': '86400', 13:38:50 INFO - 'USER': 'cltbld', 13:38:50 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:50 INFO - '_': '/tools/buildbot/bin/python'} 13:38:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 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)) 13:38:51 INFO - Cleaning up... 13:38:51 INFO - Return code: 0 13:38:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:38:51 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')]} 13:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:51 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')]} 13:38:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:51 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 0x23dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', '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 13:38:51 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 13:38:51 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 13:38:51 INFO - Using env: {'DISPLAY': ':0', 13:38:51 INFO - 'HOME': '/home/cltbld', 13:38:51 INFO - 'LANG': 'en_US.UTF-8', 13:38:51 INFO - 'LANGUAGE': 'en_US:en', 13:38:51 INFO - 'LOGNAME': 'cltbld', 13:38:51 INFO - 'MAIL': '/var/mail/cltbld', 13:38:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:51 INFO - 'MOZ_NO_REMOTE': '1', 13:38:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:38:51 INFO - 'NO_EM_RESTART': '1', 13:38:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:38:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:51 INFO - 'PWD': '/builds/slave/test', 13:38:51 INFO - 'SHELL': '/bin/bash', 13:38:51 INFO - 'SHLVL': '1', 13:38:51 INFO - 'TERM': 'linux', 13:38:51 INFO - 'TMOUT': '86400', 13:38:51 INFO - 'USER': 'cltbld', 13:38:51 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:38:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:51 INFO - '_': '/tools/buildbot/bin/python'} 13:38:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:38:51 INFO - Cleaning up... 13:38:51 INFO - Return code: 0 13:38:51 INFO - Running post-action listener: _resource_record_post_action 13:38:51 INFO - Running post-action listener: _start_resource_monitoring 13:38:51 INFO - Starting resource monitoring. 13:38:51 INFO - [mozharness: 2017-05-15 20:38:51.419959Z] Finished create-virtualenv step (success) 13:38:51 INFO - [mozharness: 2017-05-15 20:38:51.420425Z] Running install step. 13:38:51 INFO - Running pre-action listener: _resource_record_pre_action 13:38:51 INFO - Running main action method: install 13:38:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:38:51 INFO - Reading from file tmpfile_stdout 13:38:51 INFO - Detecting whether we're running mozinstall >=1.0... 13:38:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:38:51 INFO - Reading from file tmpfile_stdout 13:38:51 INFO - Output received: 13:38:51 INFO - Usage: mozinstall [options] installer 13:38:51 INFO - Options: 13:38:51 INFO - -h, --help show this help message and exit 13:38:51 INFO - -d DEST, --destination=DEST 13:38:51 INFO - Directory to install application into. [default: 13:38:51 INFO - "/builds/slave/test"] 13:38:51 INFO - --app=APP Application being installed. [default: firefox] 13:38:51 INFO - mkdir: /builds/slave/test/build/application 13:38:51 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'] 13:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:39:05 INFO - Reading from file tmpfile_stdout 13:39:05 INFO - Output received: 13:39:05 INFO - /builds/slave/test/build/application/firefox/firefox 13:39:05 INFO - Running post-action listener: _resource_record_post_action 13:39:05 INFO - [mozharness: 2017-05-15 20:39:05.824929Z] Finished install step (success) 13:39:05 INFO - [mozharness: 2017-05-15 20:39:05.825071Z] Running run-tests step. 13:39:05 INFO - Running pre-action listener: _resource_record_pre_action 13:39:05 INFO - Running main action method: run_tests 13:39:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:39:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:39:05 INFO - Python 2.7.3 13:39:05 INFO - Return code: 0 13:39:05 INFO - grabbing minidump binary from tooltool 13:39: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')]} 13:39:05 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 0x2677400>, '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 0x26783b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2678840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:39:05 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 13:39:05 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 13:39:05 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 13:39:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:39:05 INFO - Return code: 0 13:39:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:39:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:39:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:39:05 INFO - ENV: RUST_BACKTRACE is now 1 13:39:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:39:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:39:05 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/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:39:05 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/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:39:05 INFO - Using env: {'DISPLAY': ':0', 13:39:05 INFO - 'HOME': '/home/cltbld', 13:39:05 INFO - 'LANG': 'en_US.UTF-8', 13:39:05 INFO - 'LANGUAGE': 'en_US:en', 13:39:05 INFO - 'LOGNAME': 'cltbld', 13:39:05 INFO - 'MAIL': '/var/mail/cltbld', 13:39:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:39:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:39:05 INFO - 'MOZ_NO_REMOTE': '1', 13:39:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:39:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:39:05 INFO - 'NO_EM_RESTART': '1', 13:39:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:39:05 INFO - 'PWD': '/builds/slave/test', 13:39:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:39:05 INFO - 'RUST_BACKTRACE': '1', 13:39:05 INFO - 'SHELL': '/bin/bash', 13:39:05 INFO - 'SHLVL': '1', 13:39:05 INFO - 'TERM': 'linux', 13:39:05 INFO - 'TMOUT': '86400', 13:39:05 INFO - 'USER': 'cltbld', 13:39:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1494878244.585531-499719470', 13:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:39:05 INFO - '_': '/tools/buildbot/bin/python'} 13:39:05 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/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:39:06 INFO - mozversion application_buildid: 20170515194727 13:39:06 INFO - mozversion application_changeset: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d 13:39:06 INFO - mozversion application_display_name: Firefox 13:39:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:39:06 INFO - mozversion application_name: Firefox 13:39:06 INFO - mozversion application_remotingname: firefox 13:39:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:39:06 INFO - mozversion application_vendor: Mozilla 13:39:06 INFO - mozversion application_version: 54.0 13:39:06 INFO - mozversion platform_buildid: 20170515194727 13:39:06 INFO - mozversion platform_changeset: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d 13:39:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:39:06 INFO - mozversion platform_version: 54.0 13:39:06 INFO - using testdate: 1494880746 13:39:06 INFO - actual date: 1494880746 13:39:06 INFO - starting webserver on 'localhost:59331' 13:39:06 INFO - SUITE-START | Running 2 tests 13:39:06 INFO - TEST-START | dromaeo_css 13:39:06 INFO - operating with platform_type : linux_ 13:39:06 INFO - Initialising browser for dromaeo_css test... 13:39:06 INFO - TEST-INFO | started process 4642 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuBelkr/profile http://localhost:59331/getInfo.html) 13:39:11 INFO - PROCESS | 4642 | __metrics Screen width/height:1600/1200 13:39:11 INFO - PROCESS | 4642 | colorDepth:24 13:39:11 INFO - PROCESS | 4642 | Browser inner width/height: 1024/697 13:39:11 INFO - PROCESS | 4642 | __metrics 13:39:12 INFO - PROCESS | 4642 | 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" 13:39:12 INFO - PROCESS | 4642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:39:12 INFO - PROCESS | 4642 | console.error: PushService: 13:39:12 INFO - PROCESS | 4642 | stateChangeProcessEnqueue: Error transitioning state 13:39:12 INFO - PROCESS | 4642 | UnknownError 13:39:13 INFO - TEST-INFO | 4642: exit 0 13:39:13 INFO - Browser initialized. 13:39:13 INFO - Running cycle 1/1 for dromaeo_css test... 13:39:13 INFO - TEST-INFO | started process 4734 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuBelkr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:39:25 INFO - PROCESS | 4734 | RSS: Main: 141193216 13:39:25 INFO - PROCESS | 4734 | 13:42:26 INFO - PROCESS | 4734 | Cycle 1(1): loaded http://localhost:59331/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59331/tests/dromaeo/cssquery-ext.html) 13:42:28 INFO - PROCESS | 4734 | RSS: Main: 256880640 13:42:28 INFO - PROCESS | 4734 | 13:45:30 INFO - PROCESS | 4734 | Cycle 1(1): loaded http://localhost:59331/tests/dromaeo/cssquery-ext.html (next: http://localhost:59331/tests/dromaeo/cssquery-jquery.html) 13:45:30 INFO - PROCESS | 4734 | RSS: Main: 222105600 13:45:30 INFO - PROCESS | 4734 | 13:48:32 INFO - PROCESS | 4734 | Cycle 1(1): loaded http://localhost:59331/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59331/tests/dromaeo/cssquery-mootools.html) 13:48:33 INFO - PROCESS | 4734 | RSS: Main: 206700544 13:48:33 INFO - PROCESS | 4734 | 13:51:35 INFO - PROCESS | 4734 | Cycle 1(1): loaded http://localhost:59331/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59331/tests/dromaeo/cssquery-prototype.html) 13:51:35 INFO - PROCESS | 4734 | RSS: Main: 172126208 13:51:35 INFO - PROCESS | 4734 | 13:54:38 INFO - PROCESS | 4734 | Cycle 1(1): loaded http://localhost:59331/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59331/tests/dromaeo/cssquery-yui.html) 13:54:39 INFO - PROCESS | 4734 | RSS: Main: 175460352 13:54:39 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | Cycle 1(1): loaded http://localhost:59331/tests/dromaeo/cssquery-yui.html (next: http://localhost:59331/tests/dromaeo/cssquery-dojo.html) 13:57:41 INFO - PROCESS | 4734 | RSS: Main: 267816960 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | __start_tp_report 13:57:41 INFO - PROCESS | 4734 | _x_x_mozilla_page_load 13:57:41 INFO - PROCESS | 4734 | _x_x_mozilla_page_load_details 13:57:41 INFO - PROCESS | 4734 | |i|pagename|runs| 13:57:41 INFO - PROCESS | 4734 | |0;dojo.html;3415.5047518109873;3451.7411194160436;3474.930501389972;3496.353174662686;3516.1561225305936;5746.798862039829;5808.477237048664;5810.622309549876;5837.503812175964;5846.88306233875;5767.913481297784;5835.183074369581;5836.649801011942;5841.474267315941;5847;5132.281480592711;5185.818496352628;5208.947910520884;5213.973930130344;5265.842024739264;5067.49325067494;5274.92087618686;5280.894382112356;5284.497972692588;5306.92039619406;5109.974450127763;5142.7428628568605;5179.430262671103;5179.715115668637;5194.142966410538;5247.160454327309;5277.788888444457;5278.10272253714;5280.841574752744;5282.154855223146;5166.199239117943;5214.400343960418;5258.8685282867855;5275.182346736262;5310.203469479609;5573.749181286852;5616.241807355996;5716.599838011341;5781.075027995543;5851.473367396912;6253.96873015632;6272.592281501734;6276.372362763733;6279.717412716439;6284.402981716729;6316.894543454877;6326.398992095744;6328.240611126695;6359.904601430982;6365.49076073913;5622.718864056781;5704.115862041349;5802.274715660543;5856.707164641751;5902.76388944446;6424.775132870327;6429.710663020175;6443.871122577522;6446.4520515756685;6452.193475815523;6126.754929802758;6174.166487524127;6197.4732147767945;6203.96898015516;6220.129181914535;6042.274927008699;6308.653024083719;6312.021636646327;6324.1778568786685;6332.081848131995;6115.6330620162935;6152.723127459275;6171.166892469549;6174;6202.81391558254;5967.343592204854;5988.431099045583;5990.281166260077;5990.7903223386975;6265.530085243625;4611.123886461562;4634.23535116702;4653.325267836212;4664.277037059262;4732.290156476556;4258.893527661771;4261.936070958938;4266.317389217772;4299.656027517791;4323.632491238217;5792.247007888947;5834.67909264986;6031.487323577545;6056.9697151513965;6079.908801367983;6173.660448675276;6198.318184999646;6273;6284.528660350492;6297.748090076346;5929.940700593025;5933.317668468095;5934.465898069108;5942.762289508371;6029.969850150721;5301.204819277139;5315.335583052119;5315.787368505216;5323.574114070865;5324.600654950894;6204.3795620437595;6205.162303089115;6468.54720169593;6501.05734668461;6502;6258.311585725475;6313.273973493107;6317.747290108344;6320.747170113144;6337.746490140527;6599.8020059398295;6600.141981542369;6619.304972977768;6635.0379195015485;6660.467162626881;5941.346451890375;5976.372480889618;6126;6140.293866205265;6151.200343955436;6275.435210831047;6287.937120628918;6295.842603934938;6301.40136687014;6305.779297724557;6052.334243233153;6101.664408457577;6115.877682446415;6122.448979591858;6124.203853499017;5843.766249349978;5889.234399528205;6068.302145253352;6117;6139.6009259398;6067.059605761114;6076.1493390924425;6080.118382834553;6084.756609735561;6116.480099191441;6149.046897730859;6156.384361563987;6157.784477543264;6163.9383606163365;6173.228346456693;5722.2561067061015;5752.395998420106;5764.942350576608;5861.589688721749;5912.586118971631 13:57:41 INFO - PROCESS | 4734 | |1;ext.html;726.8546290741853;811.326598922894;825.9050209225938;886.6453418632547;898.6809682562695;31238;44133.558664413344;44743;44804.32793508092;44891.42879999205;42393.36409953853;42537;42684.35973460393;48219;48521.51478485214;7801.414893882953;7935.880961785578;9148.634054637823;9267.165955064043;9276.628934842598;10639.893601063968;10842;10843.891561084367;10861.40262285574;10895;11072.723181920475;11107.500162492666;11110.555577776879;11116.888831111706;11118.166137539676;10980.066694330992;10994.28537145083;11007.06439952605;11017.944910275477;11020.228583999164;10789.406582637952;10820.513076911559;10835.729106772314;10852.511636976355;10858.945705271502;43272.783636081935;43423;43492;43550.56449435497;43567.12865742667;4376.3654270130655;4398.23030969579;4417.094495628388;4489.551044895517;4527.886802829923;30066.24834379136;31187.220319491968;31215.375692486024;31339.686603134134;31843.84078079618;4257.254980378421;4324.6972711910175;4397.186520493687;4419.889502762425;4509.684322097454;243635;244617;245096;245814;246438;53936.73031634817;53953.190702138716;54097;54105;54223.186652200246;57108.71445642745;57228;57351.71324143436;57420;57493.13760293599;17237.396691116002;17521.7371739424;17529.211185496682;17576.033318167625;17629.911850440665;12726.363681815872;12934.159279646792;15002.849971500364;15529.145896975775;15538.689226215412;14268.928655356656;14314.210723659542;14355.64110897215;14409.279536023158;14461.855381446261;5553.861153471195;5568.134674883879;5599.8600034998635;5698.971505142447;5730.426957304319;1014.4775440648073;1015.4313584392617;1020.8417695257247;1029.444100185906;1030.4487099401783;28923.276918076797;28945.93389166964;28947.276318092216;29106.708932910824;29188;1096.8957948994832;1108.3073079325422;1111.1222134513805;1115.1914861725184;1122.8428020077197;14847.925760371128;15429.459968901034;15544.922275388777;15572.922135389477;15657.138857362954;308829;309068;309212;310316;311838;55794.44205557973;55875.720621396635;55967.440325596224;55984;56064.15903761447;1442.0986883198002;1445.9710805783825;1467.149053548939;1468.1851572377434;1470.6764511807385;27792.58311125294;28644.42711145807;29236.415271694874;29335.85332073326;29489.410211796072;38338.6166138335;38383.42424863573;38641.03397415087;38675;38707.22585548217;322097;322638;322768;324795;451983;25153;25587;29117.417651646425;29629;31753;40393.39409908795;40439;40476.59523404846;40573;41613.37579936364;31915.202119947186;32050;32187;32319;32564.185895352806;28053.71946280511;28796;28963.85518072396;29090.27274318159;29164.7083529162;35450.82274588611;35506.82246588854;36170.819145904105;36309.45535816912;36535.26929461449;29789.5531567031;29868.70131298659;30004.549931750604;30233.848830756586;30391.392172156877;23668.28995130149;24589.13938012161;24896.50206995886;24973.500529988934;25218.495630087662 13:57:41 INFO - PROCESS | 4734 | |2;jquery.html;462.78943080898193;481.3093211241868;485.2842057964502;486.3264378835314;487.29099160721154;19822.801771982275;21654.458638534044;21983.890080549616;22478.325650230498;22644.207452739156;22196.556068878654;22357.217497387595;22642;22816.31551053471;22923.770762292374;6586.110875031881;6689.230738465078;6689.531732778707;6725.05849181115;6734.730610775563;6604.174478190226;6724.226713927887;6725.899111513307;7138.072050633436;7442.925570744304;7606.163322034572;7620.2760737730105;7625.8474830503355;7626.08486981563;7628.275313845176;7381.372583330423;7561.130469995938;7602.2017688142505;7609.505382150171;7612.885806712904;7261.854762904765;7406.518576292524;7544.207858174867;7555.433342499279;7563.0924289085115;24803.62794558083;25773.226803195936;25788.226353209433;25881.870590647115;25995;3119.266972261517;3302.0424077017638;3483.7909725416303;3582.444721068239;3683.9447408288897;16390.098544580043;16460.259288332054;16479.505614831574;16668.74996875048;17349.653006939916;2199.3841724317217;2220.7113075300267;2220.7863213877495;2234.620114580509;2260.8756518391483;108889.91110088877;109472.4526377371;110082.44958775234;110084;113549.4322528382;16800;17698.203580838635;17994.460166195036;18161.63676726458;18647.44057678272;32280;32288;32375;32411.51382729261;32535.349293014006;10953.397563134104;11001.669949901516;11042.282251653616;11152.052075573505;11287.774244515227;9295.302852286106;9376.390534615228;9419.246460283299;9456.8581471278;9625.32622716403;9169.770755731026;9347.719568412958;9370.765730856647;9382.765430864147;9486.335956483117;3756.492873462102;3781.546214454283;3849.5188101487315;3869.5356557213313;3875.4768106305287;977.3256453047375;996.2179688944144;1166.5916929074756;1239.9504019839285;1244.440001999104;20765.792342076387;20901.581968360548;20916;20987.370378888354;21089.156433742784;1189.167582692119;1228.8955438787625;1235.765204611121;1242.2670624331506;1249.463588914505;11345.943270283597;11359.545618175345;11366.545338186545;11430.599929002612;11470.426478676032;653834;654838;658774;660171;661484;90651.54674226587;94571;111100;119201.40399297948;124094;1310.8164856920039;1311.9934400327936;1354.5056054540023;1362.4618275781208;1401.53048728675;18688.7196692047;19420.902895485433;19458.902705486948;19461;19545.21819127219;46165.07669846652;51999.480005200974;52372.21441678454;52542.73728631332;53280.20079698885;66423.66788166222;66803.33196667972;67112;68292.658536707;68936.65531672309;12144.939275303566;12641.304728240035;12656.557020504606;12773.936130319289;12872.35638218104;13401.396937138048;14744.778828317369;14959.40162393492;15116.470923517623;15188;12622;12647.051471139517;12736.108472406842;12815.935920320338;12866.935665321613;11195.44022798873;11292.887071129184;11330.94334528322;11422.31466112036;11729.530818767154;12957.611271661865;13051.804222936473;13092.279924603858;13140.277284749049;13427.865721342661;12599;12982;13017.804732928824;13106.60680179596;13302.467901284228;11914.880851191378;12192.329421881881;12241.571544995883;12655.11414200997;12657.98736101128 13:57:41 INFO - PROCESS | 4734 | |3;mootools.html;1398.930053497325;1561.9921900390495;1579.4314046943098;1596.241785152053;1598.0172194100633;9441.905580944198;11304.491297891604;11332.263402878807;11625.941870290659;11633.767324653501;11726.472308746106;11773.470193841276;11781;11788.233764805282;11807.350595717233;1835.7705286839146;2396.436837343223;2429.769171928664;2429.866357350345;2431.9756802431925;2061.865978711379;2366.4557151855083;2416.5770990076753;2419.8669073200967;2421.0436877433403;2382.845115067515;2383.5947888858936;2386.95226095478;2391.0196819304088;2391.2945681024053;2293.655951607272;2307.065638416444;2310.1567927706433;2310.445493081665;2313.525727225923;2284.6344584866342;2309.2841219222096;2309.3533810533077;2311.838171328008;2317.9652305215436;11359.488823002985;11371.260868043551;11440.19918605698;11460.255083419552;11658.358790266531;5770.884582308372;5887.646741195499;5889.734961926723;5910.852228694274;5968.343482216953;3242.659520750311;3333.984947707951;3381.8647254109806;3389.661033896615;3430.142464383904;1343.40890008396;1401.061288936415;1409.478492957602;1425.6934759026858;1464.5606318104526;17514.38699645506;17593;17621.911890440464;17693.911530442394;17727.822721772875;4698.878857029495;4769.42766867978;4839.758012099381;4891.902161956741;4893.510648935078;13867.37596808146;13940;13940.372683229076;13944.372503237379;13997.090189137878;5944.791932282349;5970.194023806817;6010.729517171738;6017.097435384728;6024.57827952048;4480.283154695233;4612.74629895359;5423.945760542423;5495.203195536705;5533.474319939599;3741.6071312511795;3833.3483307837178;4120.340745480708;4172.520160181557;4215.957840421618;1110.4503270880966;1142.7428828513684;1155.9075273978062;1158.25871442277;1172.0623501199007;1042.4006196437078;1043.400044974143;1052.0899421999916;1054.8154073037342;1066.1737153705785;7295.234000429985;7301.269873012656;7344.926550734531;7394.815129621803;7561.735339263099;1087.0977089016098;1098.6594155811724;1102.7739313440725;1112.4437781109445;1124.1231839165464;4587.266037433995;4805.014971930746;4847.587955023792;4863.270509423544;4938.8765280868265;13972.72054558903;14410.783838242434;14411.42354305837;14417.567472975827;14482.855171448362;13778.035537512478;14242.643933901529;14248.216348100746;14272.215028173343;14330.355134018993;10572.577096916037;10693.465326733787;10707.1434285257;10732.570697172181;10812.459377031117;7187.748428804966;7434.7769566913075;7505.812354691177;7508.361789247975;7598.772036838904;12823.422945967268;13025.348732563145;13120;13125.671858203621;13410.731785364433;13124.606261812161;13303;13575.389107490384;13657.044006919421;13659.453621855411;9731.318807683394;9841.704748857546;10009;10024.448655324028;10027.147692446224;11053.28153670009;11064.502097405475;11093.5562577496;11101;11128.109751219721;9293.070692930653;9342.346035777702;9424.764380890532;9475.763105922408;9563.282753793354;9312.813743725223;9389.483578403253;9412.623495060121;9584.089511496675;9646.807063858543;8220.260176584135;8397.244248017707;8467.703630372907;8549.829003419773;8568.957155214184;5918.053111502139;5950.494207992196;5950.7917222896995;6111.449969502766;6155.846103847261;6015.759369625166;6127;6164.6917654118015;6210.285817131088;6297.685115744102 13:57:41 INFO - PROCESS | 4734 | |4;prototype.html;411.33774622857214;430.61029768059905;433.01436594679683;438.7630679433106;438.8485972339542;5208.28126718183;5263.1578947368425;5270.025045366609;5281.419043905177;5316.574674026078;4948.99565675901;5189.377274727038;5199.506046925546;5224.399194092681;5225.921611175835;4297.634701050414;4308.482982042144;4321.416608757817;4326.6106050455455;4329.848455304065;4291.785410729467;4306.643407326697;4335.5881191286935;4340.9565904341025;4344.174606824709;4334.696571240014;4335.6748243881675;4340.609345158935;4370.508809978313;4380.759058251795;4145.7098003139645;4256.020785450166;4272.636825869805;4298;4301.956980430218;4013.2174226025936;4018.3369743992207;4023.2355852387955;4038.3942408638645;4063.8374465021366;5031.421386540522;5068.949310506885;5086.745662716887;5127.7948882044675;5137.8201762938115;4983.800647974077;5065.885505188852;5185.611079169077;5254.553362964152;5282.313299271109;5489.451054894518;5517.393086760493;5526.861828454281;5537.889242215133;5580.86047848803;4846.61227101831;4868.951310486886;4871.512848715136;4881.316615673809;4881.951180488185;5430.348358197002;5475.589330800166;5489.890202195974;5513.49136143054;5708.743106560215;4825;4837.782299796518;4838.443578988461;4872.439669437989;4888.50686150066;4953.529414705597;4954.9752251238515;4957.652964292465;4959.330490383824;4970.627202959792;4997.650164488524;5097.9745101275;5145.794168233304;5165.173572228426;5167.431582525919;5444.183372494158;5459.508644222039;5467.261919640877;5481;5501;1679.5717092141426;1683.8316168383308;1702.7105392083377;1706.234047760562;1713.6658351621313;1392.5100142847057;1403.3510625015565;1407.6691977385306;1443.2711480702387;1469.5517867050653;5324.8136315228785;5409.29679141709;5420.4850539198715;5475.890482190413;5508.614396992252;5504.4770746778995;5568.248286481355;5598.972005139948;5641.858953526113;5655.264815573949;5223.5559977402345;5404.432534583891;5434.2935418395355;5464.836054918359;5465.808696695596;2910.54886492594;2916.912492625225;2937.515309973873;2956.8669409876607;2973.539101439237;5749.741261643236;5776.393478684761;5815.651060936358;5852.94147058524;5880.323762767251;5447.182922561568;5710.543156547466;5712.285964254468;5720.227769251181;5747.195392645033;5866.767978724431;5867.559933005127;5872.647641141545;5877;5903.7048147593305;5492.588055895903;5651.632643878134;5654.971725141348;5660.330202590329;5681.204631351531;5312.1766126250495;5500.50495455395;5573.749181286771;5582.413846546055;5607.327120745374;5679.09989362926;5760.423957604374;5802.506786923159;5855.853603659943;5891.293044834647;4712.7643617818285;4751.40607424072;4754.738489383117;4757.714537127784;4787.40157480315;5449.754761035842;5634.718264086698;5653.632513886584;5697.772089116389;5732.8534293142375;5474.633100643156;5498.618349714977;5513.3108361454815;5562;5563.916541251965;5508.008558459515;5509;5532.585056120726;5551.389347171888;5555.972220138873;5253.553447956813;5447.53695935834;5472.413000230048;5486.698231597141;5489.533389661923;5512.283403157564;5516.779328826802;5578.652808314979;5581.972090139523;5628.774849006158;5340.626156169031;5385.6230063895155;5443.210734443563;5491.28613280287;5500.257465242301 13:57:41 INFO - PROCESS | 4734 | |5;yui.html;828.101644245142;844.2106630300666;950.2635457520421;968.1673760565916;984.0553069053706;17110;18812.529686757836;18923.52691182721;18982.620347593074;19104.713429298554;15734.213289335516;17592.912035439804;17772.64454710905;17776.377826776064;17787.199576019077;881.3257857738851;905.2350763604743;908.2280061947325;908.6637943960744;909.0818273543736;894.4990805149105;895.5790778334173;900.0999000999001;900.9369344145913;901.6078006067349;963.4123184857232;964.4599024546242;965.1120968708805;965.9951700241489;966.2077096780642;955.4171955107379;956.2302346610975;958.0180315176965;958.0611001218809;958.4872093430051;702.371377617035;705.7459314646723;707.8584283143392;708.5996412027188;708.8794904866185;16050.1172435516;16829.91585042079;16841.91579042109;16994.745078823827;16997.320107195697;14666.853331466655;14682.119072855663;14747.262636868221;14974.326155322928;15003.099814011086;12781.936090319581;12787.104902656818;12813.167144135601;12819.615411537668;12995.610131696065;4011.3581826907553;4021.638052575253;4028.617281358266;4054.2296963576828;4156.91686166278;77389.22610773877;78594.60702696507;78748.60625696834;79534.602326988;80264;10796.460176991119;10904.781904362027;10938.890611093946;11109.777804444027;11162.274452160584;23250.186243481396;23307.88346058293;23588.528229435316;23672;23680.40798980039;9285.72142835716;9304.674336398193;9429.057094290516;9432.481213533321;9450.43297402158;5220.425753167148;5240;5240.371155461293;5253.711045892436;5362.410134885159;3689.981550092286;3775.244951009809;3775.3581891078584;3777.244551089793;3785.205106927521;588.1677426441623;595.7616953218748;608.1546650156287;608.3794529579803;610.7709608896664;862.3403096631082;882.761654353321;892.3981453291358;899.9190072893471;928.5867788834039;11322.92432218938;11382.658520244406;11529.135314851419;11665.941670291764;11823.704407389883;681.4752640466813;683.7606837606797;694.2190036209265;696.5820507695444;706;5229.111051121318;5338.225957236179;5353.2237825515085;5412.972935135299;5420.620556561003;85684.57157714172;86060;87429.5628521866;87996.5600171995;88278;75738.24261757421;76122.6193869027;76201.61899190469;76599.61700191464;77957.61021194857;68582.65708671525;68598.65700671465;69737.65131174312;70161;71852.64073679702;10123;10162.745931351776;10260.538275777459;10390.948045259725;10415.791684166426;38334.424983625824;39536.60463395329;39701.801490993515;39773.60226397699;47811.760941196466;47687.52312476831;53579;55730.72134639301;56191;56467.15299270432;12232.449539770909;12705.110642254953;14540.63648408756;14636.414543418145;14676.412943482568;14926.402943882124;15139.772903406678;15272.694546109238;18366.667494844485;18781.90609046946;15439.84560154384;16717.66564668724;16767.1616419181;17224.138793060047;18146.90926545359;16169.27238274304;16926.915365423094;17413.216405262046;17761.111944402986;17799.911000445434;15880.444184453489;16169.838301616834;17126.48620541336;17613.38353157633;17648;11957.820632690342;12160.209586376857;12378.62864114078;12574.371281436075;12783.552575659807;11925.701857453632;12418.379081046092;12782;13374.318988353998;13792.793108103186 13:57:41 INFO - PROCESS | 4734 | __end_tp_report 13:57:41 INFO - PROCESS | 4734 | __start_cc_report 13:57:41 INFO - PROCESS | 4734 | _x_x_mozilla_cycle_collect,1145 13:57:41 INFO - PROCESS | 4734 | __end_cc_report 13:57:41 INFO - PROCESS | 4734 | __startTimestamp1494881861661__endTimestamp 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | ------- Summary: start ------- 13:57:41 INFO - PROCESS | 4734 | Number of tests: 6 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | [#0] dojo.html Cycles:165 Average:5777.81 Median:5990.28 stddev:669.72 (11.2%) stddev-sans-first:645.63 13:57:41 INFO - PROCESS | 4734 | Values: 3415.5 3451.7 3474.9 3496.4 3516.2 5746.8 5808.5 5810.6 5837.5 5846.9 5767.9 5835.2 5836.6 5841.5 5847.0 5132.3 5185.8 5208.9 5214.0 5265.8 5067.5 5274.9 5280.9 5284.5 5306.9 5110.0 5142.7 5179.4 5179.7 5194.1 5247.2 5277.8 5278.1 5280.8 5282.2 5166.2 5214.4 5258.9 5275.2 5310.2 5573.7 5616.2 5716.6 5781.1 5851.5 6254.0 6272.6 6276.4 6279.7 6284.4 6316.9 6326.4 6328.2 6359.9 6365.5 5622.7 5704.1 5802.3 5856.7 5902.8 6424.8 6429.7 6443.9 6446.5 6452.2 6126.8 6174.2 6197.5 6204.0 6220.1 6042.3 6308.7 6312.0 6324.2 6332.1 6115.6 6152.7 6171.2 6174.0 6202.8 5967.3 5988.4 5990.3 5990.8 6265.5 4611.1 4634.2 4653.3 4664.3 4732.3 4258.9 4261.9 4266.3 4299.7 4323.6 5792.2 5834.7 6031.5 6057.0 6079.9 6173.7 6198.3 6273.0 6284.5 6297.7 5929.9 5933.3 5934.5 5942.8 6030.0 5301.2 5315.3 5315.8 5323.6 5324.6 6204.4 6205.2 6468.5 6501.1 6502.0 6258.3 6313.3 6317.7 6320.7 6337.7 6599.8 6600.1 6619.3 6635.0 6660.5 5941.3 5976.4 6126.0 6140.3 6151.2 6275.4 6287.9 6295.8 6301.4 6305.8 6052.3 6101.7 6115.9 6122.4 6124.2 5843.8 5889.2 6068.3 6117.0 6139.6 6067.1 6076.1 6080.1 6084.8 6116.5 6149.0 6156.4 6157.8 6163.9 6173.2 5722.3 5752.4 5764.9 5861.6 5912.6 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | [#1] ext.html Cycles:180 Average:46740.84 Median:308948.50 stddev:80101.38 (25.9%) stddev-sans-first:80251.58 13:57:41 INFO - PROCESS | 4734 | Values: 726.9 811.3 825.9 886.6 898.7 31238.0 44133.6 44743.0 44804.3 44891.4 42393.4 42537.0 42684.4 48219.0 48521.5 7801.4 7935.9 9148.6 9267.2 9276.6 10639.9 10842.0 10843.9 10861.4 10895.0 11072.7 11107.5 11110.6 11116.9 11118.2 10980.1 10994.3 11007.1 11017.9 11020.2 10789.4 10820.5 10835.7 10852.5 10858.9 43272.8 43423.0 43492.0 43550.6 43567.1 4376.4 4398.2 4417.1 4489.6 4527.9 30066.2 31187.2 31215.4 31339.7 31843.8 4257.3 4324.7 4397.2 4419.9 4509.7 243635.0 244617.0 245096.0 245814.0 246438.0 53936.7 53953.2 54097.0 54105.0 54223.2 57108.7 57228.0 57351.7 57420.0 57493.1 17237.4 17521.7 17529.2 17576.0 17629.9 12726.4 12934.2 15002.8 15529.1 15538.7 14268.9 14314.2 14355.6 14409.3 14461.9 5553.9 5568.1 5599.9 5699.0 5730.4 1014.5 1015.4 1020.8 1029.4 1030.4 28923.3 28945.9 28947.3 29106.7 29188.0 1096.9 1108.3 1111.1 1115.2 1122.8 14847.9 15429.5 15544.9 15572.9 15657.1 308829.0 309068.0 309212.0 310316.0 311838.0 55794.4 55875.7 55967.4 55984.0 56064.2 1442.1 1446.0 1467.1 1468.2 1470.7 27792.6 28644.4 29236.4 29335.9 29489.4 38338.6 38383.4 38641.0 38675.0 38707.2 322097.0 322638.0 322768.0 324795.0 451983.0 25153.0 25587.0 29117.4 29629.0 31753.0 40393.4 40439.0 40476.6 40573.0 41613.4 31915.2 32050.0 32187.0 32319.0 32564.2 28053.7 28796.0 28963.9 29090.3 29164.7 35450.8 35506.8 36170.8 36309.5 36535.3 29789.6 29868.7 30004.5 30233.8 30391.4 23668.3 24589.1 24896.5 24973.5 25218.5 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | [#2] jquery.html Cycles:180 Average:37587.33 Median:12208.63 stddev:108194.29 (886.2%) stddev-sans-first:108461.90 13:57:41 INFO - PROCESS | 4734 | Values: 462.8 481.3 485.3 486.3 487.3 19822.8 21654.5 21983.9 22478.3 22644.2 22196.6 22357.2 22642.0 22816.3 22923.8 6586.1 6689.2 6689.5 6725.1 6734.7 6604.2 6724.2 6725.9 7138.1 7442.9 7606.2 7620.3 7625.8 7626.1 7628.3 7381.4 7561.1 7602.2 7609.5 7612.9 7261.9 7406.5 7544.2 7555.4 7563.1 24803.6 25773.2 25788.2 25881.9 25995.0 3119.3 3302.0 3483.8 3582.4 3683.9 16390.1 16460.3 16479.5 16668.7 17349.7 2199.4 2220.7 2220.8 2234.6 2260.9 108889.9 109472.5 110082.4 110084.0 113549.4 16800.0 17698.2 17994.5 18161.6 18647.4 32280.0 32288.0 32375.0 32411.5 32535.3 10953.4 11001.7 11042.3 11152.1 11287.8 9295.3 9376.4 9419.2 9456.9 9625.3 9169.8 9347.7 9370.8 9382.8 9486.3 3756.5 3781.5 3849.5 3869.5 3875.5 977.3 996.2 1166.6 1240.0 1244.4 20765.8 20901.6 20916.0 20987.4 21089.2 1189.2 1228.9 1235.8 1242.3 1249.5 11345.9 11359.5 11366.5 11430.6 11470.4 653834.0 654838.0 658774.0 660171.0 661484.0 90651.5 94571.0 111100.0 119201.4 124094.0 1310.8 1312.0 1354.5 1362.5 1401.5 18688.7 19420.9 19458.9 19461.0 19545.2 46165.1 51999.5 52372.2 52542.7 53280.2 66423.7 66803.3 67112.0 68292.7 68936.7 12144.9 12641.3 12656.6 12773.9 12872.4 13401.4 14744.8 14959.4 15116.5 15188.0 12622.0 12647.1 12736.1 12815.9 12866.9 11195.4 11292.9 11330.9 11422.3 11729.5 12957.6 13051.8 13092.3 13140.3 13427.9 12599.0 12982.0 13017.8 13106.6 13302.5 11914.9 12192.3 12241.6 12655.1 12658.0 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | [#3] mootools.html Cycles:180 Average:7191.48 Median:2311.14 stddev:4643.14 (200.9%) stddev-sans-first:4635.76 13:57:41 INFO - PROCESS | 4734 | Values: 1398.9 1562.0 1579.4 1596.2 1598.0 9441.9 11304.5 11332.3 11625.9 11633.8 11726.5 11773.5 11781.0 11788.2 11807.4 1835.8 2396.4 2429.8 2429.9 2432.0 2061.9 2366.5 2416.6 2419.9 2421.0 2382.8 2383.6 2387.0 2391.0 2391.3 2293.7 2307.1 2310.2 2310.4 2313.5 2284.6 2309.3 2309.4 2311.8 2318.0 11359.5 11371.3 11440.2 11460.3 11658.4 5770.9 5887.6 5889.7 5910.9 5968.3 3242.7 3334.0 3381.9 3389.7 3430.1 1343.4 1401.1 1409.5 1425.7 1464.6 17514.4 17593.0 17621.9 17693.9 17727.8 4698.9 4769.4 4839.8 4891.9 4893.5 13867.4 13940.0 13940.4 13944.4 13997.1 5944.8 5970.2 6010.7 6017.1 6024.6 4480.3 4612.7 5423.9 5495.2 5533.5 3741.6 3833.3 4120.3 4172.5 4216.0 1110.5 1142.7 1155.9 1158.3 1172.1 1042.4 1043.4 1052.1 1054.8 1066.2 7295.2 7301.3 7344.9 7394.8 7561.7 1087.1 1098.7 1102.8 1112.4 1124.1 4587.3 4805.0 4847.6 4863.3 4938.9 13972.7 14410.8 14411.4 14417.6 14482.9 13778.0 14242.6 14248.2 14272.2 14330.4 10572.6 10693.5 10707.1 10732.6 10812.5 7187.7 7434.8 7505.8 7508.4 7598.8 12823.4 13025.3 13120.0 13125.7 13410.7 13124.6 13303.0 13575.4 13657.0 13659.5 9731.3 9841.7 10009.0 10024.4 10027.1 11053.3 11064.5 11093.6 11101.0 11128.1 9293.1 9342.3 9424.8 9475.8 9563.3 9312.8 9389.5 9412.6 9584.1 9646.8 8220.3 8397.2 8467.7 8549.8 8569.0 5918.1 5950.5 5950.8 6111.4 6155.8 6015.8 6127.0 6164.7 6210.3 6297.7 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | [#4] prototype.html Cycles:180 Average:4815.25 Median:5297.24 stddev:1262.92 (23.8%) stddev-sans-first:1222.44 13:57:41 INFO - PROCESS | 4734 | Values: 411.3 430.6 433.0 438.8 438.8 5208.3 5263.2 5270.0 5281.4 5316.6 4949.0 5189.4 5199.5 5224.4 5225.9 4297.6 4308.5 4321.4 4326.6 4329.8 4291.8 4306.6 4335.6 4341.0 4344.2 4334.7 4335.7 4340.6 4370.5 4380.8 4145.7 4256.0 4272.6 4298.0 4302.0 4013.2 4018.3 4023.2 4038.4 4063.8 5031.4 5068.9 5086.7 5127.8 5137.8 4983.8 5065.9 5185.6 5254.6 5282.3 5489.5 5517.4 5526.9 5537.9 5580.9 4846.6 4869.0 4871.5 4881.3 4882.0 5430.3 5475.6 5489.9 5513.5 5708.7 4825.0 4837.8 4838.4 4872.4 4888.5 4953.5 4955.0 4957.7 4959.3 4970.6 4997.7 5098.0 5145.8 5165.2 5167.4 5444.2 5459.5 5467.3 5481.0 5501.0 1679.6 1683.8 1702.7 1706.2 1713.7 1392.5 1403.4 1407.7 1443.3 1469.6 5324.8 5409.3 5420.5 5475.9 5508.6 5504.5 5568.2 5599.0 5641.9 5655.3 5223.6 5404.4 5434.3 5464.8 5465.8 2910.5 2916.9 2937.5 2956.9 2973.5 5749.7 5776.4 5815.7 5852.9 5880.3 5447.2 5710.5 5712.3 5720.2 5747.2 5866.8 5867.6 5872.6 5877.0 5903.7 5492.6 5651.6 5655.0 5660.3 5681.2 5312.2 5500.5 5573.7 5582.4 5607.3 5679.1 5760.4 5802.5 5855.9 5891.3 4712.8 4751.4 4754.7 4757.7 4787.4 5449.8 5634.7 5653.6 5697.8 5732.9 5474.6 5498.6 5513.3 5562.0 5563.9 5508.0 5509.0 5532.6 5551.4 5556.0 5253.6 5447.5 5472.4 5486.7 5489.5 5512.3 5516.8 5578.7 5582.0 5628.8 5340.6 5385.6 5443.2 5491.3 5500.3 13:57:41 INFO - PROCESS | 4734 | 13:57:41 INFO - PROCESS | 4734 | [#5] yui.html Cycles:180 Average:19046.44 Median:4025.13 stddev:23726.11 (589.4%) stddev-sans-first:23753.23 13:57:41 INFO - PROCESS | 4734 | Values: 828.1 844.2 950.3 968.2 984.1 17110.0 18812.5 18923.5 18982.6 19104.7 15734.2 17592.9 17772.6 17776.4 17787.2 881.3 905.2 908.2 908.7 909.1 894.5 895.6 900.1 900.9 901.6 963.4 964.5 965.1 966.0 966.2 955.4 956.2 958.0 958.1 958.5 702.4 705.7 707.9 708.6 708.9 16050.1 16829.9 16841.9 16994.7 16997.3 14666.9 14682.1 14747.3 14974.3 15003.1 12781.9 12787.1 12813.2 12819.6 12995.6 4011.4 4021.6 4028.6 4054.2 4156.9 77389.2 78594.6 78748.6 79534.6 80264.0 10796.5 10904.8 10938.9 11109.8 11162.3 23250.2 23307.9 23588.5 23672.0 23680.4 9285.7 9304.7 9429.1 9432.5 9450.4 5220.4 5240.0 5240.4 5253.7 5362.4 3690.0 3775.2 3775.4 3777.2 3785.2 588.2 595.8 608.2 608.4 610.8 862.3 882.8 892.4 899.9 928.6 11322.9 11382.7 11529.1 11665.9 11823.7 681.5 683.8 694.2 696.6 706.0 5229.1 5338.2 5353.2 5413.0 5420.6 85684.6 86060.0 87429.6 87996.6 88278.0 75738.2 76122.6 76201.6 76599.6 77957.6 68582.7 68598.7 69737.7 70161.0 71852.6 10123.0 10162.7 10260.5 10390.9 10415.8 38334.4 39536.6 39701.8 39773.6 47811.8 47687.5 53579.0 55730.7 56191.0 56467.2 12232.4 12705.1 14540.6 14636.4 14676.4 14926.4 15139.8 15272.7 18366.7 18781.9 15439.8 16717.7 16767.2 17224.1 18146.9 16169.3 16926.9 17413.2 17761.1 17799.9 15880.4 16169.8 17126.5 17613.4 17648.0 11957.8 12160.2 12378.6 12574.4 12783.6 11925.7 12418.4 12782.0 13374.3 13792.8 13:57:41 INFO - PROCESS | 4734 | -------- Summary: end -------- 13:57:41 INFO - PROCESS | 4734 | 13:57:42 INFO - TEST-INFO | 4734: exit 0 13:57:42 INFO - TEST-OK | dromaeo_css | took 1116295ms 13:57:42 INFO - TEST-START | kraken 13:57:42 INFO - operating with platform_type : linux_ 13:57:42 INFO - Initialising browser for kraken test... 13:57:42 INFO - TEST-INFO | started process 5038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiaRl9o/profile http://localhost:59331/getInfo.html) 13:57:46 INFO - PROCESS | 5038 | __metrics Screen width/height:1600/1200 13:57:46 INFO - PROCESS | 5038 | colorDepth:24 13:57:46 INFO - PROCESS | 5038 | Browser inner width/height: 1024/697 13:57:46 INFO - PROCESS | 5038 | __metrics 13:57:47 INFO - PROCESS | 5038 | 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" 13:57:47 INFO - PROCESS | 5038 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:57:47 INFO - PROCESS | 5038 | console.error: PushService: 13:57:47 INFO - PROCESS | 5038 | stateChangeProcessEnqueue: Error transitioning state 13:57:47 INFO - PROCESS | 5038 | UnknownError 13:57:48 INFO - TEST-INFO | 5038: exit 0 13:57:48 INFO - Browser initialized. 13:57:48 INFO - Running cycle 1/1 for kraken test... 13:57:48 INFO - TEST-INFO | started process 5129 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiaRl9o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:58:00 INFO - PROCESS | 5129 | RSS: Main: 141787136 13:58:00 INFO - PROCESS | 5129 | 13:58:02 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59331/tests/kraken/driver.html?testName=audio-beat-detection) 13:58:03 INFO - PROCESS | 5129 | RSS: Main: 184180736 13:58:03 INFO - PROCESS | 5129 | 13:58:05 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59331/tests/kraken/driver.html?testName=audio-dft) 13:58:06 INFO - PROCESS | 5129 | RSS: Main: 174116864 13:58:06 INFO - PROCESS | 5129 | 13:58:09 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59331/tests/kraken/driver.html?testName=audio-fft) 13:58:09 INFO - PROCESS | 5129 | RSS: Main: 265142272 13:58:09 INFO - PROCESS | 5129 | 13:58:11 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59331/tests/kraken/driver.html?testName=audio-oscillator) 13:58:12 INFO - PROCESS | 5129 | RSS: Main: 171626496 13:58:12 INFO - PROCESS | 5129 | 13:58:14 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59331/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:58:15 INFO - PROCESS | 5129 | RSS: Main: 173903872 13:58:15 INFO - PROCESS | 5129 | 13:58:18 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59331/tests/kraken/driver.html?testName=imaging-darkroom) 13:58:18 INFO - PROCESS | 5129 | RSS: Main: 213164032 13:58:18 INFO - PROCESS | 5129 | 13:58:22 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59331/tests/kraken/driver.html?testName=imaging-desaturate) 13:58:22 INFO - PROCESS | 5129 | RSS: Main: 213225472 13:58:22 INFO - PROCESS | 5129 | 13:58:25 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59331/tests/kraken/driver.html?testName=json-parse-financial) 13:58:26 INFO - PROCESS | 5129 | RSS: Main: 211464192 13:58:26 INFO - PROCESS | 5129 | 13:58:27 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59331/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:58:28 INFO - PROCESS | 5129 | RSS: Main: 173920256 13:58:28 INFO - PROCESS | 5129 | 13:58:29 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:58:30 INFO - PROCESS | 5129 | RSS: Main: 180031488 13:58:30 INFO - PROCESS | 5129 | 13:58:32 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:58:33 INFO - PROCESS | 5129 | RSS: Main: 190197760 13:58:33 INFO - PROCESS | 5129 | 13:58:35 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:58:36 INFO - PROCESS | 5129 | RSS: Main: 199905280 13:58:36 INFO - PROCESS | 5129 | 13:58:39 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:58:39 INFO - PROCESS | 5129 | RSS: Main: 195952640 13:58:39 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | Cycle 1(1): loaded http://localhost:59331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59331/tests/kraken/driver.html?testName=ai-astar) 13:58:41 INFO - PROCESS | 5129 | RSS: Main: 248373248 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | __start_tp_report 13:58:41 INFO - PROCESS | 5129 | _x_x_mozilla_page_load 13:58:41 INFO - PROCESS | 5129 | _x_x_mozilla_page_load_details 13:58:41 INFO - PROCESS | 5129 | |i|pagename|runs| 13:58:41 INFO - PROCESS | 5129 | |0;ai-astar;105;114;113;112;114;114;112;113;111;112 13:58:41 INFO - PROCESS | 5129 | |1;audio-beat-detection;138;114;115;161;115;116;117;115;143;115 13:58:41 INFO - PROCESS | 5129 | |2;audio-dft;160;161;160;159;159;160;160;161;160;161 13:58:41 INFO - PROCESS | 5129 | |3;audio-fft;76;75;75;111;75;75;77;75;94;75 13:58:41 INFO - PROCESS | 5129 | |4;audio-oscillator;82;83;107;82;83;82;102;82;83;106 13:58:41 INFO - PROCESS | 5129 | |5;imaging-gaussian-blur;113;113;114;113;113;112;112;114;112;112 13:58:41 INFO - PROCESS | 5129 | |6;imaging-darkroom;145;146;145;145;145;145;145;146;147;145 13:58:41 INFO - PROCESS | 5129 | |7;imaging-desaturate;94;94;94;94;95;95;93;95;94;94 13:58:41 INFO - PROCESS | 5129 | |8;json-parse-financial;57;55;54;55;55;55;64;56;56;55 13:58:41 INFO - PROCESS | 5129 | |9;json-stringify-tinderbox;52;52;52;52;53;53;53;53;53;53 13:58:41 INFO - PROCESS | 5129 | |10;stanford-crypto-aes;75;76;75;75;81;75;81;80;79;75 13:58:41 INFO - PROCESS | 5129 | |11;stanford-crypto-ccm;128;120;139;119;127;125;117;115;160;123 13:58:41 INFO - PROCESS | 5129 | |12;stanford-crypto-pbkdf2;159;158;164;157;156;159;155;170;162;155 13:58:41 INFO - PROCESS | 5129 | |13;stanford-crypto-sha256-iterative;60;60;60;59;59;59;60;63;59;58 13:58:41 INFO - PROCESS | 5129 | __end_tp_report 13:58:41 INFO - PROCESS | 5129 | __start_cc_report 13:58:41 INFO - PROCESS | 5129 | _x_x_mozilla_cycle_collect,1681 13:58:41 INFO - PROCESS | 5129 | __end_cc_report 13:58:41 INFO - PROCESS | 5129 | __startTimestamp1494881921468__endTimestamp 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | ------- Summary: start ------- 13:58:41 INFO - PROCESS | 5129 | Number of tests: 14 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.67 (2.4%) stddev-sans-first:1.09 13:58:41 INFO - PROCESS | 5129 | Values: 105.0 114.0 113.0 112.0 114.0 114.0 112.0 113.0 111.0 112.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:116.50 stddev:16.52 (14.2%) stddev-sans-first:16.82 13:58:41 INFO - PROCESS | 5129 | Values: 138.0 114.0 115.0 161.0 115.0 116.0 117.0 115.0 143.0 115.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 13:58:41 INFO - PROCESS | 5129 | Values: 160.0 161.0 160.0 159.0 159.0 160.0 160.0 161.0 160.0 161.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.14 (16.2%) stddev-sans-first:12.75 13:58:41 INFO - PROCESS | 5129 | Values: 76.0 75.0 75.0 111.0 75.0 75.0 77.0 75.0 94.0 75.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:82.00 stddev:10.98 (13.4%) stddev-sans-first:11.34 13:58:41 INFO - PROCESS | 5129 | Values: 82.0 83.0 107.0 82.0 83.0 82.0 102.0 82.0 83.0 106.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 13:58:41 INFO - PROCESS | 5129 | Values: 113.0 113.0 114.0 113.0 113.0 112.0 112.0 114.0 112.0 112.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 13:58:41 INFO - PROCESS | 5129 | Values: 145.0 146.0 145.0 145.0 145.0 145.0 145.0 146.0 147.0 145.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 13:58:41 INFO - PROCESS | 5129 | Values: 94.0 94.0 94.0 94.0 95.0 95.0 93.0 95.0 94.0 94.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.50 stddev:2.86 (5.2%) stddev-sans-first:3.02 13:58:41 INFO - PROCESS | 5129 | Values: 57.0 55.0 54.0 55.0 55.0 55.0 64.0 56.0 56.0 55.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#9] json-stringify-tinderbox Cycles:10 Average:52.60 Median:53.00 stddev:0.52 (1.0%) stddev-sans-first:0.50 13:58:41 INFO - PROCESS | 5129 | Values: 52.0 52.0 52.0 52.0 53.0 53.0 53.0 53.0 53.0 53.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:77.50 stddev:2.70 (3.5%) stddev-sans-first:2.74 13:58:41 INFO - PROCESS | 5129 | Values: 75.0 76.0 75.0 75.0 81.0 75.0 81.0 80.0 79.0 75.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:126.00 stddev:13.38 (10.6%) stddev-sans-first:14.18 13:58:41 INFO - PROCESS | 5129 | Values: 128.0 120.0 139.0 119.0 127.0 125.0 117.0 115.0 160.0 123.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.50 Median:159.00 stddev:4.70 (3.0%) stddev-sans-first:4.98 13:58:41 INFO - PROCESS | 5129 | Values: 159.0 158.0 164.0 157.0 156.0 159.0 155.0 170.0 162.0 155.0 13:58:41 INFO - PROCESS | 5129 | 13:58:41 INFO - PROCESS | 5129 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.70 Median:60.00 stddev:1.34 (2.2%) stddev-sans-first:1.41 13:58:41 INFO - PROCESS | 5129 | Values: 60.0 60.0 60.0 59.0 59.0 59.0 60.0 63.0 59.0 58.0 13:58:41 INFO - PROCESS | 5129 | -------- Summary: end -------- 13:58:41 INFO - PROCESS | 5129 | 13:58:42 INFO - TEST-INFO | 5129: exit 0 13:58:42 INFO - TEST-OK | kraken | took 60175ms 13:58:42 INFO - SUITE-END | took 1176s 13:58:42 INFO - Completed test suite (00:19:36) 13:58:42 INFO - javascript benchmark 13:58:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7935.921299912858, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3415.5047518109873, 3451.7411194160436, 3474.930501389972, 3496.353174662686, 3516.1561225305936, 5746.798862039829, 5808.477237048664, 5810.622309549876, 5837.503812175964, 5846.88306233875, 5767.913481297784, 5835.183074369581, 5836.649801011942, 5841.474267315941, 5847.0, 5132.281480592711, 5185.818496352628, 5208.947910520884, 5213.973930130344, 5265.842024739264, 5067.49325067494, 5274.92087618686, 5280.894382112356, 5284.497972692588, 5306.92039619406, 5109.974450127763, 5142.7428628568605, 5179.430262671103, 5179.715115668637, 5194.142966410538, 5247.160454327309, 5277.788888444457, 5278.10272253714, 5280.841574752744, 5282.154855223146, 5166.199239117943, 5214.400343960418, 5258.8685282867855, 5275.182346736262, 5310.203469479609, 5573.749181286852, 5616.241807355996, 5716.599838011341, 5781.075027995543, 5851.473367396912, 6253.96873015632, 6272.592281501734, 6276.372362763733, 6279.717412716439, 6284.402981716729, 6316.894543454877, 6326.398992095744, 6328.240611126695, 6359.904601430982, 6365.49076073913, 5622.718864056781, 5704.115862041349, 5802.274715660543, 5856.707164641751, 5902.76388944446, 6424.775132870327, 6429.710663020175, 6443.871122577522, 6446.4520515756685, 6452.193475815523, 6126.754929802758, 6174.166487524127, 6197.4732147767945, 6203.96898015516, 6220.129181914535, 6042.274927008699, 6308.653024083719, 6312.021636646327, 6324.1778568786685, 6332.081848131995, 6115.6330620162935, 6152.723127459275, 6171.166892469549, 6174.0, 6202.81391558254, 5967.343592204854, 5988.431099045583, 5990.281166260077, 5990.7903223386975, 6265.530085243625, 4611.123886461562, 4634.23535116702, 4653.325267836212, 4664.277037059262, 4732.290156476556, 4258.893527661771, 4261.936070958938, 4266.317389217772, 4299.656027517791, 4323.632491238217, 5792.247007888947, 5834.67909264986, 6031.487323577545, 6056.9697151513965, 6079.908801367983, 6173.660448675276, 6198.318184999646, 6273.0, 6284.528660350492, 6297.748090076346, 5929.940700593025, 5933.317668468095, 5934.465898069108, 5942.762289508371, 6029.969850150721, 5301.204819277139, 5315.335583052119, 5315.787368505216, 5323.574114070865, 5324.600654950894, 6204.3795620437595, 6205.162303089115, 6468.54720169593, 6501.05734668461, 6502.0, 6258.311585725475, 6313.273973493107, 6317.747290108344, 6320.747170113144, 6337.746490140527, 6599.8020059398295, 6600.141981542369, 6619.304972977768, 6635.0379195015485, 6660.467162626881, 5941.346451890375, 5976.372480889618, 6126.0, 6140.293866205265, 6151.200343955436, 6275.435210831047, 6287.937120628918, 6295.842603934938, 6301.40136687014, 6305.779297724557, 6052.334243233153, 6101.664408457577, 6115.877682446415, 6122.448979591858, 6124.203853499017, 5843.766249349978, 5889.234399528205, 6068.302145253352, 6117.0, 6139.6009259398, 6067.059605761114, 6076.1493390924425, 6080.118382834553, 6084.756609735561, 6116.480099191441, 6149.046897730859, 6156.384361563987, 6157.784477543264, 6163.9383606163365, 6173.228346456693, 5722.2561067061015, 5752.395998420106, 5764.942350576608, 5861.589688721749, 5912.586118971631], "value": 5732.961549231764, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.8546290741853, 811.326598922894, 825.9050209225938, 886.6453418632547, 898.6809682562695, 31238.0, 44133.558664413344, 44743.0, 44804.32793508092, 44891.42879999205, 42393.36409953853, 42537.0, 42684.35973460393, 48219.0, 48521.51478485214, 7801.414893882953, 7935.880961785578, 9148.634054637823, 9267.165955064043, 9276.628934842598, 10639.893601063968, 10842.0, 10843.891561084367, 10861.40262285574, 10895.0, 11072.723181920475, 11107.500162492666, 11110.555577776879, 11116.888831111706, 11118.166137539676, 10980.066694330992, 10994.28537145083, 11007.06439952605, 11017.944910275477, 11020.228583999164, 10789.406582637952, 10820.513076911559, 10835.729106772314, 10852.511636976355, 10858.945705271502, 43272.783636081935, 43423.0, 43492.0, 43550.56449435497, 43567.12865742667, 4376.3654270130655, 4398.23030969579, 4417.094495628388, 4489.551044895517, 4527.886802829923, 30066.24834379136, 31187.220319491968, 31215.375692486024, 31339.686603134134, 31843.84078079618, 4257.254980378421, 4324.6972711910175, 4397.186520493687, 4419.889502762425, 4509.684322097454, 243635.0, 244617.0, 245096.0, 245814.0, 246438.0, 53936.73031634817, 53953.190702138716, 54097.0, 54105.0, 54223.186652200246, 57108.71445642745, 57228.0, 57351.71324143436, 57420.0, 57493.13760293599, 17237.396691116002, 17521.7371739424, 17529.211185496682, 17576.033318167625, 17629.911850440665, 12726.363681815872, 12934.159279646792, 15002.849971500364, 15529.145896975775, 15538.689226215412, 14268.928655356656, 14314.210723659542, 14355.64110897215, 14409.279536023158, 14461.855381446261, 5553.861153471195, 5568.134674883879, 5599.8600034998635, 5698.971505142447, 5730.426957304319, 1014.4775440648073, 1015.4313584392617, 1020.8417695257247, 1029.444100185906, 1030.4487099401783, 28923.276918076797, 28945.93389166964, 28947.276318092216, 29106.708932910824, 29188.0, 1096.8957948994832, 1108.3073079325422, 1111.1222134513805, 1115.1914861725184, 1122.8428020077197, 14847.925760371128, 15429.459968901034, 15544.922275388777, 15572.922135389477, 15657.138857362954, 308829.0, 309068.0, 309212.0, 310316.0, 311838.0, 55794.44205557973, 55875.720621396635, 55967.440325596224, 55984.0, 56064.15903761447, 1442.0986883198002, 1445.9710805783825, 1467.149053548939, 1468.1851572377434, 1470.6764511807385, 27792.58311125294, 28644.42711145807, 29236.415271694874, 29335.85332073326, 29489.410211796072, 38338.6166138335, 38383.42424863573, 38641.03397415087, 38675.0, 38707.22585548217, 322097.0, 322638.0, 322768.0, 324795.0, 451983.0, 25153.0, 25587.0, 29117.417651646425, 29629.0, 31753.0, 40393.39409908795, 40439.0, 40476.59523404846, 40573.0, 41613.37579936364, 31915.202119947186, 32050.0, 32187.0, 32319.0, 32564.185895352806, 28053.71946280511, 28796.0, 28963.85518072396, 29090.27274318159, 29164.7083529162, 35450.82274588611, 35506.82246588854, 36170.819145904105, 36309.45535816912, 36535.26929461449, 29789.5531567031, 29868.70131298659, 30004.549931750604, 30233.848830756586, 30391.392172156877, 23668.28995130149, 24589.13938012161, 24896.50206995886, 24973.500529988934, 25218.495630087662], "value": 19019.64667885459, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.78943080898193, 481.3093211241868, 485.2842057964502, 486.3264378835314, 487.29099160721154, 19822.801771982275, 21654.458638534044, 21983.890080549616, 22478.325650230498, 22644.207452739156, 22196.556068878654, 22357.217497387595, 22642.0, 22816.31551053471, 22923.770762292374, 6586.110875031881, 6689.230738465078, 6689.531732778707, 6725.05849181115, 6734.730610775563, 6604.174478190226, 6724.226713927887, 6725.899111513307, 7138.072050633436, 7442.925570744304, 7606.163322034572, 7620.2760737730105, 7625.8474830503355, 7626.08486981563, 7628.275313845176, 7381.372583330423, 7561.130469995938, 7602.2017688142505, 7609.505382150171, 7612.885806712904, 7261.854762904765, 7406.518576292524, 7544.207858174867, 7555.433342499279, 7563.0924289085115, 24803.62794558083, 25773.226803195936, 25788.226353209433, 25881.870590647115, 25995.0, 3119.266972261517, 3302.0424077017638, 3483.7909725416303, 3582.444721068239, 3683.9447408288897, 16390.098544580043, 16460.259288332054, 16479.505614831574, 16668.74996875048, 17349.653006939916, 2199.3841724317217, 2220.7113075300267, 2220.7863213877495, 2234.620114580509, 2260.8756518391483, 108889.91110088877, 109472.4526377371, 110082.44958775234, 110084.0, 113549.4322528382, 16800.0, 17698.203580838635, 17994.460166195036, 18161.63676726458, 18647.44057678272, 32280.0, 32288.0, 32375.0, 32411.51382729261, 32535.349293014006, 10953.397563134104, 11001.669949901516, 11042.282251653616, 11152.052075573505, 11287.774244515227, 9295.302852286106, 9376.390534615228, 9419.246460283299, 9456.8581471278, 9625.32622716403, 9169.770755731026, 9347.719568412958, 9370.765730856647, 9382.765430864147, 9486.335956483117, 3756.492873462102, 3781.546214454283, 3849.5188101487315, 3869.5356557213313, 3875.4768106305287, 977.3256453047375, 996.2179688944144, 1166.5916929074756, 1239.9504019839285, 1244.440001999104, 20765.792342076387, 20901.581968360548, 20916.0, 20987.370378888354, 21089.156433742784, 1189.167582692119, 1228.8955438787625, 1235.765204611121, 1242.2670624331506, 1249.463588914505, 11345.943270283597, 11359.545618175345, 11366.545338186545, 11430.599929002612, 11470.426478676032, 653834.0, 654838.0, 658774.0, 660171.0, 661484.0, 90651.54674226587, 94571.0, 111100.0, 119201.40399297948, 124094.0, 1310.8164856920039, 1311.9934400327936, 1354.5056054540023, 1362.4618275781208, 1401.53048728675, 18688.7196692047, 19420.902895485433, 19458.902705486948, 19461.0, 19545.21819127219, 46165.07669846652, 51999.480005200974, 52372.21441678454, 52542.73728631332, 53280.20079698885, 66423.66788166222, 66803.33196667972, 67112.0, 68292.658536707, 68936.65531672309, 12144.939275303566, 12641.304728240035, 12656.557020504606, 12773.936130319289, 12872.35638218104, 13401.396937138048, 14744.778828317369, 14959.40162393492, 15116.470923517623, 15188.0, 12622.0, 12647.051471139517, 12736.108472406842, 12815.935920320338, 12866.935665321613, 11195.44022798873, 11292.887071129184, 11330.94334528322, 11422.31466112036, 11729.530818767154, 12957.611271661865, 13051.804222936473, 13092.279924603858, 13140.277284749049, 13427.865721342661, 12599.0, 12982.0, 13017.804732928824, 13106.60680179596, 13302.467901284228, 11914.880851191378, 12192.329421881881, 12241.571544995883, 12655.11414200997, 12657.98736101128], "value": 11842.303883834005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.930053497325, 1561.9921900390495, 1579.4314046943098, 1596.241785152053, 1598.0172194100633, 9441.905580944198, 11304.491297891604, 11332.263402878807, 11625.941870290659, 11633.767324653501, 11726.472308746106, 11773.470193841276, 11781.0, 11788.233764805282, 11807.350595717233, 1835.7705286839146, 2396.436837343223, 2429.769171928664, 2429.866357350345, 2431.9756802431925, 2061.865978711379, 2366.4557151855083, 2416.5770990076753, 2419.8669073200967, 2421.0436877433403, 2382.845115067515, 2383.5947888858936, 2386.95226095478, 2391.0196819304088, 2391.2945681024053, 2293.655951607272, 2307.065638416444, 2310.1567927706433, 2310.445493081665, 2313.525727225923, 2284.6344584866342, 2309.2841219222096, 2309.3533810533077, 2311.838171328008, 2317.9652305215436, 11359.488823002985, 11371.260868043551, 11440.19918605698, 11460.255083419552, 11658.358790266531, 5770.884582308372, 5887.646741195499, 5889.734961926723, 5910.852228694274, 5968.343482216953, 3242.659520750311, 3333.984947707951, 3381.8647254109806, 3389.661033896615, 3430.142464383904, 1343.40890008396, 1401.061288936415, 1409.478492957602, 1425.6934759026858, 1464.5606318104526, 17514.38699645506, 17593.0, 17621.911890440464, 17693.911530442394, 17727.822721772875, 4698.878857029495, 4769.42766867978, 4839.758012099381, 4891.902161956741, 4893.510648935078, 13867.37596808146, 13940.0, 13940.372683229076, 13944.372503237379, 13997.090189137878, 5944.791932282349, 5970.194023806817, 6010.729517171738, 6017.097435384728, 6024.57827952048, 4480.283154695233, 4612.74629895359, 5423.945760542423, 5495.203195536705, 5533.474319939599, 3741.6071312511795, 3833.3483307837178, 4120.340745480708, 4172.520160181557, 4215.957840421618, 1110.4503270880966, 1142.7428828513684, 1155.9075273978062, 1158.25871442277, 1172.0623501199007, 1042.4006196437078, 1043.400044974143, 1052.0899421999916, 1054.8154073037342, 1066.1737153705785, 7295.234000429985, 7301.269873012656, 7344.926550734531, 7394.815129621803, 7561.735339263099, 1087.0977089016098, 1098.6594155811724, 1102.7739313440725, 1112.4437781109445, 1124.1231839165464, 4587.266037433995, 4805.014971930746, 4847.587955023792, 4863.270509423544, 4938.8765280868265, 13972.72054558903, 14410.783838242434, 14411.42354305837, 14417.567472975827, 14482.855171448362, 13778.035537512478, 14242.643933901529, 14248.216348100746, 14272.215028173343, 14330.355134018993, 10572.577096916037, 10693.465326733787, 10707.1434285257, 10732.570697172181, 10812.459377031117, 7187.748428804966, 7434.7769566913075, 7505.812354691177, 7508.361789247975, 7598.772036838904, 12823.422945967268, 13025.348732563145, 13120.0, 13125.671858203621, 13410.731785364433, 13124.606261812161, 13303.0, 13575.389107490384, 13657.044006919421, 13659.453621855411, 9731.318807683394, 9841.704748857546, 10009.0, 10024.448655324028, 10027.147692446224, 11053.28153670009, 11064.502097405475, 11093.5562577496, 11101.0, 11128.109751219721, 9293.070692930653, 9342.346035777702, 9424.764380890532, 9475.763105922408, 9563.282753793354, 9312.813743725223, 9389.483578403253, 9412.623495060121, 9584.089511496675, 9646.807063858543, 8220.260176584135, 8397.244248017707, 8467.703630372907, 8549.829003419773, 8568.957155214184, 5918.053111502139, 5950.494207992196, 5950.7917222896995, 6111.449969502766, 6155.846103847261, 6015.759369625166, 6127.0, 6164.6917654118015, 6210.285817131088, 6297.685115744102], "value": 5420.930537231346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.33774622857214, 430.61029768059905, 433.01436594679683, 438.7630679433106, 438.8485972339542, 5208.28126718183, 5263.1578947368425, 5270.025045366609, 5281.419043905177, 5316.574674026078, 4948.99565675901, 5189.377274727038, 5199.506046925546, 5224.399194092681, 5225.921611175835, 4297.634701050414, 4308.482982042144, 4321.416608757817, 4326.6106050455455, 4329.848455304065, 4291.785410729467, 4306.643407326697, 4335.5881191286935, 4340.9565904341025, 4344.174606824709, 4334.696571240014, 4335.6748243881675, 4340.609345158935, 4370.508809978313, 4380.759058251795, 4145.7098003139645, 4256.020785450166, 4272.636825869805, 4298.0, 4301.956980430218, 4013.2174226025936, 4018.3369743992207, 4023.2355852387955, 4038.3942408638645, 4063.8374465021366, 5031.421386540522, 5068.949310506885, 5086.745662716887, 5127.7948882044675, 5137.8201762938115, 4983.800647974077, 5065.885505188852, 5185.611079169077, 5254.553362964152, 5282.313299271109, 5489.451054894518, 5517.393086760493, 5526.861828454281, 5537.889242215133, 5580.86047848803, 4846.61227101831, 4868.951310486886, 4871.512848715136, 4881.316615673809, 4881.951180488185, 5430.348358197002, 5475.589330800166, 5489.890202195974, 5513.49136143054, 5708.743106560215, 4825.0, 4837.782299796518, 4838.443578988461, 4872.439669437989, 4888.50686150066, 4953.529414705597, 4954.9752251238515, 4957.652964292465, 4959.330490383824, 4970.627202959792, 4997.650164488524, 5097.9745101275, 5145.794168233304, 5165.173572228426, 5167.431582525919, 5444.183372494158, 5459.508644222039, 5467.261919640877, 5481.0, 5501.0, 1679.5717092141426, 1683.8316168383308, 1702.7105392083377, 1706.234047760562, 1713.6658351621313, 1392.5100142847057, 1403.3510625015565, 1407.6691977385306, 1443.2711480702387, 1469.5517867050653, 5324.8136315228785, 5409.29679141709, 5420.4850539198715, 5475.890482190413, 5508.614396992252, 5504.4770746778995, 5568.248286481355, 5598.972005139948, 5641.858953526113, 5655.264815573949, 5223.5559977402345, 5404.432534583891, 5434.2935418395355, 5464.836054918359, 5465.808696695596, 2910.54886492594, 2916.912492625225, 2937.515309973873, 2956.8669409876607, 2973.539101439237, 5749.741261643236, 5776.393478684761, 5815.651060936358, 5852.94147058524, 5880.323762767251, 5447.182922561568, 5710.543156547466, 5712.285964254468, 5720.227769251181, 5747.195392645033, 5866.767978724431, 5867.559933005127, 5872.647641141545, 5877.0, 5903.7048147593305, 5492.588055895903, 5651.632643878134, 5654.971725141348, 5660.330202590329, 5681.204631351531, 5312.1766126250495, 5500.50495455395, 5573.749181286771, 5582.413846546055, 5607.327120745374, 5679.09989362926, 5760.423957604374, 5802.506786923159, 5855.853603659943, 5891.293044834647, 4712.7643617818285, 4751.40607424072, 4754.738489383117, 4757.714537127784, 4787.40157480315, 5449.754761035842, 5634.718264086698, 5653.632513886584, 5697.772089116389, 5732.8534293142375, 5474.633100643156, 5498.618349714977, 5513.3108361454815, 5562.0, 5563.916541251965, 5508.008558459515, 5509.0, 5532.585056120726, 5551.389347171888, 5555.972220138873, 5253.553447956813, 5447.53695935834, 5472.413000230048, 5486.698231597141, 5489.533389661923, 5512.283403157564, 5516.779328826802, 5578.652808314979, 5581.972090139523, 5628.774849006158, 5340.626156169031, 5385.6230063895155, 5443.210734443563, 5491.28613280287, 5500.257465242301], "value": 4457.93441003758, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.101644245142, 844.2106630300666, 950.2635457520421, 968.1673760565916, 984.0553069053706, 17110.0, 18812.529686757836, 18923.52691182721, 18982.620347593074, 19104.713429298554, 15734.213289335516, 17592.912035439804, 17772.64454710905, 17776.377826776064, 17787.199576019077, 881.3257857738851, 905.2350763604743, 908.2280061947325, 908.6637943960744, 909.0818273543736, 894.4990805149105, 895.5790778334173, 900.0999000999001, 900.9369344145913, 901.6078006067349, 963.4123184857232, 964.4599024546242, 965.1120968708805, 965.9951700241489, 966.2077096780642, 955.4171955107379, 956.2302346610975, 958.0180315176965, 958.0611001218809, 958.4872093430051, 702.371377617035, 705.7459314646723, 707.8584283143392, 708.5996412027188, 708.8794904866185, 16050.1172435516, 16829.91585042079, 16841.91579042109, 16994.745078823827, 16997.320107195697, 14666.853331466655, 14682.119072855663, 14747.262636868221, 14974.326155322928, 15003.099814011086, 12781.936090319581, 12787.104902656818, 12813.167144135601, 12819.615411537668, 12995.610131696065, 4011.3581826907553, 4021.638052575253, 4028.617281358266, 4054.2296963576828, 4156.91686166278, 77389.22610773877, 78594.60702696507, 78748.60625696834, 79534.602326988, 80264.0, 10796.460176991119, 10904.781904362027, 10938.890611093946, 11109.777804444027, 11162.274452160584, 23250.186243481396, 23307.88346058293, 23588.528229435316, 23672.0, 23680.40798980039, 9285.72142835716, 9304.674336398193, 9429.057094290516, 9432.481213533321, 9450.43297402158, 5220.425753167148, 5240.0, 5240.371155461293, 5253.711045892436, 5362.410134885159, 3689.981550092286, 3775.244951009809, 3775.3581891078584, 3777.244551089793, 3785.205106927521, 588.1677426441623, 595.7616953218748, 608.1546650156287, 608.3794529579803, 610.7709608896664, 862.3403096631082, 882.761654353321, 892.3981453291358, 899.9190072893471, 928.5867788834039, 11322.92432218938, 11382.658520244406, 11529.135314851419, 11665.941670291764, 11823.704407389883, 681.4752640466813, 683.7606837606797, 694.2190036209265, 696.5820507695444, 706.0, 5229.111051121318, 5338.225957236179, 5353.2237825515085, 5412.972935135299, 5420.620556561003, 85684.57157714172, 86060.0, 87429.5628521866, 87996.5600171995, 88278.0, 75738.24261757421, 76122.6193869027, 76201.61899190469, 76599.61700191464, 77957.61021194857, 68582.65708671525, 68598.65700671465, 69737.65131174312, 70161.0, 71852.64073679702, 10123.0, 10162.745931351776, 10260.538275777459, 10390.948045259725, 10415.791684166426, 38334.424983625824, 39536.60463395329, 39701.801490993515, 39773.60226397699, 47811.760941196466, 47687.52312476831, 53579.0, 55730.72134639301, 56191.0, 56467.15299270432, 12232.449539770909, 12705.110642254953, 14540.63648408756, 14636.414543418145, 14676.412943482568, 14926.402943882124, 15139.772903406678, 15272.694546109238, 18366.667494844485, 18781.90609046946, 15439.84560154384, 16717.66564668724, 16767.1616419181, 17224.138793060047, 18146.90926545359, 16169.27238274304, 16926.915365423094, 17413.216405262046, 17761.111944402986, 17799.911000445434, 15880.444184453489, 16169.838301616834, 17126.48620541336, 17613.38353157633, 17648.0, 11957.820632690342, 12160.209586376857, 12378.62864114078, 12574.371281436075, 12783.552575659807, 11925.701857453632, 12418.379081046092, 12782.0, 13374.318988353998, 13792.793108103186], "value": 8004.242328691232, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1451.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 113.0, 112.0, 114.0, 114.0, 112.0, 113.0, 111.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 114.0, 115.0, 161.0, 115.0, 116.0, 117.0, 115.0, 143.0, 115.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 159.0, 159.0, 160.0, 160.0, 161.0, 160.0, 161.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 111.0, 75.0, 75.0, 77.0, 75.0, 94.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 107.0, 82.0, 83.0, 82.0, 102.0, 82.0, 83.0, 106.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 112.0, 112.0, 114.0, 112.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 147.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 93.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 54.0, 55.0, 55.0, 55.0, 64.0, 56.0, 56.0, 55.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 53.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 52.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 75.0, 81.0, 75.0, 81.0, 80.0, 79.0, 75.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 120.0, 139.0, 119.0, 127.0, 125.0, 117.0, 115.0, 160.0, 123.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 164.0, 157.0, 156.0, 159.0, 155.0, 170.0, 162.0, 155.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 59.0, 59.0, 60.0, 63.0, 59.0, 58.0], "value": 59.7, "unit": "score"}]}]} 13:58:42 INFO - Return code: 0 13:58:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:58:42 INFO - # TBPL SUCCESS # 13:58:42 INFO - Running post-action listener: _resource_record_post_action 13:58:42 INFO - [mozharness: 2017-05-15 20:58:42.779369Z] Finished run-tests step (success) 13:58:42 INFO - Running post-run listener: _resource_record_post_run 13:58:42 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 327802880; Read time: 184; Write time: 192680 13:58:42 INFO - TinderboxPrint: CPU usage
13.1% 13:58:42 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 184 13:58:42 INFO - TinderboxPrint: I/O write bytes / time
327,802,880 / 192,680 13:58:42 INFO - TinderboxPrint: CPU idle
8,263.8 (86.9%) 13:58:42 INFO - TinderboxPrint: CPU user
1,197.4 (12.6%) 13:58:42 INFO - TinderboxPrint: Swap in / out
0 / 0 13:58:42 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 471040; Read time: 0; Write time: 44 13:58:42 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 316100608; Read time: 132; Write time: 191956 13:58:43 INFO - Running post-run listener: _upload_blobber_files 13:58:43 INFO - Blob upload gear active. 13:58:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:58:43 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 13:58:43 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'] 13:58:43 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 13:58:43 INFO - (blobuploader) - INFO - Open directory for files ... 13:58:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:58:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:58:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:44 INFO - (blobuploader) - INFO - Done attempting. 13:58:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:58:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:58:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:47 INFO - (blobuploader) - INFO - Done attempting. 13:58:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:58:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:58:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:48 INFO - (blobuploader) - INFO - Done attempting. 13:58:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:58:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:58:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:58:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:58:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:58:49 INFO - (blobuploader) - INFO - Done attempting. 13:58:49 INFO - (blobuploader) - INFO - Iteration through files over. 13:58:49 INFO - Return code: 0 13:58:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:58:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:58:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c240189a5f2f0d8aca5b98608951bfb31a8bcd9014e8b4a76d58849613cf4c777d8e3b02807c03329c457a7db76edca8a4f5a7fceabf0a7df70efd66f88ec80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9ef1ed1179ab893c489f727133da1124786b4502ccb5dd3da73edd51dd4dd4347a79d387438e02fe267b5519c9da206b3f43b4eb216ffb119639446de4b3db7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ec42336428e488914b41cea688fbf7224468f14af31c835706c9f7a6d08359bd28cb01a6645a71ebb29f536673ff3f6fd29cffbf61f70f29da376247ea9f4a85", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc374075037220863f66f38e97e8d3307bce65509ea2b6535c37a6feb3d4f166016acc50f6d8ea68db3d804fa8302a3b130a46970cf72a6662fc13e2036378b5"} 13:58:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:58:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:58:49 INFO - Contents: 13:58:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c240189a5f2f0d8aca5b98608951bfb31a8bcd9014e8b4a76d58849613cf4c777d8e3b02807c03329c457a7db76edca8a4f5a7fceabf0a7df70efd66f88ec80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9ef1ed1179ab893c489f727133da1124786b4502ccb5dd3da73edd51dd4dd4347a79d387438e02fe267b5519c9da206b3f43b4eb216ffb119639446de4b3db7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ec42336428e488914b41cea688fbf7224468f14af31c835706c9f7a6d08359bd28cb01a6645a71ebb29f536673ff3f6fd29cffbf61f70f29da376247ea9f4a85", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc374075037220863f66f38e97e8d3307bce65509ea2b6535c37a6feb3d4f166016acc50f6d8ea68db3d804fa8302a3b130a46970cf72a6662fc13e2036378b5"} 13:58:49 INFO - Running post-run listener: copy_logs_to_upload_dir 13:58:49 INFO - Copying logs to upload dir... 13:58:49 INFO - mkdir: /builds/slave/test/build/upload/logs 13:58:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.051327 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-05-15 13:58:48.904593) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 13:58:48.911630) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1494878244.585531-499719470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c240189a5f2f0d8aca5b98608951bfb31a8bcd9014e8b4a76d58849613cf4c777d8e3b02807c03329c457a7db76edca8a4f5a7fceabf0a7df70efd66f88ec80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9ef1ed1179ab893c489f727133da1124786b4502ccb5dd3da73edd51dd4dd4347a79d387438e02fe267b5519c9da206b3f43b4eb216ffb119639446de4b3db7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ec42336428e488914b41cea688fbf7224468f14af31c835706c9f7a6d08359bd28cb01a6645a71ebb29f536673ff3f6fd29cffbf61f70f29da376247ea9f4a85", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc374075037220863f66f38e97e8d3307bce65509ea2b6535c37a6feb3d4f166016acc50f6d8ea68db3d804fa8302a3b130a46970cf72a6662fc13e2036378b5"} build_url:https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011357 build_url: 'https://queue.taskcluster.net/v1/task/A9i1TindSjaG0vI0rRD8Tg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c240189a5f2f0d8aca5b98608951bfb31a8bcd9014e8b4a76d58849613cf4c777d8e3b02807c03329c457a7db76edca8a4f5a7fceabf0a7df70efd66f88ec80d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9ef1ed1179ab893c489f727133da1124786b4502ccb5dd3da73edd51dd4dd4347a79d387438e02fe267b5519c9da206b3f43b4eb216ffb119639446de4b3db7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/ec42336428e488914b41cea688fbf7224468f14af31c835706c9f7a6d08359bd28cb01a6645a71ebb29f536673ff3f6fd29cffbf61f70f29da376247ea9f4a85", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc374075037220863f66f38e97e8d3307bce65509ea2b6535c37a6feb3d4f166016acc50f6d8ea68db3d804fa8302a3b130a46970cf72a6662fc13e2036378b5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 13:58:48.937863) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:58:48.938201) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1494878244.585531-499719470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:58:49.018991) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 13:58:49.021349) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 13:58:49.021672) ========= ========= Total master_lag: 0.29 =========