builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-077 starttime: 1495571994.65 results: success (0) revision: e161af4fc095a433ca4b37c39f25406438e96aa9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.646497) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.646954) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.647254) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.662510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.662764) ========= 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=20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-23 13:39:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-05-23 13:39:54 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131382 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.807019) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.810351) ========= 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=20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019788 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.859986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:54.863344) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e161af4fc095a433ca4b37c39f25406438e96aa9 --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 e161af4fc095a433ca4b37c39f25406438e96aa9 --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=20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-23 13:39:54,910 truncating revision to first 12 chars 2017-05-23 13:39:54,910 Setting DEBUG logging. 2017-05-23 13:39:54,910 attempt 1/10 2017-05-23 13:39:54,910 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e161af4fc095?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 13:39:55,230 unpacking tar archive at: mozilla-beta-e161af4fc095/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631730 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:55.507165) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:55.510517) ========= script_repo_revision: e161af4fc095a433ca4b37c39f25406438e96aa9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:55.510882) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:55.511143) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 13:39:55.520757) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-23 13:39:55.521040) ========= /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=20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:39:55 INFO - MultiFileLogger online at 20170523 13:39:55 in /builds/slave/test 13:39:55 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:39:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:39:55 INFO - {'append_to_log': False, 13:39:55 INFO - 'base_work_dir': '/builds/slave/test', 13:39:55 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 13:39:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:39:55 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 13:39:55 INFO - 'buildbot_json_path': 'buildprops.json', 13:39:55 INFO - 'config_files': ('talos/linux_config.py',), 13:39:55 INFO - 'default_actions': ('clobber', 13:39:55 INFO - 'read-buildbot-config', 13:39:55 INFO - 'download-and-extract', 13:39:55 INFO - 'populate-webroot', 13:39:55 INFO - 'create-virtualenv', 13:39:55 INFO - 'install', 13:39:55 INFO - 'run-tests'), 13:39:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:39:55 INFO - 'download_minidump_stackwalk': True, 13:39:55 INFO - 'download_symbols': 'ondemand', 13:39:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:39:55 INFO - 'tooltool.py': '/tools/tooltool.py', 13:39:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:39:55 INFO - '/tools/misc-python/virtualenv.py')}, 13:39:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:39:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:39:55 INFO - 'gecko_profile': False, 13:39:55 INFO - 'gecko_profile_interval': 0, 13:39:55 INFO - 'installer_path': 'installer.exe', 13:39:55 INFO - 'log_level': 'info', 13:39:55 INFO - 'log_name': 'talos', 13:39:55 INFO - 'log_to_console': True, 13:39:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:39:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:39:55 INFO - 'opt_config_files': (), 13:39:55 INFO - 'pip_index': False, 13:39:55 INFO - 'suite': 'dromaeojs', 13:39:55 INFO - 'system_bits': '32', 13:39:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:39:55 INFO - 'title': 'talos-linux64-ix-077', 13:39:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:39:55 INFO - 'use_talos_json': True, 13:39:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:39:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:39:55 INFO - 'work_dir': 'build'} 13:39:55 INFO - [mozharness: 2017-05-23 20:39:55.669242Z] Running clobber step. 13:39:55 INFO - Running pre-action listener: _resource_record_pre_action 13:39:55 INFO - Running main action method: clobber 13:39:55 INFO - rmtree: /builds/slave/test/build 13:39:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:39:57 INFO - Running post-action listener: _resource_record_post_action 13:39:57 INFO - [mozharness: 2017-05-23 20:39:57.261435Z] Finished clobber step (success) 13:39:57 INFO - [mozharness: 2017-05-23 20:39:57.261831Z] Running read-buildbot-config step. 13:39:57 INFO - Running pre-action listener: _resource_record_pre_action 13:39:57 INFO - Running main action method: read_buildbot_config 13:39:57 INFO - Using buildbot properties: 13:39:57 INFO - { 13:39:57 INFO - "project": "", 13:39:57 INFO - "product": "firefox", 13:39:57 INFO - "who": "raliiev@mozilla.com", 13:39:57 INFO - "installer_path": "public/build/target.tar.bz2", 13:39:57 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:39:57 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 13:39:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 13:39:57 INFO - "stage_platform": "linux64", 13:39:57 INFO - "basedir": "/builds/slave/test", 13:39:57 INFO - "buildnumber": 55, 13:39:57 INFO - "platform": "ubuntu64_hw", 13:39:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:39:57 INFO - "slavebuilddir": "test", 13:39:57 INFO - "taskId": "eKwviA7ST6GV415rnZPWDQ", 13:39:57 INFO - "branch": "mozilla-beta", 13:39:57 INFO - "script_repo_revision": "production", 13:39:57 INFO - "revision": "e161af4fc095a433ca4b37c39f25406438e96aa9", 13:39:57 INFO - "slavename": "talos-linux64-ix-077", 13:39:57 INFO - "repo_path": "releases/mozilla-beta" 13:39:57 INFO - } 13:39:57 INFO - Finding installer, test and symbols from parent task. 13:39:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKwviA7ST6GV415rnZPWDQ'}, attempt #1 13:39:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKwviA7ST6GV415rnZPWDQ'}, attempt #1 13:39:58 INFO - Task dependencies: AMwfbFrDQXyt7imB1yMX6Q 13:39:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q'}, attempt #1 13:39:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2 13:39:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/test_packages.json 13:39:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.crashreporter-symbols.zip 13:39:58 INFO - Running post-action listener: _resource_record_post_action 13:39:58 INFO - [mozharness: 2017-05-23 20:39:58.619290Z] Finished read-buildbot-config step (success) 13:39:58 INFO - [mozharness: 2017-05-23 20:39:58.619506Z] Running download-and-extract step. 13:39:58 INFO - Running pre-action listener: _resource_record_pre_action 13:39:58 INFO - Running main action method: download_and_extract 13:39:58 INFO - mkdir: /builds/slave/test/build/tests 13:39:58 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:58 INFO - trying https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.test_packages.json 13:39:58 INFO - Downloading https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:39:59 INFO - Downloaded 986 bytes. 13:39:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:39:59 INFO - Using the following test package requirements: 13:39:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:39:59 INFO - u'common': [u'target.common.tests.zip'], 13:39:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:39:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:39:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:39:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:39:59 INFO - u'mozbase': [u'target.common.tests.zip'], 13:39:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:39:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:39:59 INFO - u'web-platform': [u'target.common.tests.zip', 13:39:59 INFO - u'target.web-platform.tests.zip'], 13:39:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:39:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:39:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.common.tests.zip 13:39:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:39:59 INFO - Fetch https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.common.tests.zip into memory 13:40:00 INFO - Expected file size: 59712301 13:40:00 INFO - Obtained file size: 59712301 13:40:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:40:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.common.tests.zip 13:40:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:40:10 INFO - Fetch https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.common.tests.zip into memory 13:40:11 INFO - Expected file size: 59712301 13:40:11 INFO - Obtained file size: 59712301 13:40:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.talos.tests.zip 13:40:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:40:21 INFO - Fetch https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.talos.tests.zip into memory 13:40:22 INFO - Expected file size: 14404449 13:40:22 INFO - Obtained file size: 14404449 13:40:22 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:40:22 INFO - trying https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2 13:40:22 INFO - Downloading https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:40:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:40:23 INFO - Downloaded 59729640 bytes. 13:40:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2 13:40:23 INFO - mkdir: /builds/slave/test/properties 13:40:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:40:23 INFO - Writing to file /builds/slave/test/properties/build_url 13:40:23 INFO - Contents: 13:40:23 INFO - build_url:https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2 13:40:23 INFO - Running post-action listener: _resource_record_post_action 13:40:23 INFO - Running post-action listener: set_extra_try_arguments 13:40:23 INFO - [mozharness: 2017-05-23 20:40:23.994986Z] Finished download-and-extract step (success) 13:40:23 INFO - [mozharness: 2017-05-23 20:40:23.995083Z] Running populate-webroot step. 13:40:23 INFO - Running pre-action listener: _resource_record_pre_action 13:40:23 INFO - Running main action method: populate_webroot 13:40:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:40:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:40:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:40:24 INFO - '--tppagecycles', 13:40:24 INFO - '7'], 13:40:24 INFO - 'tests': ['tsvgm']}}, 13:40:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:40:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:40:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:40:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:40:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:40:24 INFO - 'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:40:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:40:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:40:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:40:24 INFO - 'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['damp', 'tps']}, 13:40:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:40:24 INFO - 'tests': ['damp', 'tps']}, 13:40:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['dromaeo_dom']}, 13:40:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:40:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['basic_compositor_video']}, 13:40:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:40:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['a11yr', 13:40:24 INFO - 'ts_paint', 13:40:24 INFO - 'tpaint', 13:40:24 INFO - 'sessionrestore', 13:40:24 INFO - 'sessionrestore_no_auto_restore', 13:40:24 INFO - 'tabpaint']}, 13:40:24 INFO - 'other-e10s': {'tests': ['a11yr', 13:40:24 INFO - 'ts_paint', 13:40:24 INFO - 'tpaint', 13:40:24 INFO - 'sessionrestore', 13:40:24 INFO - 'sessionrestore_no_auto_restore', 13:40:24 INFO - 'tabpaint']}, 13:40:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:40:24 INFO - 'ts_paint', 13:40:24 INFO - 'tpaint', 13:40:24 INFO - 'sessionrestore', 13:40:24 INFO - 'sessionrestore_no_auto_restore', 13:40:24 INFO - 'tabpaint']}, 13:40:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:40:24 INFO - 'ts_paint', 13:40:24 INFO - 'tpaint', 13:40:24 INFO - 'sessionrestore', 13:40:24 INFO - 'sessionrestore_no_auto_restore', 13:40:24 INFO - 'tabpaint']}, 13:40:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['a11yr', 13:40:24 INFO - 'ts_paint', 13:40:24 INFO - 'tpaint', 13:40:24 INFO - 'sessionrestore', 13:40:24 INFO - 'sessionrestore_no_auto_restore', 13:40:24 INFO - 'tabpaint']}, 13:40:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['a11yr', 13:40:24 INFO - 'ts_paint', 13:40:24 INFO - 'tpaint', 13:40:24 INFO - 'sessionrestore', 13:40:24 INFO - 'sessionrestore_no_auto_restore', 13:40:24 INFO - 'tabpaint']}, 13:40:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['tsvgx', 13:40:24 INFO - 'tsvgr_opacity', 13:40:24 INFO - 'tart', 13:40:24 INFO - 'tscrollx', 13:40:24 INFO - 'cart', 13:40:24 INFO - 'tsvg_static']}, 13:40:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:40:24 INFO - 'tsvgr_opacity', 13:40:24 INFO - 'tart', 13:40:24 INFO - 'tscrollx', 13:40:24 INFO - 'cart', 13:40:24 INFO - 'tsvg_static']}, 13:40:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:40:24 INFO - 'talos_options': ['--disable-e10s'], 13:40:24 INFO - 'tests': ['tp5o']}, 13:40:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:40:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:40:24 INFO - 'talos_options': ['--disable-e10s', 13:40:24 INFO - '--xperf_path', 13:40:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:40:24 INFO - 'tests': ['tp5n']}, 13:40:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:40:24 INFO - 'talos_options': ['--xperf_path', 13:40:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:40:24 INFO - 'tests': ['tp5n']}}, 13:40:24 INFO - 'talos.zip': {'path': '', 13:40:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:40:24 INFO - Running post-action listener: _resource_record_post_action 13:40:24 INFO - [mozharness: 2017-05-23 20:40:24.008081Z] Finished populate-webroot step (success) 13:40:24 INFO - [mozharness: 2017-05-23 20:40:24.008170Z] Running create-virtualenv step. 13:40:24 INFO - Running pre-action listener: _resource_record_pre_action 13:40:24 INFO - Running main action method: create_virtualenv 13:40:24 INFO - Creating virtualenv /builds/slave/test/build/venv 13:40:24 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:40:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:40:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:40:24 INFO - Using real prefix '/usr' 13:40:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:40:24 INFO - Installing distribute.............................................................................................................................................................................................done. 13:40:26 INFO - Installing pip.................done. 13:40:26 INFO - Return code: 0 13:40:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:40:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:40:26 INFO - Reading from file tmpfile_stdout 13:40:26 INFO - Output received: 13:40:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:40:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:40:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:40:26 INFO - Using env: {'DISPLAY': ':0', 13:40:26 INFO - 'HOME': '/home/cltbld', 13:40:26 INFO - 'LANG': 'en_US.UTF-8', 13:40:26 INFO - 'LANGUAGE': 'en_US:en', 13:40:26 INFO - 'LOGNAME': 'cltbld', 13:40:26 INFO - 'MAIL': '/var/mail/cltbld', 13:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:26 INFO - 'MOZ_NO_REMOTE': '1', 13:40:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:26 INFO - 'NO_EM_RESTART': '1', 13:40:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:26 INFO - 'PWD': '/builds/slave/test', 13:40:26 INFO - 'SHELL': '/bin/bash', 13:40:26 INFO - 'SHLVL': '1', 13:40:26 INFO - 'TERM': 'linux', 13:40:26 INFO - 'TMOUT': '86400', 13:40:26 INFO - 'USER': 'cltbld', 13:40:26 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:26 INFO - '_': '/tools/buildbot/bin/python'} 13:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:27 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:40:27 INFO - Cleaning up... 13:40:27 INFO - Return code: 0 13:40:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:40:27 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:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:27 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:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:27 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 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:27 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:40:27 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:40:27 INFO - Using env: {'DISPLAY': ':0', 13:40:27 INFO - 'HOME': '/home/cltbld', 13:40:27 INFO - 'LANG': 'en_US.UTF-8', 13:40:27 INFO - 'LANGUAGE': 'en_US:en', 13:40:27 INFO - 'LOGNAME': 'cltbld', 13:40:27 INFO - 'MAIL': '/var/mail/cltbld', 13:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:27 INFO - 'MOZ_NO_REMOTE': '1', 13:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:27 INFO - 'NO_EM_RESTART': '1', 13:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:27 INFO - 'PWD': '/builds/slave/test', 13:40:27 INFO - 'SHELL': '/bin/bash', 13:40:27 INFO - 'SHLVL': '1', 13:40:27 INFO - 'TERM': 'linux', 13:40:27 INFO - 'TMOUT': '86400', 13:40:27 INFO - 'USER': 'cltbld', 13:40:27 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:27 INFO - '_': '/tools/buildbot/bin/python'} 13:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:27 INFO - Downloading/unpacking psutil>=3.1.1 13:40:27 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:40:27 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:40:27 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:40:27 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:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:40:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:40:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:40:28 INFO - Installing collected packages: psutil 13:40:28 INFO - Running setup.py install for psutil 13:40:28 INFO - building 'psutil._psutil_linux' extension 13:40:28 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:40:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 13:40:28 INFO - building 'psutil._psutil_posix' extension 13:40:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 13:40:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 13:40:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:40:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:40:28 INFO - Successfully installed psutil 13:40:28 INFO - Cleaning up... 13:40:28 INFO - Return code: 0 13:40:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:40:28 INFO - Using env: {'DISPLAY': ':0', 13:40:28 INFO - 'HOME': '/home/cltbld', 13:40:28 INFO - 'LANG': 'en_US.UTF-8', 13:40:28 INFO - 'LANGUAGE': 'en_US:en', 13:40:28 INFO - 'LOGNAME': 'cltbld', 13:40:28 INFO - 'MAIL': '/var/mail/cltbld', 13:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:28 INFO - 'MOZ_NO_REMOTE': '1', 13:40:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:28 INFO - 'NO_EM_RESTART': '1', 13:40:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:28 INFO - 'PWD': '/builds/slave/test', 13:40:28 INFO - 'SHELL': '/bin/bash', 13:40:28 INFO - 'SHLVL': '1', 13:40:28 INFO - 'TERM': 'linux', 13:40:28 INFO - 'TMOUT': '86400', 13:40:28 INFO - 'USER': 'cltbld', 13:40:28 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:28 INFO - '_': '/tools/buildbot/bin/python'} 13:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:40:29 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:40:29 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:40:29 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:40:29 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:40:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:40:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:40:30 INFO - Installing collected packages: mozsystemmonitor 13:40:30 INFO - Running setup.py install for mozsystemmonitor 13:40:30 INFO - Successfully installed mozsystemmonitor 13:40:30 INFO - Cleaning up... 13:40:30 INFO - Return code: 0 13:40:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:40:30 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:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:30 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:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:30 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 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:30 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:40:30 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:40:30 INFO - Using env: {'DISPLAY': ':0', 13:40:30 INFO - 'HOME': '/home/cltbld', 13:40:30 INFO - 'LANG': 'en_US.UTF-8', 13:40:30 INFO - 'LANGUAGE': 'en_US:en', 13:40:30 INFO - 'LOGNAME': 'cltbld', 13:40:30 INFO - 'MAIL': '/var/mail/cltbld', 13:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:30 INFO - 'MOZ_NO_REMOTE': '1', 13:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:30 INFO - 'NO_EM_RESTART': '1', 13:40:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:30 INFO - 'PWD': '/builds/slave/test', 13:40:30 INFO - 'SHELL': '/bin/bash', 13:40:30 INFO - 'SHLVL': '1', 13:40:30 INFO - 'TERM': 'linux', 13:40:30 INFO - 'TMOUT': '86400', 13:40:30 INFO - 'USER': 'cltbld', 13:40:30 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:30 INFO - '_': '/tools/buildbot/bin/python'} 13:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:30 INFO - Downloading/unpacking jsonschema==2.5.1 13:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:40:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:31 INFO - Downloading functools32-3.2.3-2.tar.gz 13:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:40:31 INFO - warning: no files found matching '*.txt' 13:40:31 INFO - no previously-included directories found matching 'build' 13:40:31 INFO - no previously-included directories found matching 'dist' 13:40:31 INFO - no previously-included directories found matching '.git*' 13:40:31 INFO - Installing collected packages: jsonschema, functools32 13:40:32 INFO - Running setup.py install for functools32 13:40:32 INFO - warning: no files found matching '*.txt' 13:40:32 INFO - no previously-included directories found matching 'build' 13:40:32 INFO - no previously-included directories found matching 'dist' 13:40:32 INFO - no previously-included directories found matching '.git*' 13:40:32 INFO - Successfully installed jsonschema functools32 13:40:32 INFO - Cleaning up... 13:40:32 INFO - Return code: 0 13:40:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:40:32 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:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:32 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:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:32 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 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:32 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:40:32 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:40:32 INFO - Using env: {'DISPLAY': ':0', 13:40:32 INFO - 'HOME': '/home/cltbld', 13:40:32 INFO - 'LANG': 'en_US.UTF-8', 13:40:32 INFO - 'LANGUAGE': 'en_US:en', 13:40:32 INFO - 'LOGNAME': 'cltbld', 13:40:32 INFO - 'MAIL': '/var/mail/cltbld', 13:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:32 INFO - 'MOZ_NO_REMOTE': '1', 13:40:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:32 INFO - 'NO_EM_RESTART': '1', 13:40:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:32 INFO - 'PWD': '/builds/slave/test', 13:40:32 INFO - 'SHELL': '/bin/bash', 13:40:32 INFO - 'SHLVL': '1', 13:40:32 INFO - 'TERM': 'linux', 13:40:32 INFO - 'TMOUT': '86400', 13:40:32 INFO - 'USER': 'cltbld', 13:40:32 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:32 INFO - '_': '/tools/buildbot/bin/python'} 13:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:40:32 INFO - Cleaning up... 13:40:32 INFO - Return code: 0 13:40:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:40:32 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:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:32 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:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:32 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 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:32 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:40:32 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:40:32 INFO - Using env: {'DISPLAY': ':0', 13:40:32 INFO - 'HOME': '/home/cltbld', 13:40:32 INFO - 'LANG': 'en_US.UTF-8', 13:40:32 INFO - 'LANGUAGE': 'en_US:en', 13:40:32 INFO - 'LOGNAME': 'cltbld', 13:40:32 INFO - 'MAIL': '/var/mail/cltbld', 13:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:32 INFO - 'MOZ_NO_REMOTE': '1', 13:40:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:32 INFO - 'NO_EM_RESTART': '1', 13:40:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:32 INFO - 'PWD': '/builds/slave/test', 13:40:32 INFO - 'SHELL': '/bin/bash', 13:40:32 INFO - 'SHLVL': '1', 13:40:32 INFO - 'TERM': 'linux', 13:40:32 INFO - 'TMOUT': '86400', 13:40:32 INFO - 'USER': 'cltbld', 13:40:32 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:32 INFO - '_': '/tools/buildbot/bin/python'} 13:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:32 INFO - Downloading/unpacking blobuploader==1.2.4 13:40:32 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:40:32 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:40:32 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:40:32 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:40:33 INFO - Downloading blobuploader-1.2.4.tar.gz 13:40:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:40:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:40:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:40:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:40:34 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:40:34 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:40:34 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:40:34 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:40:34 INFO - Downloading docopt-0.6.1.tar.gz 13:40:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:40:34 INFO - Installing collected packages: blobuploader, requests, docopt 13:40:34 INFO - Running setup.py install for blobuploader 13:40:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:40:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:40:34 INFO - Running setup.py install for requests 13:40:34 INFO - Running setup.py install for docopt 13:40:34 INFO - Successfully installed blobuploader requests docopt 13:40:34 INFO - Cleaning up... 13:40:34 INFO - Return code: 0 13:40:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:40:34 INFO - Using env: {'DISPLAY': ':0', 13:40:34 INFO - 'HOME': '/home/cltbld', 13:40:34 INFO - 'LANG': 'en_US.UTF-8', 13:40:34 INFO - 'LANGUAGE': 'en_US:en', 13:40:34 INFO - 'LOGNAME': 'cltbld', 13:40:34 INFO - 'MAIL': '/var/mail/cltbld', 13:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:34 INFO - 'MOZ_NO_REMOTE': '1', 13:40:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:34 INFO - 'NO_EM_RESTART': '1', 13:40:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:34 INFO - 'PWD': '/builds/slave/test', 13:40:34 INFO - 'SHELL': '/bin/bash', 13:40:34 INFO - 'SHLVL': '1', 13:40:34 INFO - 'TERM': 'linux', 13:40:34 INFO - 'TMOUT': '86400', 13:40:34 INFO - 'USER': 'cltbld', 13:40:34 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:34 INFO - '_': '/tools/buildbot/bin/python'} 13:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:40:35 INFO - Running setup.py (path:/tmp/pip-z8JOel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:40:35 INFO - Running setup.py (path:/tmp/pip-lzmB40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:40:35 INFO - Running setup.py (path:/tmp/pip-kD8TtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:40:35 INFO - Running setup.py (path:/tmp/pip-HWIAFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:40:35 INFO - Running setup.py (path:/tmp/pip-28TOgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:40:35 INFO - Running setup.py (path:/tmp/pip-GwgMcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:40:35 INFO - Running setup.py (path:/tmp/pip-MJ4Q3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:40:35 INFO - Running setup.py (path:/tmp/pip-aO7ao6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:40:35 INFO - Running setup.py (path:/tmp/pip-vAtzX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:40:35 INFO - Running setup.py (path:/tmp/pip-yoq5cK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:40:35 INFO - Running setup.py (path:/tmp/pip-qmPHjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:40:35 INFO - Running setup.py (path:/tmp/pip-Dbg_GG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:40:35 INFO - Running setup.py (path:/tmp/pip-ascr7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:40:36 INFO - Running setup.py (path:/tmp/pip-Fz_18L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:36 INFO - Running setup.py (path:/tmp/pip-2Yl6Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:40:36 INFO - Running setup.py (path:/tmp/pip-0KwUXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:40:36 INFO - Running setup.py (path:/tmp/pip-P5jJcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:40:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:40:36 INFO - Running setup.py install for manifestparser 13:40:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:40:36 INFO - Running setup.py install for mozcrash 13:40:36 INFO - Running setup.py install for mozdebug 13:40:36 INFO - Running setup.py install for mozdevice 13:40:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:40:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:40:36 INFO - Running setup.py install for mozfile 13:40:36 INFO - Running setup.py install for mozhttpd 13:40:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:40:36 INFO - Running setup.py install for mozinfo 13:40:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Running setup.py install for mozInstall 13:40:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Running setup.py install for mozleak 13:40:37 INFO - Running setup.py install for mozlog 13:40:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Running setup.py install for moznetwork 13:40:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Running setup.py install for mozprocess 13:40:37 INFO - Running setup.py install for mozprofile 13:40:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Running setup.py install for mozrunner 13:40:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:40:37 INFO - Running setup.py install for mozscreenshot 13:40:37 INFO - Running setup.py install for moztest 13:40:37 INFO - Running setup.py install for mozversion 13:40:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:40:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:40:38 INFO - Cleaning up... 13:40:38 INFO - Return code: 0 13:40:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:40: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:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40: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:40:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:38 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 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:38 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:40:38 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:40:38 INFO - Using env: {'DISPLAY': ':0', 13:40:38 INFO - 'HOME': '/home/cltbld', 13:40:38 INFO - 'LANG': 'en_US.UTF-8', 13:40:38 INFO - 'LANGUAGE': 'en_US:en', 13:40:38 INFO - 'LOGNAME': 'cltbld', 13:40:38 INFO - 'MAIL': '/var/mail/cltbld', 13:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:38 INFO - 'MOZ_NO_REMOTE': '1', 13:40:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:38 INFO - 'NO_EM_RESTART': '1', 13:40:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:38 INFO - 'PWD': '/builds/slave/test', 13:40:38 INFO - 'SHELL': '/bin/bash', 13:40:38 INFO - 'SHLVL': '1', 13:40:38 INFO - 'TERM': 'linux', 13:40:38 INFO - 'TMOUT': '86400', 13:40:38 INFO - 'USER': 'cltbld', 13:40:38 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:38 INFO - '_': '/tools/buildbot/bin/python'} 13:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:40:38 INFO - Running setup.py (path:/tmp/pip-3Axkv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:40:38 INFO - Running setup.py (path:/tmp/pip-OhIv9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:40:38 INFO - Running setup.py (path:/tmp/pip-AzOzSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:40:38 INFO - Running setup.py (path:/tmp/pip-IDTmoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:40:38 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:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:40:38 INFO - Running setup.py (path:/tmp/pip-3rX8NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:40:38 INFO - Running setup.py (path:/tmp/pip-F5N4Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:40:38 INFO - Running setup.py (path:/tmp/pip-iDGP4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:40:38 INFO - Running setup.py (path:/tmp/pip-NTFgmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:40:38 INFO - Running setup.py (path:/tmp/pip-by7odi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:40:38 INFO - Running setup.py (path:/tmp/pip-ZIpc9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:40:39 INFO - Running setup.py (path:/tmp/pip-_AiyRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:40:39 INFO - Running setup.py (path:/tmp/pip-09BSWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:40:39 INFO - Running setup.py (path:/tmp/pip-GHW_kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:40:39 INFO - Running setup.py (path:/tmp/pip-HMlL3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:39 INFO - Running setup.py (path:/tmp/pip-Bd7rem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:40:39 INFO - Running setup.py (path:/tmp/pip-e989Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:40:39 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:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:40:39 INFO - Running setup.py (path:/tmp/pip-T5Mb5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:40:39 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:40:39 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:40:39 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:40:39 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:40:39 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:40:39 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:40:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:40:39 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:40:39 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:40:39 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:40:39 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:40:40 INFO - Downloading blessings-1.6.tar.gz 13:40:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:40:40 INFO - Installing collected packages: blessings 13:40:40 INFO - Running setup.py install for blessings 13:40:40 INFO - Successfully installed blessings 13:40:40 INFO - Cleaning up... 13:40:40 INFO - Return code: 0 13:40:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:40:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:40:41 INFO - Reading from file tmpfile_stdout 13:40:41 INFO - Current package versions: 13:40:41 INFO - argparse == 1.2.1 13:40:41 INFO - blessings == 1.6 13:40:41 INFO - blobuploader == 1.2.4 13:40:41 INFO - docopt == 0.6.1 13:40:41 INFO - functools32 == 3.2.3-2 13:40:41 INFO - jsonschema == 2.5.1 13:40:41 INFO - manifestparser == 1.1 13:40:41 INFO - mozInstall == 1.12 13:40:41 INFO - mozcrash == 1.0 13:40:41 INFO - mozdebug == 0.1 13:40:41 INFO - mozdevice == 0.49 13:40:41 INFO - mozfile == 1.2 13:40:41 INFO - mozhttpd == 0.7 13:40:41 INFO - mozinfo == 0.9 13:40:41 INFO - mozleak == 0.1 13:40:41 INFO - mozlog == 3.4 13:40:41 INFO - moznetwork == 0.27 13:40:41 INFO - mozprocess == 0.25 13:40:41 INFO - mozprofile == 0.28 13:40:41 INFO - mozrunner == 6.13 13:40:41 INFO - mozscreenshot == 0.1 13:40:41 INFO - mozsystemmonitor == 0.3 13:40:41 INFO - moztest == 0.8 13:40:41 INFO - mozversion == 1.4 13:40:41 INFO - psutil == 3.1.1 13:40:41 INFO - requests == 1.2.3 13:40:41 INFO - wsgiref == 0.1.2 13:40:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:40: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:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40: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:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40:41 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 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40:41 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:40:41 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:40:41 INFO - Using env: {'DISPLAY': ':0', 13:40:41 INFO - 'HOME': '/home/cltbld', 13:40:41 INFO - 'LANG': 'en_US.UTF-8', 13:40:41 INFO - 'LANGUAGE': 'en_US:en', 13:40:41 INFO - 'LOGNAME': 'cltbld', 13:40:41 INFO - 'MAIL': '/var/mail/cltbld', 13:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:41 INFO - 'MOZ_NO_REMOTE': '1', 13:40:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:41 INFO - 'NO_EM_RESTART': '1', 13:40:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:41 INFO - 'PWD': '/builds/slave/test', 13:40:41 INFO - 'SHELL': '/bin/bash', 13:40:41 INFO - 'SHLVL': '1', 13:40:41 INFO - 'TERM': 'linux', 13:40:41 INFO - 'TMOUT': '86400', 13:40:41 INFO - 'USER': 'cltbld', 13:40:41 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:41 INFO - '_': '/tools/buildbot/bin/python'} 13:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:41 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:40:41 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:40:41 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:40:41 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:40:41 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:40:41 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:40:41 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:40:41 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:40:41 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:40:41 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:40:41 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:40:41 INFO - Cleaning up... 13:40:41 INFO - Return code: 0 13:40:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:40: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:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:40: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:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:40: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1755310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, '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': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', '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:40: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', 'jsonschema'] in /builds/slave/test/build 13:40: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 jsonschema 13:40:41 INFO - Using env: {'DISPLAY': ':0', 13:40:41 INFO - 'HOME': '/home/cltbld', 13:40:41 INFO - 'LANG': 'en_US.UTF-8', 13:40:41 INFO - 'LANGUAGE': 'en_US:en', 13:40:41 INFO - 'LOGNAME': 'cltbld', 13:40:41 INFO - 'MAIL': '/var/mail/cltbld', 13:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:41 INFO - 'MOZ_NO_REMOTE': '1', 13:40:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:41 INFO - 'NO_EM_RESTART': '1', 13:40:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:41 INFO - 'PWD': '/builds/slave/test', 13:40:41 INFO - 'SHELL': '/bin/bash', 13:40:41 INFO - 'SHLVL': '1', 13:40:41 INFO - 'TERM': 'linux', 13:40:41 INFO - 'TMOUT': '86400', 13:40:41 INFO - 'USER': 'cltbld', 13:40:41 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:41 INFO - '_': '/tools/buildbot/bin/python'} 13:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:40:41 INFO - Cleaning up... 13:40:41 INFO - Return code: 0 13:40:41 INFO - Running post-action listener: _resource_record_post_action 13:40:41 INFO - Running post-action listener: _start_resource_monitoring 13:40:41 INFO - Starting resource monitoring. 13:40:41 INFO - [mozharness: 2017-05-23 20:40:41.544812Z] Finished create-virtualenv step (success) 13:40:41 INFO - [mozharness: 2017-05-23 20:40:41.545274Z] Running install step. 13:40:41 INFO - Running pre-action listener: _resource_record_pre_action 13:40:41 INFO - Running main action method: install 13:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:40:41 INFO - Reading from file tmpfile_stdout 13:40:41 INFO - Detecting whether we're running mozinstall >=1.0... 13:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:40:41 INFO - Reading from file tmpfile_stdout 13:40:41 INFO - Output received: 13:40:41 INFO - Usage: mozinstall [options] installer 13:40:41 INFO - Options: 13:40:41 INFO - -h, --help show this help message and exit 13:40:41 INFO - -d DEST, --destination=DEST 13:40:41 INFO - Directory to install application into. [default: 13:40:41 INFO - "/builds/slave/test"] 13:40:41 INFO - --app=APP Application being installed. [default: firefox] 13:40:41 INFO - mkdir: /builds/slave/test/build/application 13:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:40:55 INFO - Reading from file tmpfile_stdout 13:40:55 INFO - Output received: 13:40:55 INFO - /builds/slave/test/build/application/firefox/firefox 13:40:55 INFO - Running post-action listener: _resource_record_post_action 13:40:55 INFO - [mozharness: 2017-05-23 20:40:55.881144Z] Finished install step (success) 13:40:55 INFO - [mozharness: 2017-05-23 20:40:55.881287Z] Running run-tests step. 13:40:55 INFO - Running pre-action listener: _resource_record_pre_action 13:40:55 INFO - Running main action method: run_tests 13:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:40:55 INFO - Python 2.7.3 13:40:55 INFO - Return code: 0 13:40:55 INFO - grabbing minidump binary from tooltool 13:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:40:55 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1752400>, '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 0x17533b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1753840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:40:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:40:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 13:40:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:40:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:40:55 INFO - Return code: 0 13:40:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:40:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:40:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:40:55 INFO - ENV: RUST_BACKTRACE is now 1 13:40:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:40:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-077 --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:40:55 INFO - Using env: {'DISPLAY': ':0', 13:40:55 INFO - 'HOME': '/home/cltbld', 13:40:55 INFO - 'LANG': 'en_US.UTF-8', 13:40:55 INFO - 'LANGUAGE': 'en_US:en', 13:40:55 INFO - 'LOGNAME': 'cltbld', 13:40:55 INFO - 'MAIL': '/var/mail/cltbld', 13:40:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:40:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:40:55 INFO - 'MOZ_NO_REMOTE': '1', 13:40:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:40:55 INFO - 'NO_EM_RESTART': '1', 13:40:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:40:55 INFO - 'PWD': '/builds/slave/test', 13:40:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:40:55 INFO - 'RUST_BACKTRACE': '1', 13:40:55 INFO - 'SHELL': '/bin/bash', 13:40:55 INFO - 'SHLVL': '1', 13:40:55 INFO - 'TERM': 'linux', 13:40:55 INFO - 'TMOUT': '86400', 13:40:55 INFO - 'USER': 'cltbld', 13:40:55 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358', 13:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:40:55 INFO - '_': '/tools/buildbot/bin/python'} 13:40:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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:40:56 INFO - mozversion application_buildid: 20170523194542 13:40:56 INFO - mozversion application_changeset: e161af4fc095a433ca4b37c39f25406438e96aa9 13:40:56 INFO - mozversion application_display_name: Firefox 13:40:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:40:56 INFO - mozversion application_name: Firefox 13:40:56 INFO - mozversion application_remotingname: firefox 13:40:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:40:56 INFO - mozversion application_vendor: Mozilla 13:40:56 INFO - mozversion application_version: 54.0 13:40:56 INFO - mozversion platform_buildid: 20170523194542 13:40:56 INFO - mozversion platform_changeset: e161af4fc095a433ca4b37c39f25406438e96aa9 13:40:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:40:56 INFO - mozversion platform_version: 54.0 13:40:56 INFO - using testdate: 1495572056 13:40:56 INFO - actual date: 1495572056 13:40:56 INFO - starting webserver on 'localhost:52076' 13:40:56 INFO - SUITE-START | Running 2 tests 13:40:56 INFO - TEST-START | dromaeo_css 13:40:56 INFO - operating with platform_type : linux_ 13:40:56 INFO - Initialising browser for dromaeo_css test... 13:40:56 INFO - TEST-INFO | started process 12961 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp43wDGR/profile http://localhost:52076/getInfo.html) 13:41:00 INFO - PROCESS | 12961 | __metrics Screen width/height:1600/1200 13:41:00 INFO - PROCESS | 12961 | colorDepth:24 13:41:00 INFO - PROCESS | 12961 | Browser inner width/height: 1024/697 13:41:00 INFO - PROCESS | 12961 | __metrics 13:41:01 INFO - PROCESS | 12961 | 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:41:01 INFO - PROCESS | 12961 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:41:02 INFO - TEST-INFO | 12961: exit 0 13:41:02 INFO - Browser initialized. 13:41:02 INFO - Running cycle 1/1 for dromaeo_css test... 13:41:02 INFO - TEST-INFO | started process 13049 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp43wDGR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:41:14 INFO - PROCESS | 13049 | RSS: Main: 141922304 13:41:14 INFO - PROCESS | 13049 | 13:44:15 INFO - PROCESS | 13049 | Cycle 1(1): loaded http://localhost:52076/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52076/tests/dromaeo/cssquery-ext.html) 13:44:16 INFO - PROCESS | 13049 | RSS: Main: 264978432 13:44:16 INFO - PROCESS | 13049 | 13:47:19 INFO - PROCESS | 13049 | Cycle 1(1): loaded http://localhost:52076/tests/dromaeo/cssquery-ext.html (next: http://localhost:52076/tests/dromaeo/cssquery-jquery.html) 13:47:19 INFO - PROCESS | 13049 | RSS: Main: 219344896 13:47:19 INFO - PROCESS | 13049 | 13:50:21 INFO - PROCESS | 13049 | Cycle 1(1): loaded http://localhost:52076/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52076/tests/dromaeo/cssquery-mootools.html) 13:50:22 INFO - PROCESS | 13049 | RSS: Main: 216084480 13:50:22 INFO - PROCESS | 13049 | 13:53:24 INFO - PROCESS | 13049 | Cycle 1(1): loaded http://localhost:52076/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52076/tests/dromaeo/cssquery-prototype.html) 13:53:24 INFO - PROCESS | 13049 | RSS: Main: 172544000 13:53:24 INFO - PROCESS | 13049 | 13:56:27 INFO - PROCESS | 13049 | Cycle 1(1): loaded http://localhost:52076/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52076/tests/dromaeo/cssquery-yui.html) 13:56:27 INFO - PROCESS | 13049 | RSS: Main: 174936064 13:56:27 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | Cycle 1(1): loaded http://localhost:52076/tests/dromaeo/cssquery-yui.html (next: http://localhost:52076/tests/dromaeo/cssquery-dojo.html) 13:59:30 INFO - PROCESS | 13049 | RSS: Main: 264691712 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | __start_tp_report 13:59:30 INFO - PROCESS | 13049 | _x_x_mozilla_page_load 13:59:30 INFO - PROCESS | 13049 | _x_x_mozilla_page_load_details 13:59:30 INFO - PROCESS | 13049 | |i|pagename|runs| 13:59:30 INFO - PROCESS | 13049 | |0;dojo.html;3380.509826075219;3413.573303337083;3423.366677164723;3444.3627928833184;3466.497357883107;5788.160716696082;5894.263217097863;5896.145058966442;5913.73388197531;5935.821925342247;5875.412458754123;5918.585699001072;5928.466438020578;5933.465988061073;5944.821655350346;5235.476452354753;5250.527452529272;5252.185911183754;5315.415304316522;5334.359876814788;5103.081445339837;5131.050755610224;5333.146696528556;5346.091164502044;5349.7057661828585;5181.404138524062;5223.294855194538;5231;5234.26720259164;5235.502627250406;5227.686338819664;5239.266502689627;5257.947420525784;5263.210518422267;5271.130263506517;5207.7916883325;5313.468653134694;5316.069687804618;5323.946760532383;5324.307839980816;5696.487316141525;5879.79420720277;5904.557158213108;5973.492253158487;5990.430909063632;6140.473573783586;6187.87624247517;6251.28110267316;6331.810045698637;6348.968255158741;6381.3937675920715;6385.106085148083;6395.712192951329;6403.391677790602;6403.583767055126;5908.704564771744;5910.527157827407;5953.910691339634;5971.850703732442;5974.193483879708;6152.077188421773;6200.193974783249;6328.5886417383645;6381.074744162163;6396.744130234739;6223.1287619733275;6232.3144454110015;6235.438810507076;6235.625862448268;6242.188515493048;6297.4017468340435;6341;6344.746210151542;6345.143405640272;6347.619142851444;6049.485793707496;6101.206843110367;6164.630122192682;6234.906476402857;6314.0844577536;6309.747610095636;6309.810705678837;6315.526335524854;6315.684215789192;6323.241211054611;4599.701019433726;4630.536946305342;4631.004334068191;4732.148213321635;4772.785224664898;4241.066965267636;4262.957370426256;4271.039016221325;4280.208161490134;4281.571842815693;5793.507551858139;5831.825045248649;5867.413258674183;6014.248218972629;6016.338202797689;6294.775336470719;6298.149749783812;6305.716242769086;6327.715252813635;6329.778457754049;5941.287045554561;5941.762329506858;5947.5539334550085;5951.900914489993;5982.102684597258;5129.256257842667;5264.184051472028;5265.894682106336;5266.947330526646;5269.0252303324005;6387.425131738166;6487.18910136233;6564.573302735403;6625.271220165874;6634.668266586747;6350.7777227796805;6360.427561519486;6373.2989371168205;6375.968120159369;6378.6810659467765;6471.482281417381;6585.341465853377;6594.505412094208;6603.702833372414;6624.940009598441;5988.760449581968;6029.939700602938;6132.4166970161305;6135.600157732601;6248.718807653758;6333.809985700436;6361.681915904093;6363.427291543783;6372.840678983062;6374.139491168633;6156.476699480594;6164.815055548341;6166.938330616816;6167.814965551041;6173.876522469435;5918.082697181943;5976.521878249817;6096.664683442454;6098.390160983866;6113.871604717183;6125.601746185282;6162.506999440124;6181.289151747606;6185.628862268279;6191;6185.721642526007;6227.782027629011;6235.251769787655;6237.781677641261;6243.250809902841;5808.477237048686;5830.591858569859;5964.671943043174;5993.670348130895;6053.182820319201 13:59:30 INFO - PROCESS | 13049 | |1;ext.html;772.5905270206789;840.5587066789934;849.566720972304;918.1690570034117;931.4132096779036;32514.837425812868;45128.32307515386;45426;45551.77224113883;45742.771286143565;44863.77568112155;45042.54957450424;45258.773706131506;45268;45268.77365613176;8091.554964476952;8256.752297431056;9243.35296529245;9366.34435589509;10855.945720271387;10875.347479151236;11017.559297628086;11023.393713345762;11025.173112016591;11027.117830573536;11060.446977651085;11066.446677666125;11071.667849964473;11090.057345125673;11104.111671066295;11027.393493357862;11054.281471704313;11059.170562207786;11060.834087488694;11061.557537698482;10776.946115269451;10838.891611083945;10854.077403420719;10855.728606784814;10861;42775.35836962448;42928.35607465891;43023;43051;43148.56851431477;4354.716943398669;4389.385486031958;4411;4452.955470445287;4526.162659907927;30209.848950755324;30689.69310306913;30863;30870.228244294074;32141.678583214103;4369.978150109261;4401.64786817057;4410.911781764346;4417.359482874997;4483.618892394151;231472;232465;232538;233394;234196;53892.46107538875;54015;54239.72880135653;54248.72875635675;54254.45745542573;57253.42746572564;57268.71365643145;57382;57403.71298143482;57450.712746436;16774.41289554884;17192.31230750781;17210.483685489206;17217.655646886993;17219.913900430416;12866.228026318451;13039.478420863248;14738.115713057037;15052.172130532927;15120.319585618674;13452.394642241123;13534.255615941218;13572.93213533926;13577.456901723821;13604.387802548908;5598.216249725042;5682.374938756733;5704.486596206361;5743.655380677173;5831.708414579254;1028.1774580335702;1034.0590063042746;1038.9532471038822;1042.7288904884786;1048.9423081730426;29181.7081829179;29224.123276301743;29288.414231715247;29558.704412956024;29601.85199073991;1100.4497751124438;1102.7298311913632;1128.9604863829727;1141.9029382502415;1148.5750272399266;15112.848871511143;15877.52367428979;15973.920130399507;15991.200439977954;16012.359505619877;308019;308380;309579;309707;311480;58345.70827145922;58354.70822645944;58380.4161958375;58450.41549584535;58500.41499585035;1489.8286697029764;1500.932458039391;1533.5246073717183;1551.8525740054677;1571.4107209796327;28566;28636;29289.41421171522;29769.553456697733;30001.399972000876;24273.39316517101;24377.878110609334;24387.26838194857;24431.14490992807;24434.63348049745;285537;285761;286319;286985;287441;25711.10011149601;27733.44533109367;29031;30397.544036839023;31336.529952051194;38257.23485530329;38271;38299.23401532009;38448.423273650355;39164.804175980076;30466.238344041576;30582.541261881535;30608.234794130323;31269.687303126677;31682.683173167974;30009.249768755955;30341;30726.385472290876;32761.836190818893;32801.83599081989;35798;36648.81675591605;36970.26059478742;37235.81382093072;37276.813615931744;30561.54157687592;30826.69173308238;31111;31158.844205778827;31221.441519723576;25589.744102558736;25601.743982559936;25621.231363059138;25731.35671608225;25738.742612573635 13:59:30 INFO - PROCESS | 13049 | |2;jquery.html;464.88891549197444;477.6417686734949;487.86339824849057;493.0408817197901;498.25510861262404;20755.273565425214;21977.791221482814;22294.665580016313;22557.323280301574;22764.77235227647;22633.321000369975;22910.541789164206;22991.655125173093;23145.884270578623;23360.415989600267;6680.498962577814;7344.1554221264705;7589.316961473466;7616.504927179715;7626.809329766745;7406.925930740677;7515.32362087412;7574.053243344582;7623.047119110111;7662.425318101137;7394.630268486554;7657.081150261976;7658.463907526475;7661.502002369828;7662.387009039264;7500;7604.7338343158;7607.239276072404;7613.124490683587;7620.276073772982;7280.781576552712;7368.521046131984;7441.181470038291;7472.551646901149;7476.439267054992;25822.74177258241;25844;25957;25989.74010259892;26690.33274168142;3522.4892390603477;3619.6199399063244;3623.565172179329;3684.410494320896;3688.5020522229424;15962;15985.600359990976;16352;16632;16965.57586060346;2187.7921597448217;2230.877301748403;2242.136777340719;2312.8727919964394;2323.3262353917344;110860.44569777181;120002;121207;121576;121651;16546.917265413595;16831.455507013037;16945.661086778196;17781;18202.726959095624;31493.8425307872;31642;31645.20886977798;31672;31749;10334.379937203792;10463.895361046445;10659.880712525228;10665.413402262795;10874.238803283693;8826.91173088261;8877.112288771072;8936.285097192209;9104.908950910407;9169.22061624756;8953.686620968236;9177.77055573616;9323.72028839136;9411.90588094124;9541.570629321697;3366.562346894888;3675.6691897729334;3743.5320584926885;3830.942535861964;3874.1089549403796;961.293449314753;966.9371490853072;967.4388854464535;994.786120983992;1012.6050840172338;21648.89175554112;22172.223972160897;22205.22281720132;22303.442413939458;22559.436014099778;970.9417434953925;975.2441857560448;976.9071938165863;995.7610173558294;1000;11345;12072.879271207352;12203.572874949334;12242.265464072185;12305;645527;647993;667961;670201;682826;78269.60865195637;93684;104466;115233.42383288198;124460.37769811093;1256.9874301257053;1290.1936289818864;1300.4603089717662;1309.4500309869877;1313.7963615639592;19340.516487087374;19521;19879.701804472654;19912.303069392503;20029.899850500653;54058.729706351216;54059.729701352815;54689.45310547003;54818.725906370215;54984.725076375966;71902;72175;72330.63834680793;72758.27241727727;73600.26399735935;12272.938635306766;12444.066694998093;12686.555970540987;12953.352332383154;13036.087473876738;14031.719365612835;14064.578062658136;14492.202928839015;14505.85494145045;14702.41190352374;11821.704457388634;11906.34515101643;11971.221870578385;12016.699582510508;12186.268823870596;11384.943075284571;11477.655670329903;11536.423178841193;11612.535498579962;11699.415029248674;11401.087912967108;11576.768464630492;11588.304701717587;11865.822012669643;11975.64073077809;12513.687157821127;12656.240625562496;12658.936705316783;12763.489460421479;12875.781054736315;11217.831732524182;11271.830922536332;11323.263987840764;11327.32036077838;11660.30038197711 13:59:30 INFO - PROCESS | 13049 | |3;mootools.html;1574.5906064423245;1604.5186444066776;1621.9432319868804;1625.8049034115909;1627.662280023737;10962.232643714942;11663.883361166385;11665.883341166586;11669.24149930254;11675;10871.565137394495;11108.055815255713;11120.277181983185;11145.72135696608;11417.143714221445;2501.1496091328945;2523.634073059405;2533.5946248600226;2538.530371881199;2540.2633236361526;2482.2925466242123;2486.9129580464687;2543.8473691578474;2546.898124075035;2549.7577730115704;2392.617181251;2423.6243382275775;2497.1509686706518;2532.3542496663326;2534.5057713745828;2419.7459266777;2466.2971053249826;2473.418746594549;2473.913413030535;2490.091116742394;2346.1319311854554;2403.483251101022;2412.3969007748065;2418.1053010386267;2418.250342393846;11207.21549491536;11356.034737047361;11428.942855285753;11429.142814288878;11436.42817859112;5930.1994230779155;6089.543284253698;6101.115338275969;6112.7554897804475;6118.449339559416;3590.5870824855215;3672.7429079964413;3703.925921481555;3722.3485889969147;3764.1154328733;1287.4785711786742;1330.6360979995486;1340.1021315718492;1340.2628554295195;1358.6195865157772;17716.645667086716;17734.55663608407;17835;17956.640867182585;17981.1009449527;4848.393950756155;5018.774155163026;5028.823991160292;5032.79868805251;5127.333446651912;12936.353182340845;12985;12985.155964862253;12992.220466772023;12993.350332483338;6049.425304596057;6051.334353221142;6092.5430592705725;6096.69516524172;6096.786612468542;5393.379761327419;5512.062949298629;5565.972170139124;5582.218489411485;5631.605787594911;3926.391409331558;4037.636612704812;4037.7779222143063;4079.2453396121814;4108.794560271975;1027.4246422003703;1033.1837848099917;1038.771470276538;1050.4012712753656;1051.8842927277992;1031.437866362834;1036.1658864614058;1047.261680515235;1048.528162326956;1052.894710528941;6762.661866906635;6914.654267286615;6964.303569642995;6984.650767461607;7053.788386348418;1040.9011143941466;1087.7443800706828;1098.8846171151913;1112.2659045030402;1119.283658458587;4331.112122014979;4519.412476378116;4524.163029839425;4609.423822022247;4658.37111989877;13557.389917453738;14010;14620.634484137981;15163.924180379028;15201.315940782692;12138.757224855453;14507.419703211754;14513.92743036278;14528.709425811425;14532.709345813024;9120.45277283365;10227.28409011377;10456.73858153537;10804.621838235782;10814.188935829687;7504.399648028254;7648.617569121633;7767.883481747891;7838.137804841577;7929.088154862265;12756.234625922474;13076.607701768962;13291.867081329063;13366.131201471873;13415.932920335335;13236.933815330862;13322.600321990356;13332.200067995564;13456.46174153023;13471.595852124452;9931.95034024854;10198.286119971532;10203.540840662039;10207.693769186937;10210.540525676213;11107.500162492868;11277.548898043986;11288;11380.089592832719;11436.027937625062;9432.245420366491;9468.431894086378;9486.905130948879;9532.618695252113;9589.184919281939;9198.632054718004;9259.305552083486;9317.254619630277;9371.390859594105;9394.154526092685;8163.224493673091;8206.302464290602;8283.50298982063;8286.46138001028;8398.622062007102;6548.901766473411;6670.199576050905;6699.253306144457;6729.226138994079;6792.354726300993;6593.769218077345;6660.533762636569;6731.966340168267;6735.292794256533;6924.792256232321 13:59:30 INFO - PROCESS | 13049 | |4;prototype.html;420.8274607410962;438.70387488445294;440.2032321498088;443.28409618466145;449.6065942300487;5288.709120998344;5355.866103347418;5364;5368.32895888014;5378.489043540857;5093.770780314895;5255.896261785029;5263.86840328992;5279.155335146377;5280.815171468999;4357.257097161129;4363.869083927487;4364.847230346939;4377.233984052794;4398;4342.413774140498;4367.803448844794;4369.934450983238;4383.846565370197;4413.867583972486;4371.781410929457;4418.1384629997165;4421.668374871898;4427.557244275579;4447.954730638297;4173.39485774564;4340.500842403132;4344.7827608619755;4353.499347575038;4383.890402739925;4062.6546743526837;4072.9817545613596;4118.299889018874;4130.132672138854;4130.938035929464;5070.873228169326;5112.035019688572;5147.305113809625;5153.819616313411;5160.8193713220235;5010.348654674869;5151.175811870083;5171.922421163648;5184.533391994701;5186.50728180826;5526.778928842882;5532.28080349556;5533.197686335499;5558.610347413147;5564.1931919871395;4657.69724967876;4669.696469729491;4701.976490117528;4707.5057119002695;4710.5289471052965;5504.972475137639;5519.668819870821;5606.187102870061;5625.006245565694;5761.971190144064;4688.5545873142;4710.9764451177525;4723.669343146015;4724.834630787906;4794.904101918012;4840.709557426566;4840.903181936412;4850.975745121323;4859.829905953346;4867.342908707279;4983.875403114951;5037.29477873098;5114.539691427789;5139.794408223629;5213.661112027706;5458.235846981426;5467.480589344006;5495.395506494282;5563.970665426909;5569.777208911679;1647.7281248594088;1652.9421470248483;1667.1497536256597;1669.3656410564151;1669.7078011347965;1266.9864108712993;1274.8024056271292;1275.1573975605347;1298.5130576033987;1318.8483324417623;5224.320838290998;5320.893582128336;5327.280817089721;5333.893322133613;5383.327084114486;5583.6320101575375;5616.438356164351;5642.645765016448;5644.661320320712;5653.547716182696;5045.562014825757;5172.732680493304;5248.3701955765555;5312.468753124656;5372.865678358073;2952.9261768455963;2972.895948641787;2975.6577993530586;2992.132281638344;2994.7903646744953;5621.241132447149;5825.300963884276;5848.157830283447;5863.736131874076;5873.676947767914;5745.2818397700285;5765.913511297334;5769.769209231667;5771.633630211593;5792.768289268466;5796.3913789052385;5822.602575381878;5827.79602713903;5855.326637436664;5870;5453.2365468833605;5602.299712535933;5718.771249149988;5730.484256416942;5755.8561035974435;5527.944720552904;5649.943500565106;5692.459216374438;5698.77747678414;5730.9713451434145;5855.531557475307;5864.237649105589;5890.2342695449315;5916.970415148069;5934.821955341346;4768.546988036131;4785.641076919175;4794.897173650011;4797.520247975314;4813.326134341125;5445.782168713207;5686.43135686428;5704.543636508986;5757;5812.273465816773;5543.417941116125;5571.637843540157;5587.357453892854;5591.9720401397735;5593.944060559343;5565.1930470082425;5565.471280228372;5601.439856014366;5607.523360514238;5709.714514274353;5352.584118102503;5523.784767351176;5559.24950131727;5575.665460072409;5581.804636837691;5526.640768350045;5558.3607885092115;5576.219329293821;5576.498115169654;5620.522255608155;5258.605604579749;5454.618176727591;5473.863153421037;5476.945230547643;5509.025126431682 13:59:30 INFO - PROCESS | 13049 | |5;yui.html;828.3704384667654;854.7179430787841;923.0190248878413;933.6239271055164;947.1097168661457;16538.503844884643;18739.81260187398;18946;18952.715709264372;19112.235510579576;16054.43809466669;17475.5631109222;17579;17618.29526818929;17625.55936101598;896.4755617963499;903.5301643145593;909.3180114913814;912.2792993535132;912.3157631776168;890.5992303463435;901.9413738107035;903.029243563169;906.0712769411373;906.1165363770338;957.3203025851653;959.2134449751206;960.1214888377161;960.7021823234785;960.9135177833994;946.0775743649955;947.5830634520789;948.9667861624879;949.1505102932871;949.349695458613;700.6916956539158;702.4801646781397;704.3449591879549;704.6547191875944;704.68993642797;18633.347832825923;18700.62598748031;18741.90629046846;19110;19381;16346.3028600282;16369.508914732578;16635.6672866542;16834.579135521588;17143.314267429287;12962.481500740054;13140.211587304697;13160.012999025112;13344;13453.66365840852;4046.433499310099;4050.23045621331;4071.1654110907193;4101.938470922939;4199.118185181115;88293.55853220692;88545;89610.10389896148;90020;91187;12431.191972521758;12703.11078224534;12745.107842451125;12923.935380323039;12944.8058279124;24529.264122076365;24662.630060549105;24867;24963.12629057974;25209.369765756004;8337.541435221121;8348.373871959628;8459.746207613782;8539.530325832138;8640.049594544595;4997.90004199914;5095.184770436712;5105.897882042413;5230.503102205284;5275.630705850554;3814.3896976484184;3877.0501227199516;3885.0287428142965;3891.669208117285;3930.1353702186047;641.2369280556123;644.9064885591657;650.180772226988;650.9153810004669;663.9933600663932;670.316277397062;676.9559978601375;679.1680191765048;692.3388164303078;696.6690821859576;11409.372484513431;11461.544226013571;11908.345041022654;12051.096167787451;12117.636470905887;848.6562942008496;849.8640217565284;866.3372520022189;871.8930768126854;884.1379654836483;5520.06158952979;5558.527525160407;5559.305086864142;5596.104623260258;5652.152177173457;87646;88190.55904720436;88334.11665883387;90591.54704226437;92879.53560232291;76970;78904.60547697225;79857;81713.59143204246;82076.58961705273;68840;70107.64946175237;70180.2981970184;71267.28732712606;72246;10759.89240107589;10797.190210734068;10958.45207739594;11200.551977921117;11208.71978200519;48683.269750953055;49009.2648610264;49029.50970490249;49278;49853.252201217736;51113;52074.73962630163;56644.433555665564;57995.123191767016;58256.4174358251;14067.155970641794;14081.155130692192;14375.424983000563;14440;14491.637709057359;15978.440754573534;16431.342746290014;16651.250693719056;16683;17120.14399280056;12536.247825130522;12743.235405875677;14220.93796399537;14688.853111468748;15409.460668876536;13392.598222053353;13492.257925814174;13503.662408439475;13665.453381864614;14269.928650356682;14016.29016736648;14096.754135057829;15740.606484837512;16543;17494.825051749318;11162.44187790591;11791.174617776673;11974.401279936143;12053.63839084829;12088.214266072677;13076.542321018718;13202.603921882359;13229.867701322864;13343.866561334262;13460.865391345962 13:59:30 INFO - PROCESS | 13049 | __end_tp_report 13:59:30 INFO - PROCESS | 13049 | __start_cc_report 13:59:30 INFO - PROCESS | 13049 | _x_x_mozilla_cycle_collect,1190 13:59:30 INFO - PROCESS | 13049 | __end_cc_report 13:59:30 INFO - PROCESS | 13049 | __startTimestamp1495573170550__endTimestamp 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | ------- Summary: start ------- 13:59:30 INFO - PROCESS | 13049 | Number of tests: 6 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | [#0] dojo.html Cycles:165 Average:5823.97 Median:6029.94 stddev:688.65 (11.4%) stddev-sans-first:663.55 13:59:30 INFO - PROCESS | 13049 | Values: 3380.5 3413.6 3423.4 3444.4 3466.5 5788.2 5894.3 5896.1 5913.7 5935.8 5875.4 5918.6 5928.5 5933.5 5944.8 5235.5 5250.5 5252.2 5315.4 5334.4 5103.1 5131.1 5333.1 5346.1 5349.7 5181.4 5223.3 5231.0 5234.3 5235.5 5227.7 5239.3 5257.9 5263.2 5271.1 5207.8 5313.5 5316.1 5323.9 5324.3 5696.5 5879.8 5904.6 5973.5 5990.4 6140.5 6187.9 6251.3 6331.8 6349.0 6381.4 6385.1 6395.7 6403.4 6403.6 5908.7 5910.5 5953.9 5971.9 5974.2 6152.1 6200.2 6328.6 6381.1 6396.7 6223.1 6232.3 6235.4 6235.6 6242.2 6297.4 6341.0 6344.7 6345.1 6347.6 6049.5 6101.2 6164.6 6234.9 6314.1 6309.7 6309.8 6315.5 6315.7 6323.2 4599.7 4630.5 4631.0 4732.1 4772.8 4241.1 4263.0 4271.0 4280.2 4281.6 5793.5 5831.8 5867.4 6014.2 6016.3 6294.8 6298.1 6305.7 6327.7 6329.8 5941.3 5941.8 5947.6 5951.9 5982.1 5129.3 5264.2 5265.9 5266.9 5269.0 6387.4 6487.2 6564.6 6625.3 6634.7 6350.8 6360.4 6373.3 6376.0 6378.7 6471.5 6585.3 6594.5 6603.7 6624.9 5988.8 6029.9 6132.4 6135.6 6248.7 6333.8 6361.7 6363.4 6372.8 6374.1 6156.5 6164.8 6166.9 6167.8 6173.9 5918.1 5976.5 6096.7 6098.4 6113.9 6125.6 6162.5 6181.3 6185.6 6191.0 6185.7 6227.8 6235.3 6237.8 6243.3 5808.5 5830.6 5964.7 5993.7 6053.2 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | [#1] ext.html Cycles:180 Average:44426.74 Median:30275.42 stddev:72540.27 (239.6%) stddev-sans-first:72669.71 13:59:30 INFO - PROCESS | 13049 | Values: 772.6 840.6 849.6 918.2 931.4 32514.8 45128.3 45426.0 45551.8 45742.8 44863.8 45042.5 45258.8 45268.0 45268.8 8091.6 8256.8 9243.4 9366.3 10855.9 10875.3 11017.6 11023.4 11025.2 11027.1 11060.4 11066.4 11071.7 11090.1 11104.1 11027.4 11054.3 11059.2 11060.8 11061.6 10776.9 10838.9 10854.1 10855.7 10861.0 42775.4 42928.4 43023.0 43051.0 43148.6 4354.7 4389.4 4411.0 4453.0 4526.2 30209.8 30689.7 30863.0 30870.2 32141.7 4370.0 4401.6 4410.9 4417.4 4483.6 231472.0 232465.0 232538.0 233394.0 234196.0 53892.5 54015.0 54239.7 54248.7 54254.5 57253.4 57268.7 57382.0 57403.7 57450.7 16774.4 17192.3 17210.5 17217.7 17219.9 12866.2 13039.5 14738.1 15052.2 15120.3 13452.4 13534.3 13572.9 13577.5 13604.4 5598.2 5682.4 5704.5 5743.7 5831.7 1028.2 1034.1 1039.0 1042.7 1048.9 29181.7 29224.1 29288.4 29558.7 29601.9 1100.4 1102.7 1129.0 1141.9 1148.6 15112.8 15877.5 15973.9 15991.2 16012.4 308019.0 308380.0 309579.0 309707.0 311480.0 58345.7 58354.7 58380.4 58450.4 58500.4 1489.8 1500.9 1533.5 1551.9 1571.4 28566.0 28636.0 29289.4 29769.6 30001.4 24273.4 24377.9 24387.3 24431.1 24434.6 285537.0 285761.0 286319.0 286985.0 287441.0 25711.1 27733.4 29031.0 30397.5 31336.5 38257.2 38271.0 38299.2 38448.4 39164.8 30466.2 30582.5 30608.2 31269.7 31682.7 30009.2 30341.0 30726.4 32761.8 32801.8 35798.0 36648.8 36970.3 37235.8 37276.8 30561.5 30826.7 31111.0 31158.8 31221.4 25589.7 25601.7 25621.2 25731.4 25738.7 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | [#2] jquery.html Cycles:180 Average:38017.99 Median:12652.26 stddev:109201.02 (863.1%) stddev-sans-first:109470.95 13:59:30 INFO - PROCESS | 13049 | Values: 464.9 477.6 487.9 493.0 498.3 20755.3 21977.8 22294.7 22557.3 22764.8 22633.3 22910.5 22991.7 23145.9 23360.4 6680.5 7344.2 7589.3 7616.5 7626.8 7406.9 7515.3 7574.1 7623.0 7662.4 7394.6 7657.1 7658.5 7661.5 7662.4 7500.0 7604.7 7607.2 7613.1 7620.3 7280.8 7368.5 7441.2 7472.6 7476.4 25822.7 25844.0 25957.0 25989.7 26690.3 3522.5 3619.6 3623.6 3684.4 3688.5 15962.0 15985.6 16352.0 16632.0 16965.6 2187.8 2230.9 2242.1 2312.9 2323.3 110860.4 120002.0 121207.0 121576.0 121651.0 16546.9 16831.5 16945.7 17781.0 18202.7 31493.8 31642.0 31645.2 31672.0 31749.0 10334.4 10463.9 10659.9 10665.4 10874.2 8826.9 8877.1 8936.3 9104.9 9169.2 8953.7 9177.8 9323.7 9411.9 9541.6 3366.6 3675.7 3743.5 3830.9 3874.1 961.3 966.9 967.4 994.8 1012.6 21648.9 22172.2 22205.2 22303.4 22559.4 970.9 975.2 976.9 995.8 1000.0 11345.0 12072.9 12203.6 12242.3 12305.0 645527.0 647993.0 667961.0 670201.0 682826.0 78269.6 93684.0 104466.0 115233.4 124460.4 1257.0 1290.2 1300.5 1309.5 1313.8 19340.5 19521.0 19879.7 19912.3 20029.9 54058.7 54059.7 54689.5 54818.7 54984.7 71902.0 72175.0 72330.6 72758.3 73600.3 12272.9 12444.1 12686.6 12953.4 13036.1 14031.7 14064.6 14492.2 14505.9 14702.4 11821.7 11906.3 11971.2 12016.7 12186.3 11384.9 11477.7 11536.4 11612.5 11699.4 11401.1 11576.8 11588.3 11865.8 11975.6 12513.7 12656.2 12658.9 12763.5 12875.8 11217.8 11271.8 11323.3 11327.3 11660.3 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | [#3] mootools.html Cycles:180 Average:7231.03 Median:2444.96 stddev:4580.07 (187.3%) stddev-sans-first:4573.20 13:59:30 INFO - PROCESS | 13049 | Values: 1574.6 1604.5 1621.9 1625.8 1627.7 10962.2 11663.9 11665.9 11669.2 11675.0 10871.6 11108.1 11120.3 11145.7 11417.1 2501.1 2523.6 2533.6 2538.5 2540.3 2482.3 2486.9 2543.8 2546.9 2549.8 2392.6 2423.6 2497.2 2532.4 2534.5 2419.7 2466.3 2473.4 2473.9 2490.1 2346.1 2403.5 2412.4 2418.1 2418.3 11207.2 11356.0 11428.9 11429.1 11436.4 5930.2 6089.5 6101.1 6112.8 6118.4 3590.6 3672.7 3703.9 3722.3 3764.1 1287.5 1330.6 1340.1 1340.3 1358.6 17716.6 17734.6 17835.0 17956.6 17981.1 4848.4 5018.8 5028.8 5032.8 5127.3 12936.4 12985.0 12985.2 12992.2 12993.4 6049.4 6051.3 6092.5 6096.7 6096.8 5393.4 5512.1 5566.0 5582.2 5631.6 3926.4 4037.6 4037.8 4079.2 4108.8 1027.4 1033.2 1038.8 1050.4 1051.9 1031.4 1036.2 1047.3 1048.5 1052.9 6762.7 6914.7 6964.3 6984.7 7053.8 1040.9 1087.7 1098.9 1112.3 1119.3 4331.1 4519.4 4524.2 4609.4 4658.4 13557.4 14010.0 14620.6 15163.9 15201.3 12138.8 14507.4 14513.9 14528.7 14532.7 9120.5 10227.3 10456.7 10804.6 10814.2 7504.4 7648.6 7767.9 7838.1 7929.1 12756.2 13076.6 13291.9 13366.1 13415.9 13236.9 13322.6 13332.2 13456.5 13471.6 9932.0 10198.3 10203.5 10207.7 10210.5 11107.5 11277.5 11288.0 11380.1 11436.0 9432.2 9468.4 9486.9 9532.6 9589.2 9198.6 9259.3 9317.3 9371.4 9394.2 8163.2 8206.3 8283.5 8286.5 8398.6 6548.9 6670.2 6699.3 6729.2 6792.4 6593.8 6660.5 6732.0 6735.3 6924.8 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | [#4] prototype.html Cycles:180 Average:4828.04 Median:5300.59 stddev:1281.10 (24.2%) stddev-sans-first:1241.25 13:59:30 INFO - PROCESS | 13049 | Values: 420.8 438.7 440.2 443.3 449.6 5288.7 5355.9 5364.0 5368.3 5378.5 5093.8 5255.9 5263.9 5279.2 5280.8 4357.3 4363.9 4364.8 4377.2 4398.0 4342.4 4367.8 4369.9 4383.8 4413.9 4371.8 4418.1 4421.7 4427.6 4448.0 4173.4 4340.5 4344.8 4353.5 4383.9 4062.7 4073.0 4118.3 4130.1 4130.9 5070.9 5112.0 5147.3 5153.8 5160.8 5010.3 5151.2 5171.9 5184.5 5186.5 5526.8 5532.3 5533.2 5558.6 5564.2 4657.7 4669.7 4702.0 4707.5 4710.5 5505.0 5519.7 5606.2 5625.0 5762.0 4688.6 4711.0 4723.7 4724.8 4794.9 4840.7 4840.9 4851.0 4859.8 4867.3 4983.9 5037.3 5114.5 5139.8 5213.7 5458.2 5467.5 5495.4 5564.0 5569.8 1647.7 1652.9 1667.1 1669.4 1669.7 1267.0 1274.8 1275.2 1298.5 1318.8 5224.3 5320.9 5327.3 5333.9 5383.3 5583.6 5616.4 5642.6 5644.7 5653.5 5045.6 5172.7 5248.4 5312.5 5372.9 2952.9 2972.9 2975.7 2992.1 2994.8 5621.2 5825.3 5848.2 5863.7 5873.7 5745.3 5765.9 5769.8 5771.6 5792.8 5796.4 5822.6 5827.8 5855.3 5870.0 5453.2 5602.3 5718.8 5730.5 5755.9 5527.9 5649.9 5692.5 5698.8 5731.0 5855.5 5864.2 5890.2 5917.0 5934.8 4768.5 4785.6 4794.9 4797.5 4813.3 5445.8 5686.4 5704.5 5757.0 5812.3 5543.4 5571.6 5587.4 5592.0 5593.9 5565.2 5565.5 5601.4 5607.5 5709.7 5352.6 5523.8 5559.2 5575.7 5581.8 5526.6 5558.4 5576.2 5576.5 5620.5 5258.6 5454.6 5473.9 5476.9 5509.0 13:59:30 INFO - PROCESS | 13049 | 13:59:30 INFO - PROCESS | 13049 | [#5] yui.html Cycles:180 Average:19792.90 Median:48846.27 stddev:25264.64 (51.7%) stddev-sans-first:25295.37 13:59:30 INFO - PROCESS | 13049 | Values: 828.4 854.7 923.0 933.6 947.1 16538.5 18739.8 18946.0 18952.7 19112.2 16054.4 17475.6 17579.0 17618.3 17625.6 896.5 903.5 909.3 912.3 912.3 890.6 901.9 903.0 906.1 906.1 957.3 959.2 960.1 960.7 960.9 946.1 947.6 949.0 949.2 949.3 700.7 702.5 704.3 704.7 704.7 18633.3 18700.6 18741.9 19110.0 19381.0 16346.3 16369.5 16635.7 16834.6 17143.3 12962.5 13140.2 13160.0 13344.0 13453.7 4046.4 4050.2 4071.2 4101.9 4199.1 88293.6 88545.0 89610.1 90020.0 91187.0 12431.2 12703.1 12745.1 12923.9 12944.8 24529.3 24662.6 24867.0 24963.1 25209.4 8337.5 8348.4 8459.7 8539.5 8640.0 4997.9 5095.2 5105.9 5230.5 5275.6 3814.4 3877.1 3885.0 3891.7 3930.1 641.2 644.9 650.2 650.9 664.0 670.3 677.0 679.2 692.3 696.7 11409.4 11461.5 11908.3 12051.1 12117.6 848.7 849.9 866.3 871.9 884.1 5520.1 5558.5 5559.3 5596.1 5652.2 87646.0 88190.6 88334.1 90591.5 92879.5 76970.0 78904.6 79857.0 81713.6 82076.6 68840.0 70107.6 70180.3 71267.3 72246.0 10759.9 10797.2 10958.5 11200.6 11208.7 48683.3 49009.3 49029.5 49278.0 49853.3 51113.0 52074.7 56644.4 57995.1 58256.4 14067.2 14081.2 14375.4 14440.0 14491.6 15978.4 16431.3 16651.3 16683.0 17120.1 12536.2 12743.2 14220.9 14688.9 15409.5 13392.6 13492.3 13503.7 13665.5 14269.9 14016.3 14096.8 15740.6 16543.0 17494.8 11162.4 11791.2 11974.4 12053.6 12088.2 13076.5 13202.6 13229.9 13343.9 13460.9 13:59:30 INFO - PROCESS | 13049 | -------- Summary: end -------- 13:59:30 INFO - PROCESS | 13049 | 13:59:31 INFO - TEST-INFO | 13049: exit 0 13:59:31 INFO - TEST-OK | dromaeo_css | took 1115119ms 13:59:31 INFO - TEST-START | kraken 13:59:31 INFO - operating with platform_type : linux_ 13:59:31 INFO - Initialising browser for kraken test... 13:59:31 INFO - TEST-INFO | started process 13352 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpve2IQD/profile http://localhost:52076/getInfo.html) 13:59:35 INFO - PROCESS | 13352 | __metrics Screen width/height:1600/1200 13:59:35 INFO - PROCESS | 13352 | colorDepth:24 13:59:35 INFO - PROCESS | 13352 | Browser inner width/height: 1024/697 13:59:35 INFO - PROCESS | 13352 | __metrics 13:59:35 INFO - PROCESS | 13352 | 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:59:35 INFO - PROCESS | 13352 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:59:36 INFO - TEST-INFO | 13352: exit 0 13:59:36 INFO - Browser initialized. 13:59:36 INFO - Running cycle 1/1 for kraken test... 13:59:36 INFO - TEST-INFO | started process 13442 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpve2IQD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:59:48 INFO - PROCESS | 13442 | RSS: Main: 141565952 13:59:48 INFO - PROCESS | 13442 | 13:59:51 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52076/tests/kraken/driver.html?testName=audio-beat-detection) 13:59:51 INFO - PROCESS | 13442 | RSS: Main: 184090624 13:59:51 INFO - PROCESS | 13442 | 13:59:54 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52076/tests/kraken/driver.html?testName=audio-dft) 13:59:55 INFO - PROCESS | 13442 | RSS: Main: 173682688 13:59:55 INFO - PROCESS | 13442 | 13:59:57 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52076/tests/kraken/driver.html?testName=audio-fft) 13:59:58 INFO - PROCESS | 13442 | RSS: Main: 265596928 13:59:58 INFO - PROCESS | 13442 | 14:00:00 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52076/tests/kraken/driver.html?testName=audio-oscillator) 14:00:01 INFO - PROCESS | 13442 | RSS: Main: 171905024 14:00:01 INFO - PROCESS | 13442 | 14:00:03 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52076/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:00:03 INFO - PROCESS | 13442 | RSS: Main: 174489600 14:00:03 INFO - PROCESS | 13442 | 14:00:06 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52076/tests/kraken/driver.html?testName=imaging-darkroom) 14:00:07 INFO - PROCESS | 13442 | RSS: Main: 209821696 14:00:07 INFO - PROCESS | 13442 | 14:00:10 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52076/tests/kraken/driver.html?testName=imaging-desaturate) 14:00:11 INFO - PROCESS | 13442 | RSS: Main: 212209664 14:00:11 INFO - PROCESS | 13442 | 14:00:14 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52076/tests/kraken/driver.html?testName=json-parse-financial) 14:00:14 INFO - PROCESS | 13442 | RSS: Main: 212033536 14:00:14 INFO - PROCESS | 13442 | 14:00:16 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52076/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:00:16 INFO - PROCESS | 13442 | RSS: Main: 174022656 14:00:16 INFO - PROCESS | 13442 | 14:00:18 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:00:18 INFO - PROCESS | 13442 | RSS: Main: 181280768 14:00:18 INFO - PROCESS | 13442 | 14:00:20 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:00:21 INFO - PROCESS | 13442 | RSS: Main: 190996480 14:00:21 INFO - PROCESS | 13442 | 14:00:24 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:00:24 INFO - PROCESS | 13442 | RSS: Main: 199651328 14:00:24 INFO - PROCESS | 13442 | 14:00:27 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:00:28 INFO - PROCESS | 13442 | RSS: Main: 195624960 14:00:28 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | Cycle 1(1): loaded http://localhost:52076/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52076/tests/kraken/driver.html?testName=ai-astar) 14:00:29 INFO - PROCESS | 13442 | RSS: Main: 246845440 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | __start_tp_report 14:00:29 INFO - PROCESS | 13442 | _x_x_mozilla_page_load 14:00:29 INFO - PROCESS | 13442 | _x_x_mozilla_page_load_details 14:00:29 INFO - PROCESS | 13442 | |i|pagename|runs| 14:00:29 INFO - PROCESS | 13442 | |0;ai-astar;103;113;113;113;114;113;111;112;112;111 14:00:29 INFO - PROCESS | 13442 | |1;audio-beat-detection;146;115;116;155;130;116;116;119;137;115 14:00:29 INFO - PROCESS | 13442 | |2;audio-dft;162;161;160;161;161;160;162;161;160;163 14:00:29 INFO - PROCESS | 13442 | |3;audio-fft;74;76;75;106;75;74;74;76;91;75 14:00:29 INFO - PROCESS | 13442 | |4;audio-oscillator;82;82;111;82;82;82;96;82;81;111 14:00:29 INFO - PROCESS | 13442 | |5;imaging-gaussian-blur;113;112;112;113;113;113;113;113;113;113 14:00:29 INFO - PROCESS | 13442 | |6;imaging-darkroom;147;147;147;146;146;147;147;146;147;147 14:00:29 INFO - PROCESS | 13442 | |7;imaging-desaturate;94;94;94;94;93;94;94;95;94;94 14:00:29 INFO - PROCESS | 13442 | |8;json-parse-financial;56;54;55;54;54;55;56;56;54;56 14:00:29 INFO - PROCESS | 13442 | |9;json-stringify-tinderbox;52;53;52;53;52;53;53;52;52;52 14:00:29 INFO - PROCESS | 13442 | |10;stanford-crypto-aes;76;85;76;80;79;87;76;76;79;80 14:00:29 INFO - PROCESS | 13442 | |11;stanford-crypto-ccm;128;120;120;119;118;118;126;118;156;117 14:00:29 INFO - PROCESS | 13442 | |12;stanford-crypto-pbkdf2;157;156;156;155;156;155;156;161;170;158 14:00:29 INFO - PROCESS | 13442 | |13;stanford-crypto-sha256-iterative;59;58;60;59;59;60;58;60;62;59 14:00:29 INFO - PROCESS | 13442 | __end_tp_report 14:00:29 INFO - PROCESS | 13442 | __start_cc_report 14:00:29 INFO - PROCESS | 13442 | _x_x_mozilla_cycle_collect,1631 14:00:29 INFO - PROCESS | 13442 | __end_cc_report 14:00:29 INFO - PROCESS | 13442 | __startTimestamp1495573229925__endTimestamp 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | ------- Summary: start ------- 14:00:29 INFO - PROCESS | 13442 | Number of tests: 14 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 14:00:29 INFO - PROCESS | 13442 | Values: 103.0 113.0 113.0 113.0 114.0 113.0 111.0 112.0 112.0 111.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:124.50 stddev:14.78 (11.9%) stddev-sans-first:13.89 14:00:29 INFO - PROCESS | 13442 | Values: 146.0 115.0 116.0 155.0 130.0 116.0 116.0 119.0 137.0 115.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 14:00:29 INFO - PROCESS | 13442 | Values: 162.0 161.0 160.0 161.0 161.0 160.0 162.0 161.0 160.0 163.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#3] audio-fft Cycles:10 Average:79.60 Median:75.00 stddev:10.60 (14.1%) stddev-sans-first:11.04 14:00:29 INFO - PROCESS | 13442 | Values: 74.0 76.0 75.0 106.0 75.0 74.0 74.0 76.0 91.0 75.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:82.00 stddev:12.36 (15.1%) stddev-sans-first:12.84 14:00:29 INFO - PROCESS | 13442 | Values: 82.0 82.0 111.0 82.0 82.0 82.0 96.0 82.0 81.0 111.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 14:00:29 INFO - PROCESS | 13442 | Values: 113.0 112.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 14:00:29 INFO - PROCESS | 13442 | Values: 147.0 147.0 147.0 146.0 146.0 147.0 147.0 146.0 147.0 147.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 14:00:29 INFO - PROCESS | 13442 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 95.0 94.0 94.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:0.94 (1.7%) stddev-sans-first:0.93 14:00:29 INFO - PROCESS | 13442 | Values: 56.0 54.0 55.0 54.0 54.0 55.0 56.0 56.0 54.0 56.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#9] json-stringify-tinderbox Cycles:10 Average:52.40 Median:52.50 stddev:0.52 (1.0%) stddev-sans-first:0.53 14:00:29 INFO - PROCESS | 13442 | Values: 52.0 53.0 52.0 53.0 52.0 53.0 53.0 52.0 52.0 52.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.50 stddev:3.89 (4.9%) stddev-sans-first:3.93 14:00:29 INFO - PROCESS | 13442 | Values: 76.0 85.0 76.0 80.0 79.0 87.0 76.0 76.0 79.0 80.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:120.00 stddev:11.82 (9.9%) stddev-sans-first:12.45 14:00:29 INFO - PROCESS | 13442 | Values: 128.0 120.0 120.0 119.0 118.0 118.0 126.0 118.0 156.0 117.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.00 Median:156.50 stddev:4.57 (2.9%) stddev-sans-first:4.83 14:00:29 INFO - PROCESS | 13442 | Values: 157.0 156.0 156.0 155.0 156.0 155.0 156.0 161.0 170.0 158.0 14:00:29 INFO - PROCESS | 13442 | 14:00:29 INFO - PROCESS | 13442 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.40 Median:59.50 stddev:1.17 (2.0%) stddev-sans-first:1.24 14:00:29 INFO - PROCESS | 13442 | Values: 59.0 58.0 60.0 59.0 59.0 60.0 58.0 60.0 62.0 59.0 14:00:29 INFO - PROCESS | 13442 | -------- Summary: end -------- 14:00:29 INFO - PROCESS | 13442 | 14:00:31 INFO - TEST-INFO | 13442: exit 0 14:00:31 INFO - TEST-OK | kraken | took 59839ms 14:00:31 INFO - SUITE-END | took 1174s 14:00:31 INFO - Completed test suite (00:19:35) 14:00:31 INFO - javascript benchmark 14:00:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7954.149899002556, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3380.509826075219, 3413.573303337083, 3423.366677164723, 3444.3627928833184, 3466.497357883107, 5788.160716696082, 5894.263217097863, 5896.145058966442, 5913.73388197531, 5935.821925342247, 5875.412458754123, 5918.585699001072, 5928.466438020578, 5933.465988061073, 5944.821655350346, 5235.476452354753, 5250.527452529272, 5252.185911183754, 5315.415304316522, 5334.359876814788, 5103.081445339837, 5131.050755610224, 5333.146696528556, 5346.091164502044, 5349.7057661828585, 5181.404138524062, 5223.294855194538, 5231.0, 5234.26720259164, 5235.502627250406, 5227.686338819664, 5239.266502689627, 5257.947420525784, 5263.210518422267, 5271.130263506517, 5207.7916883325, 5313.468653134694, 5316.069687804618, 5323.946760532383, 5324.307839980816, 5696.487316141525, 5879.79420720277, 5904.557158213108, 5973.492253158487, 5990.430909063632, 6140.473573783586, 6187.87624247517, 6251.28110267316, 6331.810045698637, 6348.968255158741, 6381.3937675920715, 6385.106085148083, 6395.712192951329, 6403.391677790602, 6403.583767055126, 5908.704564771744, 5910.527157827407, 5953.910691339634, 5971.850703732442, 5974.193483879708, 6152.077188421773, 6200.193974783249, 6328.5886417383645, 6381.074744162163, 6396.744130234739, 6223.1287619733275, 6232.3144454110015, 6235.438810507076, 6235.625862448268, 6242.188515493048, 6297.4017468340435, 6341.0, 6344.746210151542, 6345.143405640272, 6347.619142851444, 6049.485793707496, 6101.206843110367, 6164.630122192682, 6234.906476402857, 6314.0844577536, 6309.747610095636, 6309.810705678837, 6315.526335524854, 6315.684215789192, 6323.241211054611, 4599.701019433726, 4630.536946305342, 4631.004334068191, 4732.148213321635, 4772.785224664898, 4241.066965267636, 4262.957370426256, 4271.039016221325, 4280.208161490134, 4281.571842815693, 5793.507551858139, 5831.825045248649, 5867.413258674183, 6014.248218972629, 6016.338202797689, 6294.775336470719, 6298.149749783812, 6305.716242769086, 6327.715252813635, 6329.778457754049, 5941.287045554561, 5941.762329506858, 5947.5539334550085, 5951.900914489993, 5982.102684597258, 5129.256257842667, 5264.184051472028, 5265.894682106336, 5266.947330526646, 5269.0252303324005, 6387.425131738166, 6487.18910136233, 6564.573302735403, 6625.271220165874, 6634.668266586747, 6350.7777227796805, 6360.427561519486, 6373.2989371168205, 6375.968120159369, 6378.6810659467765, 6471.482281417381, 6585.341465853377, 6594.505412094208, 6603.702833372414, 6624.940009598441, 5988.760449581968, 6029.939700602938, 6132.4166970161305, 6135.600157732601, 6248.718807653758, 6333.809985700436, 6361.681915904093, 6363.427291543783, 6372.840678983062, 6374.139491168633, 6156.476699480594, 6164.815055548341, 6166.938330616816, 6167.814965551041, 6173.876522469435, 5918.082697181943, 5976.521878249817, 6096.664683442454, 6098.390160983866, 6113.871604717183, 6125.601746185282, 6162.506999440124, 6181.289151747606, 6185.628862268279, 6191.0, 6185.721642526007, 6227.782027629011, 6235.251769787655, 6237.781677641261, 6243.250809902841, 5808.477237048686, 5830.591858569859, 5964.671943043174, 5993.670348130895, 6053.182820319201], "value": 5776.277486344096, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.5905270206789, 840.5587066789934, 849.566720972304, 918.1690570034117, 931.4132096779036, 32514.837425812868, 45128.32307515386, 45426.0, 45551.77224113883, 45742.771286143565, 44863.77568112155, 45042.54957450424, 45258.773706131506, 45268.0, 45268.77365613176, 8091.554964476952, 8256.752297431056, 9243.35296529245, 9366.34435589509, 10855.945720271387, 10875.347479151236, 11017.559297628086, 11023.393713345762, 11025.173112016591, 11027.117830573536, 11060.446977651085, 11066.446677666125, 11071.667849964473, 11090.057345125673, 11104.111671066295, 11027.393493357862, 11054.281471704313, 11059.170562207786, 11060.834087488694, 11061.557537698482, 10776.946115269451, 10838.891611083945, 10854.077403420719, 10855.728606784814, 10861.0, 42775.35836962448, 42928.35607465891, 43023.0, 43051.0, 43148.56851431477, 4354.716943398669, 4389.385486031958, 4411.0, 4452.955470445287, 4526.162659907927, 30209.848950755324, 30689.69310306913, 30863.0, 30870.228244294074, 32141.678583214103, 4369.978150109261, 4401.64786817057, 4410.911781764346, 4417.359482874997, 4483.618892394151, 231472.0, 232465.0, 232538.0, 233394.0, 234196.0, 53892.46107538875, 54015.0, 54239.72880135653, 54248.72875635675, 54254.45745542573, 57253.42746572564, 57268.71365643145, 57382.0, 57403.71298143482, 57450.712746436, 16774.41289554884, 17192.31230750781, 17210.483685489206, 17217.655646886993, 17219.913900430416, 12866.228026318451, 13039.478420863248, 14738.115713057037, 15052.172130532927, 15120.319585618674, 13452.394642241123, 13534.255615941218, 13572.93213533926, 13577.456901723821, 13604.387802548908, 5598.216249725042, 5682.374938756733, 5704.486596206361, 5743.655380677173, 5831.708414579254, 1028.1774580335702, 1034.0590063042746, 1038.9532471038822, 1042.7288904884786, 1048.9423081730426, 29181.7081829179, 29224.123276301743, 29288.414231715247, 29558.704412956024, 29601.85199073991, 1100.4497751124438, 1102.7298311913632, 1128.9604863829727, 1141.9029382502415, 1148.5750272399266, 15112.848871511143, 15877.52367428979, 15973.920130399507, 15991.200439977954, 16012.359505619877, 308019.0, 308380.0, 309579.0, 309707.0, 311480.0, 58345.70827145922, 58354.70822645944, 58380.4161958375, 58450.41549584535, 58500.41499585035, 1489.8286697029764, 1500.932458039391, 1533.5246073717183, 1551.8525740054677, 1571.4107209796327, 28566.0, 28636.0, 29289.41421171522, 29769.553456697733, 30001.399972000876, 24273.39316517101, 24377.878110609334, 24387.26838194857, 24431.14490992807, 24434.63348049745, 285537.0, 285761.0, 286319.0, 286985.0, 287441.0, 25711.10011149601, 27733.44533109367, 29031.0, 30397.544036839023, 31336.529952051194, 38257.23485530329, 38271.0, 38299.23401532009, 38448.423273650355, 39164.804175980076, 30466.238344041576, 30582.541261881535, 30608.234794130323, 31269.687303126677, 31682.683173167974, 30009.249768755955, 30341.0, 30726.385472290876, 32761.836190818893, 32801.83599081989, 35798.0, 36648.81675591605, 36970.26059478742, 37235.81382093072, 37276.813615931744, 30561.54157687592, 30826.69173308238, 31111.0, 31158.844205778827, 31221.441519723576, 25589.744102558736, 25601.743982559936, 25621.231363059138, 25731.35671608225, 25738.742612573635], "value": 18813.359213884745, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.88891549197444, 477.6417686734949, 487.86339824849057, 493.0408817197901, 498.25510861262404, 20755.273565425214, 21977.791221482814, 22294.665580016313, 22557.323280301574, 22764.77235227647, 22633.321000369975, 22910.541789164206, 22991.655125173093, 23145.884270578623, 23360.415989600267, 6680.498962577814, 7344.1554221264705, 7589.316961473466, 7616.504927179715, 7626.809329766745, 7406.925930740677, 7515.32362087412, 7574.053243344582, 7623.047119110111, 7662.425318101137, 7394.630268486554, 7657.081150261976, 7658.463907526475, 7661.502002369828, 7662.387009039264, 7500.0, 7604.7338343158, 7607.239276072404, 7613.124490683587, 7620.276073772982, 7280.781576552712, 7368.521046131984, 7441.181470038291, 7472.551646901149, 7476.439267054992, 25822.74177258241, 25844.0, 25957.0, 25989.74010259892, 26690.33274168142, 3522.4892390603477, 3619.6199399063244, 3623.565172179329, 3684.410494320896, 3688.5020522229424, 15962.0, 15985.600359990976, 16352.0, 16632.0, 16965.57586060346, 2187.7921597448217, 2230.877301748403, 2242.136777340719, 2312.8727919964394, 2323.3262353917344, 110860.44569777181, 120002.0, 121207.0, 121576.0, 121651.0, 16546.917265413595, 16831.455507013037, 16945.661086778196, 17781.0, 18202.726959095624, 31493.8425307872, 31642.0, 31645.20886977798, 31672.0, 31749.0, 10334.379937203792, 10463.895361046445, 10659.880712525228, 10665.413402262795, 10874.238803283693, 8826.91173088261, 8877.112288771072, 8936.285097192209, 9104.908950910407, 9169.22061624756, 8953.686620968236, 9177.77055573616, 9323.72028839136, 9411.90588094124, 9541.570629321697, 3366.562346894888, 3675.6691897729334, 3743.5320584926885, 3830.942535861964, 3874.1089549403796, 961.293449314753, 966.9371490853072, 967.4388854464535, 994.786120983992, 1012.6050840172338, 21648.89175554112, 22172.223972160897, 22205.22281720132, 22303.442413939458, 22559.436014099778, 970.9417434953925, 975.2441857560448, 976.9071938165863, 995.7610173558294, 1000.0, 11345.0, 12072.879271207352, 12203.572874949334, 12242.265464072185, 12305.0, 645527.0, 647993.0, 667961.0, 670201.0, 682826.0, 78269.60865195637, 93684.0, 104466.0, 115233.42383288198, 124460.37769811093, 1256.9874301257053, 1290.1936289818864, 1300.4603089717662, 1309.4500309869877, 1313.7963615639592, 19340.516487087374, 19521.0, 19879.701804472654, 19912.303069392503, 20029.899850500653, 54058.729706351216, 54059.729701352815, 54689.45310547003, 54818.725906370215, 54984.725076375966, 71902.0, 72175.0, 72330.63834680793, 72758.27241727727, 73600.26399735935, 12272.938635306766, 12444.066694998093, 12686.555970540987, 12953.352332383154, 13036.087473876738, 14031.719365612835, 14064.578062658136, 14492.202928839015, 14505.85494145045, 14702.41190352374, 11821.704457388634, 11906.34515101643, 11971.221870578385, 12016.699582510508, 12186.268823870596, 11384.943075284571, 11477.655670329903, 11536.423178841193, 11612.535498579962, 11699.415029248674, 11401.087912967108, 11576.768464630492, 11588.304701717587, 11865.822012669643, 11975.64073077809, 12513.687157821127, 12656.240625562496, 12658.936705316783, 12763.489460421479, 12875.781054736315, 11217.831732524182, 11271.830922536332, 11323.263987840764, 11327.32036077838, 11660.30038197711], "value": 11748.2860568518, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.5906064423245, 1604.5186444066776, 1621.9432319868804, 1625.8049034115909, 1627.662280023737, 10962.232643714942, 11663.883361166385, 11665.883341166586, 11669.24149930254, 11675.0, 10871.565137394495, 11108.055815255713, 11120.277181983185, 11145.72135696608, 11417.143714221445, 2501.1496091328945, 2523.634073059405, 2533.5946248600226, 2538.530371881199, 2540.2633236361526, 2482.2925466242123, 2486.9129580464687, 2543.8473691578474, 2546.898124075035, 2549.7577730115704, 2392.617181251, 2423.6243382275775, 2497.1509686706518, 2532.3542496663326, 2534.5057713745828, 2419.7459266777, 2466.2971053249826, 2473.418746594549, 2473.913413030535, 2490.091116742394, 2346.1319311854554, 2403.483251101022, 2412.3969007748065, 2418.1053010386267, 2418.250342393846, 11207.21549491536, 11356.034737047361, 11428.942855285753, 11429.142814288878, 11436.42817859112, 5930.1994230779155, 6089.543284253698, 6101.115338275969, 6112.7554897804475, 6118.449339559416, 3590.5870824855215, 3672.7429079964413, 3703.925921481555, 3722.3485889969147, 3764.1154328733, 1287.4785711786742, 1330.6360979995486, 1340.1021315718492, 1340.2628554295195, 1358.6195865157772, 17716.645667086716, 17734.55663608407, 17835.0, 17956.640867182585, 17981.1009449527, 4848.393950756155, 5018.774155163026, 5028.823991160292, 5032.79868805251, 5127.333446651912, 12936.353182340845, 12985.0, 12985.155964862253, 12992.220466772023, 12993.350332483338, 6049.425304596057, 6051.334353221142, 6092.5430592705725, 6096.69516524172, 6096.786612468542, 5393.379761327419, 5512.062949298629, 5565.972170139124, 5582.218489411485, 5631.605787594911, 3926.391409331558, 4037.636612704812, 4037.7779222143063, 4079.2453396121814, 4108.794560271975, 1027.4246422003703, 1033.1837848099917, 1038.771470276538, 1050.4012712753656, 1051.8842927277992, 1031.437866362834, 1036.1658864614058, 1047.261680515235, 1048.528162326956, 1052.894710528941, 6762.661866906635, 6914.654267286615, 6964.303569642995, 6984.650767461607, 7053.788386348418, 1040.9011143941466, 1087.7443800706828, 1098.8846171151913, 1112.2659045030402, 1119.283658458587, 4331.112122014979, 4519.412476378116, 4524.163029839425, 4609.423822022247, 4658.37111989877, 13557.389917453738, 14010.0, 14620.634484137981, 15163.924180379028, 15201.315940782692, 12138.757224855453, 14507.419703211754, 14513.92743036278, 14528.709425811425, 14532.709345813024, 9120.45277283365, 10227.28409011377, 10456.73858153537, 10804.621838235782, 10814.188935829687, 7504.399648028254, 7648.617569121633, 7767.883481747891, 7838.137804841577, 7929.088154862265, 12756.234625922474, 13076.607701768962, 13291.867081329063, 13366.131201471873, 13415.932920335335, 13236.933815330862, 13322.600321990356, 13332.200067995564, 13456.46174153023, 13471.595852124452, 9931.95034024854, 10198.286119971532, 10203.540840662039, 10207.693769186937, 10210.540525676213, 11107.500162492868, 11277.548898043986, 11288.0, 11380.089592832719, 11436.027937625062, 9432.245420366491, 9468.431894086378, 9486.905130948879, 9532.618695252113, 9589.184919281939, 9198.632054718004, 9259.305552083486, 9317.254619630277, 9371.390859594105, 9394.154526092685, 8163.224493673091, 8206.302464290602, 8283.50298982063, 8286.46138001028, 8398.622062007102, 6548.901766473411, 6670.199576050905, 6699.253306144457, 6729.226138994079, 6792.354726300993, 6593.769218077345, 6660.533762636569, 6731.966340168267, 6735.292794256533, 6924.792256232321], "value": 5487.867682655151, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.8274607410962, 438.70387488445294, 440.2032321498088, 443.28409618466145, 449.6065942300487, 5288.709120998344, 5355.866103347418, 5364.0, 5368.32895888014, 5378.489043540857, 5093.770780314895, 5255.896261785029, 5263.86840328992, 5279.155335146377, 5280.815171468999, 4357.257097161129, 4363.869083927487, 4364.847230346939, 4377.233984052794, 4398.0, 4342.413774140498, 4367.803448844794, 4369.934450983238, 4383.846565370197, 4413.867583972486, 4371.781410929457, 4418.1384629997165, 4421.668374871898, 4427.557244275579, 4447.954730638297, 4173.39485774564, 4340.500842403132, 4344.7827608619755, 4353.499347575038, 4383.890402739925, 4062.6546743526837, 4072.9817545613596, 4118.299889018874, 4130.132672138854, 4130.938035929464, 5070.873228169326, 5112.035019688572, 5147.305113809625, 5153.819616313411, 5160.8193713220235, 5010.348654674869, 5151.175811870083, 5171.922421163648, 5184.533391994701, 5186.50728180826, 5526.778928842882, 5532.28080349556, 5533.197686335499, 5558.610347413147, 5564.1931919871395, 4657.69724967876, 4669.696469729491, 4701.976490117528, 4707.5057119002695, 4710.5289471052965, 5504.972475137639, 5519.668819870821, 5606.187102870061, 5625.006245565694, 5761.971190144064, 4688.5545873142, 4710.9764451177525, 4723.669343146015, 4724.834630787906, 4794.904101918012, 4840.709557426566, 4840.903181936412, 4850.975745121323, 4859.829905953346, 4867.342908707279, 4983.875403114951, 5037.29477873098, 5114.539691427789, 5139.794408223629, 5213.661112027706, 5458.235846981426, 5467.480589344006, 5495.395506494282, 5563.970665426909, 5569.777208911679, 1647.7281248594088, 1652.9421470248483, 1667.1497536256597, 1669.3656410564151, 1669.7078011347965, 1266.9864108712993, 1274.8024056271292, 1275.1573975605347, 1298.5130576033987, 1318.8483324417623, 5224.320838290998, 5320.893582128336, 5327.280817089721, 5333.893322133613, 5383.327084114486, 5583.6320101575375, 5616.438356164351, 5642.645765016448, 5644.661320320712, 5653.547716182696, 5045.562014825757, 5172.732680493304, 5248.3701955765555, 5312.468753124656, 5372.865678358073, 2952.9261768455963, 2972.895948641787, 2975.6577993530586, 2992.132281638344, 2994.7903646744953, 5621.241132447149, 5825.300963884276, 5848.157830283447, 5863.736131874076, 5873.676947767914, 5745.2818397700285, 5765.913511297334, 5769.769209231667, 5771.633630211593, 5792.768289268466, 5796.3913789052385, 5822.602575381878, 5827.79602713903, 5855.326637436664, 5870.0, 5453.2365468833605, 5602.299712535933, 5718.771249149988, 5730.484256416942, 5755.8561035974435, 5527.944720552904, 5649.943500565106, 5692.459216374438, 5698.77747678414, 5730.9713451434145, 5855.531557475307, 5864.237649105589, 5890.2342695449315, 5916.970415148069, 5934.821955341346, 4768.546988036131, 4785.641076919175, 4794.897173650011, 4797.520247975314, 4813.326134341125, 5445.782168713207, 5686.43135686428, 5704.543636508986, 5757.0, 5812.273465816773, 5543.417941116125, 5571.637843540157, 5587.357453892854, 5591.9720401397735, 5593.944060559343, 5565.1930470082425, 5565.471280228372, 5601.439856014366, 5607.523360514238, 5709.714514274353, 5352.584118102503, 5523.784767351176, 5559.24950131727, 5575.665460072409, 5581.804636837691, 5526.640768350045, 5558.3607885092115, 5576.219329293821, 5576.498115169654, 5620.522255608155, 5258.605604579749, 5454.618176727591, 5473.863153421037, 5476.945230547643, 5509.025126431682], "value": 4460.418016273663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.3704384667654, 854.7179430787841, 923.0190248878413, 933.6239271055164, 947.1097168661457, 16538.503844884643, 18739.81260187398, 18946.0, 18952.715709264372, 19112.235510579576, 16054.43809466669, 17475.5631109222, 17579.0, 17618.29526818929, 17625.55936101598, 896.4755617963499, 903.5301643145593, 909.3180114913814, 912.2792993535132, 912.3157631776168, 890.5992303463435, 901.9413738107035, 903.029243563169, 906.0712769411373, 906.1165363770338, 957.3203025851653, 959.2134449751206, 960.1214888377161, 960.7021823234785, 960.9135177833994, 946.0775743649955, 947.5830634520789, 948.9667861624879, 949.1505102932871, 949.349695458613, 700.6916956539158, 702.4801646781397, 704.3449591879549, 704.6547191875944, 704.68993642797, 18633.347832825923, 18700.62598748031, 18741.90629046846, 19110.0, 19381.0, 16346.3028600282, 16369.508914732578, 16635.6672866542, 16834.579135521588, 17143.314267429287, 12962.481500740054, 13140.211587304697, 13160.012999025112, 13344.0, 13453.66365840852, 4046.433499310099, 4050.23045621331, 4071.1654110907193, 4101.938470922939, 4199.118185181115, 88293.55853220692, 88545.0, 89610.10389896148, 90020.0, 91187.0, 12431.191972521758, 12703.11078224534, 12745.107842451125, 12923.935380323039, 12944.8058279124, 24529.264122076365, 24662.630060549105, 24867.0, 24963.12629057974, 25209.369765756004, 8337.541435221121, 8348.373871959628, 8459.746207613782, 8539.530325832138, 8640.049594544595, 4997.90004199914, 5095.184770436712, 5105.897882042413, 5230.503102205284, 5275.630705850554, 3814.3896976484184, 3877.0501227199516, 3885.0287428142965, 3891.669208117285, 3930.1353702186047, 641.2369280556123, 644.9064885591657, 650.180772226988, 650.9153810004669, 663.9933600663932, 670.316277397062, 676.9559978601375, 679.1680191765048, 692.3388164303078, 696.6690821859576, 11409.372484513431, 11461.544226013571, 11908.345041022654, 12051.096167787451, 12117.636470905887, 848.6562942008496, 849.8640217565284, 866.3372520022189, 871.8930768126854, 884.1379654836483, 5520.06158952979, 5558.527525160407, 5559.305086864142, 5596.104623260258, 5652.152177173457, 87646.0, 88190.55904720436, 88334.11665883387, 90591.54704226437, 92879.53560232291, 76970.0, 78904.60547697225, 79857.0, 81713.59143204246, 82076.58961705273, 68840.0, 70107.64946175237, 70180.2981970184, 71267.28732712606, 72246.0, 10759.89240107589, 10797.190210734068, 10958.45207739594, 11200.551977921117, 11208.71978200519, 48683.269750953055, 49009.2648610264, 49029.50970490249, 49278.0, 49853.252201217736, 51113.0, 52074.73962630163, 56644.433555665564, 57995.123191767016, 58256.4174358251, 14067.155970641794, 14081.155130692192, 14375.424983000563, 14440.0, 14491.637709057359, 15978.440754573534, 16431.342746290014, 16651.250693719056, 16683.0, 17120.14399280056, 12536.247825130522, 12743.235405875677, 14220.93796399537, 14688.853111468748, 15409.460668876536, 13392.598222053353, 13492.257925814174, 13503.662408439475, 13665.453381864614, 14269.928650356682, 14016.29016736648, 14096.754135057829, 15740.606484837512, 16543.0, 17494.825051749318, 11162.44187790591, 11791.174617776673, 11974.401279936143, 12053.63839084829, 12088.214266072677, 13076.542321018718, 13202.603921882359, 13229.867701322864, 13343.866561334262, 13460.865391345962], "value": 8103.204472317976, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 114.0, 113.0, 111.0, 112.0, 112.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 116.0, 155.0, 130.0, 116.0, 116.0, 119.0, 137.0, 115.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 161.0, 160.0, 162.0, 161.0, 160.0, 163.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 106.0, 75.0, 74.0, 74.0, 76.0, 91.0, 75.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 111.0, 82.0, 82.0, 82.0, 96.0, 82.0, 81.0, 111.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 54.0, 54.0, 55.0, 56.0, 56.0, 54.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 53.0, 52.0, 53.0, 53.0, 52.0, 52.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 85.0, 76.0, 80.0, 79.0, 87.0, 76.0, 76.0, 79.0, 80.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 120.0, 120.0, 119.0, 118.0, 118.0, 126.0, 118.0, 156.0, 117.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 156.0, 155.0, 156.0, 155.0, 156.0, 161.0, 170.0, 158.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 60.0, 59.0, 59.0, 60.0, 58.0, 60.0, 62.0, 59.0], "value": 59.4, "unit": "score"}]}]} 14:00:31 INFO - Return code: 0 14:00:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:00:31 INFO - # TBPL SUCCESS # 14:00:31 INFO - Running post-action listener: _resource_record_post_action 14:00:31 INFO - [mozharness: 2017-05-23 21:00:31.714010Z] Finished run-tests step (success) 14:00:31 INFO - Running post-run listener: _resource_record_post_run 14:00:31 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 315392; Write bytes: 329097216; Read time: 112; Write time: 161080 14:00:31 INFO - TinderboxPrint: CPU usage
13.1% 14:00:31 INFO - TinderboxPrint: I/O read bytes / time
315,392 / 112 14:00:31 INFO - TinderboxPrint: I/O write bytes / time
329,097,216 / 161,080 14:00:31 INFO - TinderboxPrint: CPU idle
8,256.6 (86.9%) 14:00:31 INFO - TinderboxPrint: CPU user
1,198.3 (12.6%) 14:00:31 INFO - TinderboxPrint: Swap in / out
0 / 0 14:00:31 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 4096; Write bytes: 30461952; Read time: 52; Write time: 6144 14:00:31 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 307200; Write bytes: 298061824; Read time: 56; Write time: 154908 14:00:32 INFO - Running post-run listener: _upload_blobber_files 14:00:32 INFO - Blob upload gear active. 14:00:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:00:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:00:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:00:32 INFO - (blobuploader) - INFO - Open directory for files ... 14:00:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:00:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:00:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:33 INFO - (blobuploader) - INFO - Done attempting. 14:00:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:00:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:00:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:34 INFO - (blobuploader) - INFO - Done attempting. 14:00:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:00:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:00:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:35 INFO - (blobuploader) - INFO - Done attempting. 14:00:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:00:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:00:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:41 INFO - (blobuploader) - INFO - Done attempting. 14:00:41 INFO - (blobuploader) - INFO - Iteration through files over. 14:00:41 INFO - Return code: 0 14:00:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:00:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:00:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15c25ff75e49f2d150198a2410ce93efd86009bcdf74070b5861448294bcbaedaf6b0eead3f5de8475753af223e85fa210afb72c6bdd9307cf16a3774b9e9d80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/815a40e3d9e1fe15765e753371c71797396e93e9029beb9c9c8f8ee2ae4d39305cfec1b2568fd218e8f0cb6339232048a2412f8198fdefa0e55a5ff79fbf44dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dda84ff753a0a0997bd3c1e747323df83142b3c414503624effb0777e93258b2b5af89a1b5a3b572085b265fec50c98643c323d1dfd838176e26163d313746d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c4656ccafed38845409695f9c9ae302b146741a7859fa6012b6ad61f9a8ccb0c451e40cc14a52f7fb63bfc2031d216610a599cedcc276861d1bbc0f02e27d11e"} 14:00:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:00:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:00:41 INFO - Contents: 14:00:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15c25ff75e49f2d150198a2410ce93efd86009bcdf74070b5861448294bcbaedaf6b0eead3f5de8475753af223e85fa210afb72c6bdd9307cf16a3774b9e9d80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/815a40e3d9e1fe15765e753371c71797396e93e9029beb9c9c8f8ee2ae4d39305cfec1b2568fd218e8f0cb6339232048a2412f8198fdefa0e55a5ff79fbf44dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dda84ff753a0a0997bd3c1e747323df83142b3c414503624effb0777e93258b2b5af89a1b5a3b572085b265fec50c98643c323d1dfd838176e26163d313746d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c4656ccafed38845409695f9c9ae302b146741a7859fa6012b6ad61f9a8ccb0c451e40cc14a52f7fb63bfc2031d216610a599cedcc276861d1bbc0f02e27d11e"} 14:00:41 INFO - Running post-run listener: copy_logs_to_upload_dir 14:00:41 INFO - Copying logs to upload dir... 14:00:41 INFO - mkdir: /builds/slave/test/build/upload/logs 14:00:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.712025 ========= master_lag: -0.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-23 14:00:40.987418) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 14:00:40.991024) ========= 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=20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358 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/15c25ff75e49f2d150198a2410ce93efd86009bcdf74070b5861448294bcbaedaf6b0eead3f5de8475753af223e85fa210afb72c6bdd9307cf16a3774b9e9d80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/815a40e3d9e1fe15765e753371c71797396e93e9029beb9c9c8f8ee2ae4d39305cfec1b2568fd218e8f0cb6339232048a2412f8198fdefa0e55a5ff79fbf44dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dda84ff753a0a0997bd3c1e747323df83142b3c414503624effb0777e93258b2b5af89a1b5a3b572085b265fec50c98643c323d1dfd838176e26163d313746d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c4656ccafed38845409695f9c9ae302b146741a7859fa6012b6ad61f9a8ccb0c451e40cc14a52f7fb63bfc2031d216610a599cedcc276861d1bbc0f02e27d11e"} build_url:https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/AMwfbFrDQXyt7imB1yMX6Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15c25ff75e49f2d150198a2410ce93efd86009bcdf74070b5861448294bcbaedaf6b0eead3f5de8475753af223e85fa210afb72c6bdd9307cf16a3774b9e9d80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/815a40e3d9e1fe15765e753371c71797396e93e9029beb9c9c8f8ee2ae4d39305cfec1b2568fd218e8f0cb6339232048a2412f8198fdefa0e55a5ff79fbf44dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dda84ff753a0a0997bd3c1e747323df83142b3c414503624effb0777e93258b2b5af89a1b5a3b572085b265fec50c98643c323d1dfd838176e26163d313746d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c4656ccafed38845409695f9c9ae302b146741a7859fa6012b6ad61f9a8ccb0c451e40cc14a52f7fb63bfc2031d216610a599cedcc276861d1bbc0f02e27d11e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 14:00:41.021928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 14:00:41.022330) ========= 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=20e20d796406ba5117c4a6f1000001ba-1495571993.987351-954670358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 14:00:41.111207) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 14:00:41.111638) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 14:00:41.112047) ========= ========= Total master_lag: -0.09 =========