builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-077 starttime: 1495482080.83 results: success (0) revision: 5de3b1528ca0947dfde7b1aef7a42f5984b5ee89 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:20.831990) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:20.832420) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:20.832685) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:20.857142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:20.857491) ========= 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-1495480170.388738-1768667795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-22 12:41:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2017-05-22 12:41:22 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103220 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:20.980210) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:20.980500) ========= 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-1495480170.388738-1768667795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020225 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:21.034278) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:21.034567) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5de3b1528ca0947dfde7b1aef7a42f5984b5ee89 --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 5de3b1528ca0947dfde7b1aef7a42f5984b5ee89 --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-1495480170.388738-1768667795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-22 12:41:22,293 truncating revision to first 12 chars 2017-05-22 12:41:22,293 Setting DEBUG logging. 2017-05-22 12:41:22,293 attempt 1/10 2017-05-22 12:41:22,293 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5de3b1528ca0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 12:41:22,615 unpacking tar archive at: mozilla-beta-5de3b1528ca0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.766356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:21.823068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:21.826407) ========= script_repo_revision: 5de3b1528ca0947dfde7b1aef7a42f5984b5ee89 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:21.826772) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:21.827025) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 12:41:21.837256) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-22 12:41:21.837632) ========= /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-1495480170.388738-1768667795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:41:23 INFO - MultiFileLogger online at 20170522 12:41:23 in /builds/slave/test 12:41:23 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 12:41:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:41:23 INFO - {'append_to_log': False, 12:41:23 INFO - 'base_work_dir': '/builds/slave/test', 12:41:23 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 12:41:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:41:23 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 12:41:23 INFO - 'buildbot_json_path': 'buildprops.json', 12:41:23 INFO - 'config_files': ('talos/linux_config.py',), 12:41:23 INFO - 'default_actions': ('clobber', 12:41:23 INFO - 'read-buildbot-config', 12:41:23 INFO - 'download-and-extract', 12:41:23 INFO - 'populate-webroot', 12:41:23 INFO - 'create-virtualenv', 12:41:23 INFO - 'install', 12:41:23 INFO - 'run-tests'), 12:41:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:41:23 INFO - 'download_minidump_stackwalk': True, 12:41:23 INFO - 'download_symbols': 'ondemand', 12:41:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:41:23 INFO - 'tooltool.py': '/tools/tooltool.py', 12:41:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:41:23 INFO - '/tools/misc-python/virtualenv.py')}, 12:41:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:41:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:41:23 INFO - 'gecko_profile': False, 12:41:23 INFO - 'gecko_profile_interval': 0, 12:41:23 INFO - 'installer_path': 'installer.exe', 12:41:23 INFO - 'log_level': 'info', 12:41:23 INFO - 'log_name': 'talos', 12:41:23 INFO - 'log_to_console': True, 12:41:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:41:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:41:23 INFO - 'opt_config_files': (), 12:41:23 INFO - 'pip_index': False, 12:41:23 INFO - 'suite': 'dromaeojs', 12:41:23 INFO - 'system_bits': '32', 12:41:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:41:23 INFO - 'title': 'talos-linux64-ix-077', 12:41:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:41:23 INFO - 'use_talos_json': True, 12:41:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:41:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:41:23 INFO - 'work_dir': 'build'} 12:41:23 INFO - [mozharness: 2017-05-22 19:41:23.185394Z] Running clobber step. 12:41:23 INFO - Running pre-action listener: _resource_record_pre_action 12:41:23 INFO - Running main action method: clobber 12:41:23 INFO - rmtree: /builds/slave/test/build 12:41:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:41:24 INFO - Running post-action listener: _resource_record_post_action 12:41:24 INFO - [mozharness: 2017-05-22 19:41:24.222541Z] Finished clobber step (success) 12:41:24 INFO - [mozharness: 2017-05-22 19:41:24.222640Z] Running read-buildbot-config step. 12:41:24 INFO - Running pre-action listener: _resource_record_pre_action 12:41:24 INFO - Running main action method: read_buildbot_config 12:41:24 INFO - Using buildbot properties: 12:41:24 INFO - { 12:41:24 INFO - "project": "", 12:41:24 INFO - "product": "firefox", 12:41:24 INFO - "who": "ryanvm@gmail.com", 12:41:24 INFO - "installer_path": "public/build/target.tar.bz2", 12:41:24 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:41:24 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 12:41:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 12:41:24 INFO - "stage_platform": "linux64", 12:41:24 INFO - "basedir": "/builds/slave/test", 12:41:24 INFO - "buildnumber": 54, 12:41:24 INFO - "platform": "ubuntu64_hw", 12:41:24 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:41:24 INFO - "slavebuilddir": "test", 12:41:24 INFO - "taskId": "Gm-ybggURSekiGJii1KLow", 12:41:24 INFO - "branch": "mozilla-beta", 12:41:24 INFO - "script_repo_revision": "production", 12:41:24 INFO - "revision": "5de3b1528ca0947dfde7b1aef7a42f5984b5ee89", 12:41:24 INFO - "slavename": "talos-linux64-ix-077", 12:41:24 INFO - "repo_path": "releases/mozilla-beta" 12:41:24 INFO - } 12:41:24 INFO - Finding installer, test and symbols from parent task. 12:41:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gm-ybggURSekiGJii1KLow'}, attempt #1 12:41:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gm-ybggURSekiGJii1KLow'}, attempt #1 12:41:25 INFO - Task dependencies: WXranwPGSl65o-qsjXzvpw 12:41:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw'}, attempt #1 12:41:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2 12:41:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/test_packages.json 12:41:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.crashreporter-symbols.zip 12:41:25 INFO - Running post-action listener: _resource_record_post_action 12:41:25 INFO - [mozharness: 2017-05-22 19:41:25.571813Z] Finished read-buildbot-config step (success) 12:41:25 INFO - [mozharness: 2017-05-22 19:41:25.572029Z] Running download-and-extract step. 12:41:25 INFO - Running pre-action listener: _resource_record_pre_action 12:41:25 INFO - Running main action method: download_and_extract 12:41:25 INFO - mkdir: /builds/slave/test/build/tests 12:41:25 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')]} 12:41:25 INFO - trying https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.test_packages.json 12:41:25 INFO - Downloading https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:41:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:41:26 INFO - Downloaded 986 bytes. 12:41:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:41:26 INFO - Using the following test package requirements: 12:41:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:41:26 INFO - u'common': [u'target.common.tests.zip'], 12:41:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:41:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:41:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:41:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:41:26 INFO - u'mozbase': [u'target.common.tests.zip'], 12:41:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:41:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:41:26 INFO - u'web-platform': [u'target.common.tests.zip', 12:41:26 INFO - u'target.web-platform.tests.zip'], 12:41:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:41:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:41:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.common.tests.zip 12:41:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:41:26 INFO - Fetch https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.common.tests.zip into memory 12:41:28 INFO - Expected file size: 59713949 12:41:28 INFO - Obtained file size: 59713949 12:41:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:41:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.common.tests.zip 12:41:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:41:38 INFO - Fetch https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.common.tests.zip into memory 12:41:39 INFO - Expected file size: 59713949 12:41:39 INFO - Obtained file size: 59713949 12:41:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.talos.tests.zip 12:41:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:41:49 INFO - Fetch https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.talos.tests.zip into memory 12:41:50 INFO - Expected file size: 14404449 12:41:50 INFO - Obtained file size: 14404449 12:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:50 INFO - trying https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2 12:41:50 INFO - Downloading https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:41:52 INFO - Downloaded 59882049 bytes. 12:41:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2 12:41:52 INFO - mkdir: /builds/slave/test/properties 12:41:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:41:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:41:52 INFO - Contents: 12:41:52 INFO - build_url:https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2 12:41:52 INFO - Running post-action listener: _resource_record_post_action 12:41:52 INFO - Running post-action listener: set_extra_try_arguments 12:41:52 INFO - [mozharness: 2017-05-22 19:41:52.894548Z] Finished download-and-extract step (success) 12:41:52 INFO - [mozharness: 2017-05-22 19:41:52.894699Z] Running populate-webroot step. 12:41:52 INFO - Running pre-action listener: _resource_record_pre_action 12:41:52 INFO - Running main action method: populate_webroot 12:41:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:41:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:41:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:41:52 INFO - '--tppagecycles', 12:41:52 INFO - '7'], 12:41:52 INFO - 'tests': ['tsvgm']}}, 12:41:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:41:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:41:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:41:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:41:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['damp', 'tps']}, 12:41:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'tests': ['damp', 'tps']}, 12:41:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['dromaeo_dom']}, 12:41:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:41:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['basic_compositor_video']}, 12:41:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:41:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other-e10s': {'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tsvgx', 12:41:52 INFO - 'tsvgr_opacity', 12:41:52 INFO - 'tart', 12:41:52 INFO - 'tscrollx', 12:41:52 INFO - 'cart', 12:41:52 INFO - 'tsvg_static']}, 12:41:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:41:52 INFO - 'tsvgr_opacity', 12:41:52 INFO - 'tart', 12:41:52 INFO - 'tscrollx', 12:41:52 INFO - 'cart', 12:41:52 INFO - 'tsvg_static']}, 12:41:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tp5o']}, 12:41:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:41:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s', 12:41:52 INFO - '--xperf_path', 12:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:52 INFO - 'tests': ['tp5n']}, 12:41:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--xperf_path', 12:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:52 INFO - 'tests': ['tp5n']}}, 12:41:52 INFO - 'talos.zip': {'path': '', 12:41:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:41:52 INFO - Running post-action listener: _resource_record_post_action 12:41:52 INFO - [mozharness: 2017-05-22 19:41:52.912368Z] Finished populate-webroot step (success) 12:41:52 INFO - [mozharness: 2017-05-22 19:41:52.912459Z] Running create-virtualenv step. 12:41:52 INFO - Running pre-action listener: _resource_record_pre_action 12:41:52 INFO - Running main action method: create_virtualenv 12:41:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:41:52 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 12:41:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:41:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:41:53 INFO - Using real prefix '/usr' 12:41:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:41:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:41:55 INFO - Installing pip.................done. 12:41:55 INFO - Return code: 0 12:41:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:41:55 INFO - Reading from file tmpfile_stdout 12:41:55 INFO - Output received: 12:41:55 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) 12:41:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:41: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')]} 12:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41: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')]} 12:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:41:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:41:55 INFO - Using env: {'DISPLAY': ':0', 12:41:55 INFO - 'HOME': '/home/cltbld', 12:41:55 INFO - 'LANG': 'en_US.UTF-8', 12:41:55 INFO - 'LANGUAGE': 'en_US:en', 12:41:55 INFO - 'LOGNAME': 'cltbld', 12:41:55 INFO - 'MAIL': '/var/mail/cltbld', 12:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:55 INFO - 'MOZ_NO_REMOTE': '1', 12:41:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:55 INFO - 'NO_EM_RESTART': '1', 12:41:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:55 INFO - 'PWD': '/builds/slave/test', 12:41:55 INFO - 'SHELL': '/bin/bash', 12:41:55 INFO - 'SHLVL': '1', 12:41:55 INFO - 'TERM': 'linux', 12:41:55 INFO - 'TMOUT': '86400', 12:41:55 INFO - 'USER': 'cltbld', 12:41:55 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:55 INFO - '_': '/tools/buildbot/bin/python'} 12:41:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:55 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 12:41:55 INFO - Cleaning up... 12:41:55 INFO - Return code: 0 12:41:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:41: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')]} 12:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41: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')]} 12:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:41:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:41:55 INFO - Using env: {'DISPLAY': ':0', 12:41:55 INFO - 'HOME': '/home/cltbld', 12:41:55 INFO - 'LANG': 'en_US.UTF-8', 12:41:55 INFO - 'LANGUAGE': 'en_US:en', 12:41:55 INFO - 'LOGNAME': 'cltbld', 12:41:55 INFO - 'MAIL': '/var/mail/cltbld', 12:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:55 INFO - 'MOZ_NO_REMOTE': '1', 12:41:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:55 INFO - 'NO_EM_RESTART': '1', 12:41:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:55 INFO - 'PWD': '/builds/slave/test', 12:41:55 INFO - 'SHELL': '/bin/bash', 12:41:55 INFO - 'SHLVL': '1', 12:41:55 INFO - 'TERM': 'linux', 12:41:55 INFO - 'TMOUT': '86400', 12:41:55 INFO - 'USER': 'cltbld', 12:41:55 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:55 INFO - '_': '/tools/buildbot/bin/python'} 12:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:56 INFO - Downloading/unpacking psutil>=3.1.1 12:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:41:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:41:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:41:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:41:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:41:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:41:57 INFO - Installing collected packages: psutil 12:41:57 INFO - Running setup.py install for psutil 12:41:57 INFO - building 'psutil._psutil_linux' extension 12:41:57 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 12:41:57 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 12:41:57 INFO - building 'psutil._psutil_posix' extension 12:41:57 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 12:41:57 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 12:41:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:41:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:41:57 INFO - Successfully installed psutil 12:41:57 INFO - Cleaning up... 12:41:57 INFO - Return code: 0 12:41:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:57 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:41:57 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 12:41:57 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 12:41:57 INFO - Using env: {'DISPLAY': ':0', 12:41:57 INFO - 'HOME': '/home/cltbld', 12:41:57 INFO - 'LANG': 'en_US.UTF-8', 12:41:57 INFO - 'LANGUAGE': 'en_US:en', 12:41:57 INFO - 'LOGNAME': 'cltbld', 12:41:57 INFO - 'MAIL': '/var/mail/cltbld', 12:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:57 INFO - 'MOZ_NO_REMOTE': '1', 12:41:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:57 INFO - 'NO_EM_RESTART': '1', 12:41:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:57 INFO - 'PWD': '/builds/slave/test', 12:41:57 INFO - 'SHELL': '/bin/bash', 12:41:57 INFO - 'SHLVL': '1', 12:41:57 INFO - 'TERM': 'linux', 12:41:57 INFO - 'TMOUT': '86400', 12:41:57 INFO - 'USER': 'cltbld', 12:41:57 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:57 INFO - '_': '/tools/buildbot/bin/python'} 12:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:41:58 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 12:41:58 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 12:41:58 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 12:41:58 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 12:41:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:41:59 INFO - Installing collected packages: mozsystemmonitor 12:41:59 INFO - Running setup.py install for mozsystemmonitor 12:41:59 INFO - Successfully installed mozsystemmonitor 12:41:59 INFO - Cleaning up... 12:41:59 INFO - Return code: 0 12:41:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:41:59 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')]} 12:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:59 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')]} 12:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:59 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:41:59 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 12:41:59 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 12:41:59 INFO - Using env: {'DISPLAY': ':0', 12:41:59 INFO - 'HOME': '/home/cltbld', 12:41:59 INFO - 'LANG': 'en_US.UTF-8', 12:41:59 INFO - 'LANGUAGE': 'en_US:en', 12:41:59 INFO - 'LOGNAME': 'cltbld', 12:41:59 INFO - 'MAIL': '/var/mail/cltbld', 12:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:59 INFO - 'MOZ_NO_REMOTE': '1', 12:41:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:59 INFO - 'NO_EM_RESTART': '1', 12:41:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:59 INFO - 'PWD': '/builds/slave/test', 12:41:59 INFO - 'SHELL': '/bin/bash', 12:41:59 INFO - 'SHLVL': '1', 12:41:59 INFO - 'TERM': 'linux', 12:41:59 INFO - 'TMOUT': '86400', 12:41:59 INFO - 'USER': 'cltbld', 12:41:59 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:59 INFO - '_': '/tools/buildbot/bin/python'} 12:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:59 INFO - Downloading/unpacking jsonschema==2.5.1 12:41:59 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 12:41:59 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 12:41:59 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 12:41:59 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 12:42:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:42:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:42:00 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 12:42:00 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 12:42:00 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 12:42:00 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 12:42:00 INFO - Downloading functools32-3.2.3-2.tar.gz 12:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:42:00 INFO - warning: no files found matching '*.txt' 12:42:00 INFO - no previously-included directories found matching 'build' 12:42:00 INFO - no previously-included directories found matching 'dist' 12:42:00 INFO - no previously-included directories found matching '.git*' 12:42:00 INFO - Installing collected packages: jsonschema, functools32 12:42:00 INFO - Running setup.py install for functools32 12:42:00 INFO - warning: no files found matching '*.txt' 12:42:00 INFO - no previously-included directories found matching 'build' 12:42:00 INFO - no previously-included directories found matching 'dist' 12:42:00 INFO - no previously-included directories found matching '.git*' 12:42:01 INFO - Successfully installed jsonschema functools32 12:42:01 INFO - Cleaning up... 12:42:01 INFO - Return code: 0 12:42:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:01 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:42:01 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 12:42:01 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 12:42:01 INFO - Using env: {'DISPLAY': ':0', 12:42:01 INFO - 'HOME': '/home/cltbld', 12:42:01 INFO - 'LANG': 'en_US.UTF-8', 12:42:01 INFO - 'LANGUAGE': 'en_US:en', 12:42:01 INFO - 'LOGNAME': 'cltbld', 12:42:01 INFO - 'MAIL': '/var/mail/cltbld', 12:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:01 INFO - 'MOZ_NO_REMOTE': '1', 12:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:01 INFO - 'NO_EM_RESTART': '1', 12:42:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:01 INFO - 'PWD': '/builds/slave/test', 12:42:01 INFO - 'SHELL': '/bin/bash', 12:42:01 INFO - 'SHLVL': '1', 12:42:01 INFO - 'TERM': 'linux', 12:42:01 INFO - 'TMOUT': '86400', 12:42:01 INFO - 'USER': 'cltbld', 12:42:01 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:01 INFO - '_': '/tools/buildbot/bin/python'} 12:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:42:01 INFO - Cleaning up... 12:42:01 INFO - Return code: 0 12:42:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:01 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:42:01 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 12:42:01 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 12:42:01 INFO - Using env: {'DISPLAY': ':0', 12:42:01 INFO - 'HOME': '/home/cltbld', 12:42:01 INFO - 'LANG': 'en_US.UTF-8', 12:42:01 INFO - 'LANGUAGE': 'en_US:en', 12:42:01 INFO - 'LOGNAME': 'cltbld', 12:42:01 INFO - 'MAIL': '/var/mail/cltbld', 12:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:01 INFO - 'MOZ_NO_REMOTE': '1', 12:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:01 INFO - 'NO_EM_RESTART': '1', 12:42:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:01 INFO - 'PWD': '/builds/slave/test', 12:42:01 INFO - 'SHELL': '/bin/bash', 12:42:01 INFO - 'SHLVL': '1', 12:42:01 INFO - 'TERM': 'linux', 12:42:01 INFO - 'TMOUT': '86400', 12:42:01 INFO - 'USER': 'cltbld', 12:42:01 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:01 INFO - '_': '/tools/buildbot/bin/python'} 12:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:01 INFO - Downloading/unpacking blobuploader==1.2.4 12:42:01 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 12:42:01 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 12:42:01 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 12:42:01 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 12:42:02 INFO - Downloading blobuploader-1.2.4.tar.gz 12:42:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:42:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:42:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:03 INFO - Downloading docopt-0.6.1.tar.gz 12:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:42:03 INFO - Installing collected packages: blobuploader, requests, docopt 12:42:03 INFO - Running setup.py install for blobuploader 12:42:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:42:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:42:03 INFO - Running setup.py install for requests 12:42:03 INFO - Running setup.py install for docopt 12:42:03 INFO - Successfully installed blobuploader requests docopt 12:42:03 INFO - Cleaning up... 12:42:03 INFO - Return code: 0 12:42:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:03 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')]} 12:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:03 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')]} 12:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:03 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:42:03 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 12:42:03 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 12:42:03 INFO - Using env: {'DISPLAY': ':0', 12:42:03 INFO - 'HOME': '/home/cltbld', 12:42:03 INFO - 'LANG': 'en_US.UTF-8', 12:42:03 INFO - 'LANGUAGE': 'en_US:en', 12:42:03 INFO - 'LOGNAME': 'cltbld', 12:42:03 INFO - 'MAIL': '/var/mail/cltbld', 12:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:03 INFO - 'MOZ_NO_REMOTE': '1', 12:42:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:03 INFO - 'NO_EM_RESTART': '1', 12:42:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:03 INFO - 'PWD': '/builds/slave/test', 12:42:03 INFO - 'SHELL': '/bin/bash', 12:42:03 INFO - 'SHLVL': '1', 12:42:03 INFO - 'TERM': 'linux', 12:42:03 INFO - 'TMOUT': '86400', 12:42:03 INFO - 'USER': 'cltbld', 12:42:03 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:03 INFO - '_': '/tools/buildbot/bin/python'} 12:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:03 INFO - Running setup.py (path:/tmp/pip-99AwtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:04 INFO - Running setup.py (path:/tmp/pip-f_tGU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:04 INFO - Running setup.py (path:/tmp/pip-2CH8hL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:04 INFO - Running setup.py (path:/tmp/pip-E0ty4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:04 INFO - Running setup.py (path:/tmp/pip-WpwhVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:04 INFO - Running setup.py (path:/tmp/pip-BE15sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:04 INFO - Running setup.py (path:/tmp/pip-f8GeOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:04 INFO - Running setup.py (path:/tmp/pip-VD0cMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:04 INFO - Running setup.py (path:/tmp/pip-gdboBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:04 INFO - Running setup.py (path:/tmp/pip-C31Rm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:04 INFO - Running setup.py (path:/tmp/pip-LpHaIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:04 INFO - Running setup.py (path:/tmp/pip-fn4uwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:04 INFO - Running setup.py (path:/tmp/pip-hytgq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:04 INFO - Running setup.py (path:/tmp/pip-Xd09NM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:05 INFO - Running setup.py (path:/tmp/pip-ovfWRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:05 INFO - Running setup.py (path:/tmp/pip-1O99IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:05 INFO - Running setup.py (path:/tmp/pip-0ONr4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:42:05 INFO - Running setup.py install for manifestparser 12:42:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozcrash 12:42:05 INFO - Running setup.py install for mozdebug 12:42:05 INFO - Running setup.py install for mozdevice 12:42:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozfile 12:42:05 INFO - Running setup.py install for mozhttpd 12:42:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozinfo 12:42:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:42:05 INFO - Running setup.py install for mozInstall 12:42:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Running setup.py install for mozleak 12:42:06 INFO - Running setup.py install for mozlog 12:42:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Running setup.py install for moznetwork 12:42:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Running setup.py install for mozprocess 12:42:06 INFO - Running setup.py install for mozprofile 12:42:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Running setup.py install for mozrunner 12:42:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Running setup.py install for mozscreenshot 12:42:06 INFO - Running setup.py install for moztest 12:42:06 INFO - Running setup.py install for mozversion 12:42:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:42:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:42:06 INFO - Cleaning up... 12:42:07 INFO - Return code: 0 12:42:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:07 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')]} 12:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:07 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')]} 12:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:07 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:42:07 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 12:42:07 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 12:42:07 INFO - Using env: {'DISPLAY': ':0', 12:42:07 INFO - 'HOME': '/home/cltbld', 12:42:07 INFO - 'LANG': 'en_US.UTF-8', 12:42:07 INFO - 'LANGUAGE': 'en_US:en', 12:42:07 INFO - 'LOGNAME': 'cltbld', 12:42:07 INFO - 'MAIL': '/var/mail/cltbld', 12:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:07 INFO - 'MOZ_NO_REMOTE': '1', 12:42:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:07 INFO - 'NO_EM_RESTART': '1', 12:42:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:07 INFO - 'PWD': '/builds/slave/test', 12:42:07 INFO - 'SHELL': '/bin/bash', 12:42:07 INFO - 'SHLVL': '1', 12:42:07 INFO - 'TERM': 'linux', 12:42:07 INFO - 'TMOUT': '86400', 12:42:07 INFO - 'USER': 'cltbld', 12:42:07 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:07 INFO - '_': '/tools/buildbot/bin/python'} 12:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:07 INFO - Running setup.py (path:/tmp/pip-N1YAvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:07 INFO - Running setup.py (path:/tmp/pip-4itabq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:07 INFO - Running setup.py (path:/tmp/pip-DtWFOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:07 INFO - Running setup.py (path:/tmp/pip-PJveWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:07 INFO - Running setup.py (path:/tmp/pip-LvWVRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:07 INFO - Running setup.py (path:/tmp/pip-UxAzLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:07 INFO - Running setup.py (path:/tmp/pip-KEbERS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:07 INFO - Running setup.py (path:/tmp/pip-4kMmzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:07 INFO - Running setup.py (path:/tmp/pip-FvTPoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:07 INFO - Running setup.py (path:/tmp/pip-Ue0XAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:07 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)) 12:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:07 INFO - Running setup.py (path:/tmp/pip-5mYBIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:08 INFO - Running setup.py (path:/tmp/pip-NlLsVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:08 INFO - Running setup.py (path:/tmp/pip-l_H5hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:08 INFO - Running setup.py (path:/tmp/pip-YSdVqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:08 INFO - Running setup.py (path:/tmp/pip-uamlEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:08 INFO - Running setup.py (path:/tmp/pip-_Dy23k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:08 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)) 12:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:08 INFO - Running setup.py (path:/tmp/pip-qn4AdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:09 INFO - Downloading blessings-1.6.tar.gz 12:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:42:09 INFO - Installing collected packages: blessings 12:42:09 INFO - Running setup.py install for blessings 12:42:09 INFO - Successfully installed blessings 12:42:09 INFO - Cleaning up... 12:42:09 INFO - Return code: 0 12:42:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:42:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:09 INFO - Reading from file tmpfile_stdout 12:42:09 INFO - Current package versions: 12:42:09 INFO - argparse == 1.2.1 12:42:09 INFO - blessings == 1.6 12:42:09 INFO - blobuploader == 1.2.4 12:42:09 INFO - docopt == 0.6.1 12:42:09 INFO - functools32 == 3.2.3-2 12:42:09 INFO - jsonschema == 2.5.1 12:42:09 INFO - manifestparser == 1.1 12:42:09 INFO - mozInstall == 1.12 12:42:09 INFO - mozcrash == 1.0 12:42:09 INFO - mozdebug == 0.1 12:42:09 INFO - mozdevice == 0.49 12:42:09 INFO - mozfile == 1.2 12:42:09 INFO - mozhttpd == 0.7 12:42:09 INFO - mozinfo == 0.9 12:42:09 INFO - mozleak == 0.1 12:42:09 INFO - mozlog == 3.4 12:42:09 INFO - moznetwork == 0.27 12:42:09 INFO - mozprocess == 0.25 12:42:09 INFO - mozprofile == 0.28 12:42:09 INFO - mozrunner == 6.13 12:42:09 INFO - mozscreenshot == 0.1 12:42:09 INFO - mozsystemmonitor == 0.3 12:42:09 INFO - moztest == 0.8 12:42:09 INFO - mozversion == 1.4 12:42:09 INFO - psutil == 3.1.1 12:42:09 INFO - requests == 1.2.3 12:42:09 INFO - wsgiref == 0.1.2 12:42:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:10 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:42:10 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 12:42:10 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 12:42:10 INFO - Using env: {'DISPLAY': ':0', 12:42:10 INFO - 'HOME': '/home/cltbld', 12:42:10 INFO - 'LANG': 'en_US.UTF-8', 12:42:10 INFO - 'LANGUAGE': 'en_US:en', 12:42:10 INFO - 'LOGNAME': 'cltbld', 12:42:10 INFO - 'MAIL': '/var/mail/cltbld', 12:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:10 INFO - 'MOZ_NO_REMOTE': '1', 12:42:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:10 INFO - 'NO_EM_RESTART': '1', 12:42:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:10 INFO - 'PWD': '/builds/slave/test', 12:42:10 INFO - 'SHELL': '/bin/bash', 12:42:10 INFO - 'SHLVL': '1', 12:42:10 INFO - 'TERM': 'linux', 12:42:10 INFO - 'TMOUT': '86400', 12:42:10 INFO - 'USER': 'cltbld', 12:42:10 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:10 INFO - '_': '/tools/buildbot/bin/python'} 12:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 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)) 12:42:10 INFO - Cleaning up... 12:42:10 INFO - Return code: 0 12:42:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:42:10 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')]} 12:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:10 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')]} 12:42:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:10 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 0x10c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1365310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, '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-1495480170.388738-1768667795', '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 12:42:10 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 12:42:10 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 12:42:10 INFO - Using env: {'DISPLAY': ':0', 12:42:10 INFO - 'HOME': '/home/cltbld', 12:42:10 INFO - 'LANG': 'en_US.UTF-8', 12:42:10 INFO - 'LANGUAGE': 'en_US:en', 12:42:10 INFO - 'LOGNAME': 'cltbld', 12:42:10 INFO - 'MAIL': '/var/mail/cltbld', 12:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:10 INFO - 'MOZ_NO_REMOTE': '1', 12:42:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:10 INFO - 'NO_EM_RESTART': '1', 12:42:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:10 INFO - 'PWD': '/builds/slave/test', 12:42:10 INFO - 'SHELL': '/bin/bash', 12:42:10 INFO - 'SHLVL': '1', 12:42:10 INFO - 'TERM': 'linux', 12:42:10 INFO - 'TMOUT': '86400', 12:42:10 INFO - 'USER': 'cltbld', 12:42:10 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:10 INFO - '_': '/tools/buildbot/bin/python'} 12:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:42:10 INFO - Cleaning up... 12:42:10 INFO - Return code: 0 12:42:10 INFO - Running post-action listener: _resource_record_post_action 12:42:10 INFO - Running post-action listener: _start_resource_monitoring 12:42:10 INFO - Starting resource monitoring. 12:42:10 INFO - [mozharness: 2017-05-22 19:42:10.478564Z] Finished create-virtualenv step (success) 12:42:10 INFO - [mozharness: 2017-05-22 19:42:10.479038Z] Running install step. 12:42:10 INFO - Running pre-action listener: _resource_record_pre_action 12:42:10 INFO - Running main action method: install 12:42:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:10 INFO - Reading from file tmpfile_stdout 12:42:10 INFO - Detecting whether we're running mozinstall >=1.0... 12:42:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:42:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:42:10 INFO - Reading from file tmpfile_stdout 12:42:10 INFO - Output received: 12:42:10 INFO - Usage: mozinstall [options] installer 12:42:10 INFO - Options: 12:42:10 INFO - -h, --help show this help message and exit 12:42:10 INFO - -d DEST, --destination=DEST 12:42:10 INFO - Directory to install application into. [default: 12:42:10 INFO - "/builds/slave/test"] 12:42:10 INFO - --app=APP Application being installed. [default: firefox] 12:42:10 INFO - mkdir: /builds/slave/test/build/application 12:42:10 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'] 12:42:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:42:24 INFO - Reading from file tmpfile_stdout 12:42:24 INFO - Output received: 12:42:24 INFO - /builds/slave/test/build/application/firefox/firefox 12:42:24 INFO - Running post-action listener: _resource_record_post_action 12:42:24 INFO - [mozharness: 2017-05-22 19:42:24.850414Z] Finished install step (success) 12:42:24 INFO - [mozharness: 2017-05-22 19:42:24.850657Z] Running run-tests step. 12:42:24 INFO - Running pre-action listener: _resource_record_pre_action 12:42:24 INFO - Running main action method: run_tests 12:42:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:42:24 INFO - Python 2.7.3 12:42:24 INFO - Return code: 0 12:42:24 INFO - grabbing minidump binary from tooltool 12:42:24 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')]} 12:42:24 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 0x1362400>, '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 0x13633b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1363840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:42:24 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 12:42:24 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 12:42:24 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 12:42:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:42:24 INFO - Return code: 0 12:42:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:42:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:42:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:42:24 INFO - ENV: RUST_BACKTRACE is now 1 12:42:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:42:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:42:24 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/WXranwPGSl65o-qsjXzvpw/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 12:42:24 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/WXranwPGSl65o-qsjXzvpw/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 12:42:24 INFO - Using env: {'DISPLAY': ':0', 12:42:24 INFO - 'HOME': '/home/cltbld', 12:42:24 INFO - 'LANG': 'en_US.UTF-8', 12:42:24 INFO - 'LANGUAGE': 'en_US:en', 12:42:24 INFO - 'LOGNAME': 'cltbld', 12:42:24 INFO - 'MAIL': '/var/mail/cltbld', 12:42:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:42:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:24 INFO - 'MOZ_NO_REMOTE': '1', 12:42:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:42:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:24 INFO - 'NO_EM_RESTART': '1', 12:42:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:24 INFO - 'PWD': '/builds/slave/test', 12:42:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:42:24 INFO - 'RUST_BACKTRACE': '1', 12:42:24 INFO - 'SHELL': '/bin/bash', 12:42:24 INFO - 'SHLVL': '1', 12:42:24 INFO - 'TERM': 'linux', 12:42:24 INFO - 'TMOUT': '86400', 12:42:24 INFO - 'USER': 'cltbld', 12:42:24 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1495480170.388738-1768667795', 12:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:24 INFO - '_': '/tools/buildbot/bin/python'} 12:42:24 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/WXranwPGSl65o-qsjXzvpw/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 12:42:25 INFO - mozversion application_buildid: 20170522181711 12:42:25 INFO - mozversion application_changeset: 5de3b1528ca0947dfde7b1aef7a42f5984b5ee89 12:42:25 INFO - mozversion application_display_name: Firefox 12:42:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:42:25 INFO - mozversion application_name: Firefox 12:42:25 INFO - mozversion application_remotingname: firefox 12:42:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:42:25 INFO - mozversion application_vendor: Mozilla 12:42:25 INFO - mozversion application_version: 54.0 12:42:25 INFO - mozversion platform_buildid: 20170522181711 12:42:25 INFO - mozversion platform_changeset: 5de3b1528ca0947dfde7b1aef7a42f5984b5ee89 12:42:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:42:25 INFO - mozversion platform_version: 54.0 12:42:25 INFO - using testdate: 1495482145 12:42:25 INFO - actual date: 1495482145 12:42:25 INFO - starting webserver on 'localhost:44715' 12:42:25 INFO - SUITE-START | Running 2 tests 12:42:25 INFO - TEST-START | dromaeo_css 12:42:25 INFO - operating with platform_type : linux_ 12:42:25 INFO - Initialising browser for dromaeo_css test... 12:42:25 INFO - TEST-INFO | started process 6635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBTx8GG/profile http://localhost:44715/getInfo.html) 12:42:29 INFO - PROCESS | 6635 | __metrics Screen width/height:1600/1200 12:42:29 INFO - PROCESS | 6635 | colorDepth:24 12:42:29 INFO - PROCESS | 6635 | Browser inner width/height: 1024/697 12:42:29 INFO - PROCESS | 6635 | __metrics 12:42:30 INFO - PROCESS | 6635 | 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" 12:42:30 INFO - PROCESS | 6635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:42:31 INFO - TEST-INFO | 6635: exit 0 12:42:31 INFO - Browser initialized. 12:42:31 INFO - Running cycle 1/1 for dromaeo_css test... 12:42:31 INFO - TEST-INFO | started process 6727 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBTx8GG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:42:42 INFO - PROCESS | 6727 | RSS: Main: 140845056 12:42:42 INFO - PROCESS | 6727 | 12:45:44 INFO - PROCESS | 6727 | Cycle 1(1): loaded http://localhost:44715/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44715/tests/dromaeo/cssquery-ext.html) 12:45:45 INFO - PROCESS | 6727 | RSS: Main: 246714368 12:45:45 INFO - PROCESS | 6727 | 12:48:48 INFO - PROCESS | 6727 | Cycle 1(1): loaded http://localhost:44715/tests/dromaeo/cssquery-ext.html (next: http://localhost:44715/tests/dromaeo/cssquery-jquery.html) 12:48:48 INFO - PROCESS | 6727 | RSS: Main: 227643392 12:48:48 INFO - PROCESS | 6727 | 12:51:50 INFO - PROCESS | 6727 | Cycle 1(1): loaded http://localhost:44715/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44715/tests/dromaeo/cssquery-mootools.html) 12:51:50 INFO - PROCESS | 6727 | RSS: Main: 175661056 12:51:50 INFO - PROCESS | 6727 | 12:54:52 INFO - PROCESS | 6727 | Cycle 1(1): loaded http://localhost:44715/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44715/tests/dromaeo/cssquery-prototype.html) 12:54:53 INFO - PROCESS | 6727 | RSS: Main: 171753472 12:54:53 INFO - PROCESS | 6727 | 12:57:56 INFO - PROCESS | 6727 | Cycle 1(1): loaded http://localhost:44715/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44715/tests/dromaeo/cssquery-yui.html) 12:57:56 INFO - PROCESS | 6727 | RSS: Main: 175509504 12:57:56 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | Cycle 1(1): loaded http://localhost:44715/tests/dromaeo/cssquery-yui.html (next: http://localhost:44715/tests/dromaeo/cssquery-dojo.html) 13:00:59 INFO - PROCESS | 6727 | RSS: Main: 270258176 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | __start_tp_report 13:00:59 INFO - PROCESS | 6727 | _x_x_mozilla_page_load 13:00:59 INFO - PROCESS | 6727 | _x_x_mozilla_page_load_details 13:00:59 INFO - PROCESS | 6727 | |i|pagename|runs| 13:00:59 INFO - PROCESS | 6727 | |0;dojo.html;3389.135770378553;3426.0749597608647;3444.0356700123957;3458.118179864134;3499.877504287349;5709.257796486456;5800.970995145019;5822.767089316433;5825.970870145648;5838.503727183189;5783.5373170146395;5794.739236734357;5841.503472204857;5845.7369418376165;5851.941480585193;5177.171652535595;5192.844214673566;5201.9479805201845;5258.894822103555;5262.763175657085;5087.186050231945;5119.257707632391;5287.550558202538;5301.41684414716;5302.973485132569;5130.333056702623;5160.25176349431;5179.533841954223;5219.45195754446;5232.45059268777;5243.10867152585;5244.606654500909;5251.949610077962;5262.473752624745;5265.183896496049;5163;5185.766640501187;5229.137192363255;5233.712145831978;5258.81594144207;5572.219889215513;5604.467575580314;5610.607257491977;5623.887522249573;5780.364159942403;6112.32111068014;6114.755409783604;6124.418180272867;6126.540509461808;6130.448259656652;6242.937570624281;6250.124982502408;6269.216347956505;6274.498040156776;6279.780207692709;5792.710364481759;5822.068469044975;5833.066709326572;5850.297964244275;5863.061910094364;6122.051082082285;6125.41859352575;6247.843803904848;6340.4293613575;6349.0476428536085;6118.832384214075;6121.422488707753;6133.76314253068;6134.222293197073;6137.358988059894;6188.195534580475;6210.627362358273;6225.3774622537385;6230.345813689588;6235.781747638811;5977.1333156692035;6023.608465449819;6198.783042593487;6205.5345849061505;6208.658523781145;6116.724747386378;6120.234970628671;6124.116761842097;6128.014278093809;6131.713108457284;4694.788734506956;4710.29345598163;4711.1519926413575;4715.717056976592;4734.289856521549;4111.60939710727;4180.1012782251355;4185.602367775056;4192.392103145027;4212.199682060399;5799.739011744482;5819.330776960619;5841.649501029951;5845.824625261249;5923.318818335946;6133.080037994337;6172.321044685081;6192.040233763774;6194.5354098442795;6202.472789812826;5919.526437884959;5921.970390148022;5926.733297001645;5929.525637948954;5937.406259374028;5089.923651145235;5121.487851214849;5165.586753059822;5230.34620672416;5232.3459567554055;6387.712552935129;6393.1369265148605;6393.552451328363;6398.936010639834;6401.9039714404325;6184.041473571604;6184.81445556634;6184.876302474016;6191.288001879841;6192.442680158807;6220.129181914626;6245.969415046467;6339.144215531029;6385.29761726219;6395.680215989275;6062.363451837494;6064.332923378337;6129.756146212572;6133.457080270768;6138.508919286357;6058.151858739692;6096.177016102769;6261.843453913506;6268.561200716089;6268.655223962726;6004.789832355847;6004.939950600438;6033.637981721111;6037.63774173551;6142.539309551891;5899.587028907935;6123.785667501616;6132.2947860996555;6133.049377346519;6134.263888333428;5989.52083833301;6037;6042.969785151046;6125.264968203844;6131.233595800525;6114.714308451384;6137.324894261538;6147.108669243024;6150.907736384047;6153.66154861469;5998.670073145844;6005.279366476051;6010.639361638316;6013.879722405615;6030.457258846549 13:00:59 INFO - PROCESS | 6727 | |1;ext.html;775.4649291988532;854.4232642965997;861.6208868098037;936.1948724097275;945.8959514453413;31872;45037.77481112594;45591.316130258034;45758.542414575844;45844.083118337614;41991;42134.789326053404;42189.367159492635;46252;46309.76845115778;7723.420743444264;7905.525668459911;9230.353875228737;9372.625094996192;9378;10748.516316765765;10954.123670106412;10957.726056848564;10961.451927403637;10963.83554246687;11115.555377784878;11116.777664446707;11151.219414640978;11153.386563738992;11155;11034.227604067719;11053.834192487118;11057.668269951873;11075.390853503053;11079.279846810012;10700.571977120906;10749.408782516959;10757.515911783988;10760;10761;43034.784826075986;43109.13781724348;43202;43228.3515747264;43246.35130473045;4317.0934103838235;4354.782260886943;4390.999999999968;4478.820847166109;4479.3281007848755;30335.544966825517;30515.61552907968;30620.693793062008;30815;32248.6775132248;4170.562090980434;4224.54253282617;4246.4691913510815;4276.444062271916;4313.590208930146;239848;240049;240084;241191.99999999825;241573;54197.1870421944;54389.72805135949;54402;54429.45570544323;54444;57456.71271643615;57459;57521;57527.424725753044;57556.71221643949;15654.217289135726;16191.919040404959;16202.108884011257;16204.75692864608;16208;12252.264864108181;12420.068494862922;13355.931525477938;14036.789448158286;14051.156930584199;14026.438942442392;14077.788833167511;14111.506097286545;14163.929180354033;14175.362108705132;5686.431356864363;5786.855328616734;5812.941870581325;5824.388878895112;5950.107483877453;1023.4166525080633;1028.6451174344838;1036.108946306176;1049.0872940541778;1056.3556230699267;29384.559231611544;29643.55534666982;29827;30263;30392.240193995327;1168.784464157267;1176.611718133014;1177.4054102678265;1202.5250026239587;1222.3643705273387;14603.707925841423;14739.55781326562;14926.179060151795;14973.550793476214;15000.849991500163;342686;343111;343937;344514;345290;50000;50008.74995624999;50057.49942500601;50097.74951125221;50148.49851501511;1295.9805602915965;1302.5571305756089;1304.3478260869565;1322.5966080345395;1507.3593722667822;28611.570826438037;28887.7111228885;29143;29528.557071643514;29855.552166717953;38194.809025954695;38503.807480962416;38588.421173681854;38597.421038685;38618.22763544769;324730;324732;324949;325044;429911;26716.465670686088;31348;31527;31767.682323177396;31776;33080.83459582687;40137.799311004426;41062.79468602638;41688.79155604202;44688.77655611701;32755.181120472178;32993.67006330002;33102.834485827414;33185.66814331922;33306.33387332288;32737.67262327346;32822.343553128325;33021.17447063747;33099.83450082734;33561.818481798604;37577.248455030196;38060;38209.617903821716;38395.23209535738;38627.03432414212;30575.694243057285;31345.216369590944;31454.21364465815;31819.20451988719;32130.35739285248;24355;25182.496350072528;25623.10319138821;25677.229683109537;25787.871060645328 13:00:59 INFO - PROCESS | 6727 | |2;jquery.html;464.60508567717426;473.2428115015975;479.31218701163834;492.0675320268092;492.25177729850617;20484.385468435932;22361.32916012518;22956.54086918265;23248.95379707915;23416.946237419314;22937.082516699313;23126.190583329586;23653;23703.170389036386;23832.761672383273;6284.654344011066;6657.733690652392;6699.631520266384;6703.1956165260235;6703.363180497845;6582.078509008718;6722.563033402837;7374.557526548398;7653.923460765377;7852.921470785304;7122.323379278961;7823.726169584065;7842.803929901741;7852.685892564291;7853.214678532158;7304.561726296412;7394.26057394259;7396.704131834747;7398.260173982612;7402.7409040683315;7309.195988441267;7698.730544430974;7739.458237923292;7744.148143704188;7749.651265693057;26486.205413837615;27403.588946165823;27514.587281190597;27531.724682753316;27634.723652763416;3058.663547009827;3161.3677264546955;3181.904542863718;3198.4882418813113;3252.593425821772;16643.33426662932;16716.41492547755;16734.24695888688;16826.83173168265;17127.914360428244;2300.620397634388;2336.1356298169617;2344.5310937812337;2422.842515236504;2429.9028038878423;120956.39521802422;125160;125706.37146814207;125885;126237;18282.634347313244;18553.814461855207;18595.163217655238;18828.905855470635;19181.520461988282;32735.672643273738;32824.671753282164;32916;32916.67083329184;33085.503717444255;10920.617778377718;10943.671689849318;10951.3429194247;11055.391953442639;11080.168987325982;9186.265098792213;9202.769930751785;9267.490288034223;9377.671781487616;9478.952605237067;9175.495347755937;9541.856872146924;9628;9632.229421646252;9771;3366.9494957575657;3578.248567800739;3933.350997085449;3962.484876965976;4103.297131690315;950.1401231885155;975.4098770244042;975.6438899801664;976.6630512473185;1010.6260683546988;21750.56498870014;21964.89017554934;22004.669929951062;22211.888940555193;22395.32814015582;977.0766625538939;979.1921664626569;987.5111819649176;1000.0799264676369;1005.1054561440324;11106.222564420576;11931.88068119325;12003.039756819433;12099.455524501242;12272;651006;651163;652832;656061;676470;113943;114832.42583787028;114961.42519287518;117219;117984.41007794905;1268.8659012239752;1287.1118927939879;1293.1982171054194;1321.4846209978116;1325.8077578751027;18135.183916723516;18377.54056148607;18794.530136746143;18954.52613684669;19063.14215860262;47623.761881190374;47713.52286477091;50025.49974500354;50717.49282507128;50807.74596126996;69749.65125174342;70287;70461;71713.28286717067;72056;11649.242799218024;12596.433160507615;12893.548725794928;12926.93536532349;13182.670433239246;13797.51708690191;14042.438302468196;14087.788683169556;14140.43438262458;14327.641808954442;12937.353132343156;13004.414801334153;13043.217406955613;13066.608001759962;13153.07928445038;10318.122959548522;10905.400202988912;11312.717182070513;11358.829617555579;11560.421978900853;12914.09601327898;13106;13182.60452186436;13324.200547967153;13513.324333783468;12546.372681366078;12734.745305094031;12820.935895320463;12907.870921290667;13171;11454.94272528632;11544;11572.305661660328;11611.88388116108;11738 13:00:59 INFO - PROCESS | 6727 | |3;mootools.html;1422.4168091082659;1573.5908663747423;1578.921053947303;1585.8731301495877;1588.491682661548;9870.654527091554;11365.772684546304;11601.477933492992;11609.999999999989;11617.361045142514;11702.941485292582;11800.35098069608;11804.350760708157;11809.70475738107;11811.645650630473;2016.707577401276;2054.527458684496;2521.9747802521924;2563.9615955537943;2565.2047865161767;2497.8126640501946;2527.178666933245;2533.3919859233742;2536.480021595578;2550.77043066124;2468.2965354873863;2488.415222422729;2496.4133428644254;2496.8252222344445;2498.0757119865675;2458.0659349447274;2476.021971321327;2481.367251350894;2483.192962287255;2486.2416962826333;2306.4349234437504;2315.7452680205165;2384.9046038158453;2397.1969390254285;2418.5042066376527;11932;11965.641030769091;12108.636740897788;12120.757584848254;12132.45403956824;5961.821145365646;6123.142760013601;6125.050617154348;6127.509799216052;6127.754889804402;3461.913452163691;3527.1358517163203;3533.8939831805087;3586.677199052072;3598.3702852001056;1264.2856785915928;1300.655326338521;1311.1018952017803;1332.8933685305249;1398.9510367137202;18022.729659055123;18195.909020454812;18201.36295229674;18216.726749098772;18244.90877545604;4850.054239423279;4972.65191436596;5013.749312534359;5087.262346959744;5097.388313402415;14981.850181498263;15024.09855408679;15028.248587570577;15042.924785376003;15045.699086018218;6184;6194.752209911553;6226;6226.62640241587;6230.06549017651;5526.364468086141;5545.944540554624;5589.357223919302;5637.689927054052;5655.066913959151;4283.378910058024;4312.137572485515;4460.732356058582;4552.840650577214;4564.6348292136545;1134.6085600467825;1146.696125526736;1169.9415029248503;1171.8945294923499;1174.301290732013;1026.8921763214905;1055.1822337688352;1060.0618452669444;1064.4518073192312;1067.3275836223129;7500.662470188855;7517;7533.585652789149;7545.170031296553;7639.961800191075;1087.8585783848207;1090.378484263962;1099.0328510910347;1099.1371773158032;1099.483242875847;4609.216433206296;4627.259638457831;4638.97680511602;4642.814287428465;4650;14359.92820035893;14443.92778036124;14500.202488863213;14676.412943482355;14723.484678036217;14269;14502.202378869264;14595.708085838223;14599;14694.779578306334;10431.687049388378;10486.213533984981;10618.36289822613;10631.946840265904;10639.840402393971;7436.256374362737;7575.621218939141;7615.619219039137;7648.961755191411;7746.978678315574;12520.123591348518;12564.497420103458;12617.116801824151;12623.179493332904;12719.42762575705;10454.68635940923;12546.498140074294;12734.61796146117;12758.48966041348;12991.480340786262;10147.34042287249;10252;10344;10347.223958203014;10350.171986240932;11392.25950313227;11426.314421134759;11452.08383329315;11452.541898323974;11491.310521368745;9350.766230844283;9463.763405914908;9484.383515071499;9521.238300936047;9631;9103.590338434919;9240.722778316662;9315.068493150902;9329.58016889228;9847;8910.910890891268;8918.86621700688;8954.462732236087;8971.102889711237;9055.18503334703;6783.287754785676;6846.657667116525;6867.450603951772;6911.067005954132;6930.237673855972;6562.442192413699;6627.304133065959;6753.662316884234;6790.830229244309;6817.761378351734 13:00:59 INFO - PROCESS | 6727 | |4;prototype.html;420.68658849157384;443.0673432424747;445.18531088108745;446.6449172907538;450.76334924164814;5171.2243163525445;5265.684058956465;5284.44513326101;5285.762140703668;5296;5014.473480284563;5204.115300398932;5222.294990176325;5222.858627962536;5225.712585807779;4303.376010478479;4310.806013729381;4319.697621166504;4325.589069038437;4328.39402483651;4333.891652708676;4339.848105316315;4354.629856462205;4355.324924636687;4355.6515478761785;4314.956850431487;4315.4605674290715;4331.963976930838;4354.804033818486;4374.7094607090985;4171.979140104295;4275.230458517465;4281.7002809803325;4309.439772829513;4321.783910804447;4053.412255222976;4080.326746086922;4080.3678528588507;4094.4472496213225;4100.507939047304;5144.768485418165;5167.863070124604;5175.637705360627;5187.922181167285;5189.844304670866;5022.372203474566;5041.445441001487;5050.080969231705;5103.183490641517;5113.718745468997;5458;5479.383581843365;5498;5505.5775458798;5505.862353441156;4551.362809206714;4567.634589232854;4583.633309335245;4607.52132140232;4619.399478067863;5436.700981446019;5599.7600959616475;5650.63270887391;5673.8581535461535;5688.203651488795;4704.929426058612;4710.882227944328;4731.645126615517;4740.95259047412;4776.785044672998;4761.40482439695;4801.303810947393;4829.154897892855;4829.95170048295;4862.930155365814;4866.537678920497;5065.8733531662;5075.1625981712605;5099.413567439718;5104.515071068243;5501.284832971768;5508.944910550923;5519.199716041152;5522.189689114579;5545.889082218333;1564.702706485764;1653.4460955579775;1662.7339625659836;1671.3899426709406;1673.21358961288;1255.3974092435683;1269.3526301586073;1280.7182419867613;1284.1460428814732;1301.8307620009339;5375.650582712111;5376.274202982548;5394.892102158014;5455.399906010336;5458.344998600193;5693.686847223443;5699.430056994267;5705.659170095024;5716.914246286309;5719.3422756382715;5138.3833939927445;5298.470152984671;5324.760385782649;5369.167778994262;5469.644473109235;2907.5057240269207;2948.0860933110803;2959.4820906341306;2959.70622767357;2965.303153758865;5784.71076446176;5839.970800145972;5844.88310233793;5846.649201047951;5884.323302820145;5457;5524.254225679562;5559.444055594408;5678.858028549319;5758.193852860602;5759.884802303931;5780.393058728857;5790.71046447676;5797.4782269595935;5846.327672317738;5567.638103523258;5587.385387607441;5598;5630.23991761107;5673.659580425187;5420.349558053059;5574.609777315549;5591.664500130005;5617.185508101385;5649.209110724584;5884.411208973467;5890.617109887844;5906.497947674496;5908.793192238251;5910.7931222407005;4500.032493014018;4717.499725145678;4755.120302744003;4762.52374762521;4768.546988036131;5625.115586278479;5658.20785090096;5665.235193248858;5682.659040457585;5723.742431590672;5522.944770552243;5542.72286385671;5544.861378465571;5559.691983269948;5561.443855614406;5254.369475662945;5489.258950041693;5537.8061767837935;5538.335399752055;5558.221848941071;5489.313835770528;5501.2945986744235;5512.944870551243;5526.143447765603;5541.778328866801;5553.556075420339;5556.972215138899;5559.861003474945;5576.77692892296;5578.497935185853;5173.974130129325;5366.892662146814;5380.919286210632;5384.9192262116885;5424.294841670558 13:00:59 INFO - PROCESS | 6727 | |5;yui.html;852.9402941794073;863.365426411587;892.7723430525215;893.4282059481933;906.3791302957475;17600.64798704027;19046.428607141774;19456.70814937777;19719.1126399312;19875.99999999998;18849.43451696448;19432;20051.298204562805;20239.089240984154;20282.29011984581;899.4917871402653;933.5145724223433;940.731891410948;941.1482608239554;941.3316545252878;898.9730308090768;918.6049998500642;927.3276874266137;928.4522131942152;928.6610387208653;964.6286179820785;965.4931161140387;966.2028826218362;967.3083745122236;967.5210770668529;960.0303693269848;964.064857088623;964.6527250189926;964.8793900762405;966.246327864267;706.5548704316247;706.6925887239016;708.0158579574396;709.0923617769264;709.1419382547073;16959.067251301174;16967.915160424243;16995.745063823928;19093.61812763751;19410.417687469257;15944.521664350088;16266.593335166715;16375.672486550202;16434.26045827929;16903.23935422909;13472.25902575358;13830.446782128703;13862.4455021799;13957.232352220624;14036;4020.2361551305453;4051.817668204938;4084.1627466369323;4131.876043718693;4222.493300803892;79376;83838.6108988092;85033.57483212545;87596.12403875943;87638;12816.93591532055;12841.357932103358;12864.549740759216;12887.355632218352;12927.030472714585;24027.159049433187;24148.758512415003;24279.878600606884;24388.512229755306;24544.631830522558;9047.321450891079;9171.358004939724;9180.540972951325;9408.341416100944;9432.575534100983;5298.629095963245;5315.707636079976;5395.109806881899;5395.14129895885;5398.163284690879;3823.598522155189;3883.0680636647567;3921.568627450978;3923.1172986077904;3931.68546516278;655.5312951239887;656.022526435617;656.7668477690352;664.1266734244549;668.5854770042605;779.5244900610624;803.3773825285334;809.283783851284;813;826.4132465949123;10857;11809.52761889532;11944.940275298743;12107.75784484323;12388.690282742824;833.629035079396;840.0843081041694;845.2308399356556;862.2713806833341;862.4609618988133;5428.131498960148;5433.673979561239;5474.972625136928;5500;5604.85987850307;83363;85165.57417212875;85190;86459;87433.56283218543;62127.68936155381;70039.64980175067;76765.61617191878;77288.61355693186;79353.60323198463;55506;55731.442685573435;67509.3249067503;67990.66004669946;68909.65545172241;11758.118141139277;12071.818922715991;12131.150819442908;12372.072094592762;12413.44139513706;39548;41651.583484164774;43119.56880431281;43120;43883.34174987442;50068;50719.49280507147;50743.74628126836;52588.211176833145;53510.73244633752;13367.598972030855;13633.79549306781;13642.454301827816;13897.235652039235;14050.156990580597;15459.381624734884;15710.921445393156;15747.291371888063;16230.837691622932;16738.832611674214;15165.165915874733;15605;15650.295736691649;16449.67100658004;16483.423080192137;14590.85409145895;15104.622384440478;15269.465568705042;15898.920505397398;16159;14449.78325325142;14696.41214351414;14732.631684207552;14906.850931490546;14957.700845982801;10291.33106348085;10470.633527826489;10621.575136994743;10837.566497340333;11077.335359878432;12163.75672486563;12260.32568208757;12297.449943520596;12750;12761.872381276069 13:00:59 INFO - PROCESS | 6727 | __end_tp_report 13:00:59 INFO - PROCESS | 6727 | __start_cc_report 13:00:59 INFO - PROCESS | 6727 | _x_x_mozilla_cycle_collect,1140 13:00:59 INFO - PROCESS | 6727 | __end_cc_report 13:00:59 INFO - PROCESS | 6727 | __startTimestamp1495483259342__endTimestamp 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | ------- Summary: start ------- 13:00:59 INFO - PROCESS | 6727 | Number of tests: 6 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | [#0] dojo.html Cycles:165 Average:5739.27 Median:6010.64 stddev:653.45 (10.9%) stddev-sans-first:628.91 13:00:59 INFO - PROCESS | 6727 | Values: 3389.1 3426.1 3444.0 3458.1 3499.9 5709.3 5801.0 5822.8 5826.0 5838.5 5783.5 5794.7 5841.5 5845.7 5851.9 5177.2 5192.8 5201.9 5258.9 5262.8 5087.2 5119.3 5287.6 5301.4 5303.0 5130.3 5160.3 5179.5 5219.5 5232.5 5243.1 5244.6 5251.9 5262.5 5265.2 5163.0 5185.8 5229.1 5233.7 5258.8 5572.2 5604.5 5610.6 5623.9 5780.4 6112.3 6114.8 6124.4 6126.5 6130.4 6242.9 6250.1 6269.2 6274.5 6279.8 5792.7 5822.1 5833.1 5850.3 5863.1 6122.1 6125.4 6247.8 6340.4 6349.0 6118.8 6121.4 6133.8 6134.2 6137.4 6188.2 6210.6 6225.4 6230.3 6235.8 5977.1 6023.6 6198.8 6205.5 6208.7 6116.7 6120.2 6124.1 6128.0 6131.7 4694.8 4710.3 4711.2 4715.7 4734.3 4111.6 4180.1 4185.6 4192.4 4212.2 5799.7 5819.3 5841.6 5845.8 5923.3 6133.1 6172.3 6192.0 6194.5 6202.5 5919.5 5922.0 5926.7 5929.5 5937.4 5089.9 5121.5 5165.6 5230.3 5232.3 6387.7 6393.1 6393.6 6398.9 6401.9 6184.0 6184.8 6184.9 6191.3 6192.4 6220.1 6246.0 6339.1 6385.3 6395.7 6062.4 6064.3 6129.8 6133.5 6138.5 6058.2 6096.2 6261.8 6268.6 6268.7 6004.8 6004.9 6033.6 6037.6 6142.5 5899.6 6123.8 6132.3 6133.0 6134.3 5989.5 6037.0 6043.0 6125.3 6131.2 6114.7 6137.3 6147.1 6150.9 6153.7 5998.7 6005.3 6010.6 6013.9 6030.5 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | [#1] ext.html Cycles:180 Average:47559.87 Median:178489.34 stddev:82562.70 (46.3%) stddev-sans-first:82719.59 13:00:59 INFO - PROCESS | 6727 | Values: 775.5 854.4 861.6 936.2 945.9 31872.0 45037.8 45591.3 45758.5 45844.1 41991.0 42134.8 42189.4 46252.0 46309.8 7723.4 7905.5 9230.4 9372.6 9378.0 10748.5 10954.1 10957.7 10961.5 10963.8 11115.6 11116.8 11151.2 11153.4 11155.0 11034.2 11053.8 11057.7 11075.4 11079.3 10700.6 10749.4 10757.5 10760.0 10761.0 43034.8 43109.1 43202.0 43228.4 43246.4 4317.1 4354.8 4391.0 4478.8 4479.3 30335.5 30515.6 30620.7 30815.0 32248.7 4170.6 4224.5 4246.5 4276.4 4313.6 239848.0 240049.0 240084.0 241192.0 241573.0 54197.2 54389.7 54402.0 54429.5 54444.0 57456.7 57459.0 57521.0 57527.4 57556.7 15654.2 16191.9 16202.1 16204.8 16208.0 12252.3 12420.1 13355.9 14036.8 14051.2 14026.4 14077.8 14111.5 14163.9 14175.4 5686.4 5786.9 5812.9 5824.4 5950.1 1023.4 1028.6 1036.1 1049.1 1056.4 29384.6 29643.6 29827.0 30263.0 30392.2 1168.8 1176.6 1177.4 1202.5 1222.4 14603.7 14739.6 14926.2 14973.6 15000.8 342686.0 343111.0 343937.0 344514.0 345290.0 50000.0 50008.7 50057.5 50097.7 50148.5 1296.0 1302.6 1304.3 1322.6 1507.4 28611.6 28887.7 29143.0 29528.6 29855.6 38194.8 38503.8 38588.4 38597.4 38618.2 324730.0 324732.0 324949.0 325044.0 429911.0 26716.5 31348.0 31527.0 31767.7 31776.0 33080.8 40137.8 41062.8 41688.8 44688.8 32755.2 32993.7 33102.8 33185.7 33306.3 32737.7 32822.3 33021.2 33099.8 33561.8 37577.2 38060.0 38209.6 38395.2 38627.0 30575.7 31345.2 31454.2 31819.2 32130.4 24355.0 25182.5 25623.1 25677.2 25787.9 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | [#2] jquery.html Cycles:180 Average:38348.84 Median:12876.54 stddev:108588.24 (843.3%) stddev-sans-first:108855.60 13:00:59 INFO - PROCESS | 6727 | Values: 464.6 473.2 479.3 492.1 492.3 20484.4 22361.3 22956.5 23249.0 23416.9 22937.1 23126.2 23653.0 23703.2 23832.8 6284.7 6657.7 6699.6 6703.2 6703.4 6582.1 6722.6 7374.6 7653.9 7852.9 7122.3 7823.7 7842.8 7852.7 7853.2 7304.6 7394.3 7396.7 7398.3 7402.7 7309.2 7698.7 7739.5 7744.1 7749.7 26486.2 27403.6 27514.6 27531.7 27634.7 3058.7 3161.4 3181.9 3198.5 3252.6 16643.3 16716.4 16734.2 16826.8 17127.9 2300.6 2336.1 2344.5 2422.8 2429.9 120956.4 125160.0 125706.4 125885.0 126237.0 18282.6 18553.8 18595.2 18828.9 19181.5 32735.7 32824.7 32916.0 32916.7 33085.5 10920.6 10943.7 10951.3 11055.4 11080.2 9186.3 9202.8 9267.5 9377.7 9479.0 9175.5 9541.9 9628.0 9632.2 9771.0 3366.9 3578.2 3933.4 3962.5 4103.3 950.1 975.4 975.6 976.7 1010.6 21750.6 21964.9 22004.7 22211.9 22395.3 977.1 979.2 987.5 1000.1 1005.1 11106.2 11931.9 12003.0 12099.5 12272.0 651006.0 651163.0 652832.0 656061.0 676470.0 113943.0 114832.4 114961.4 117219.0 117984.4 1268.9 1287.1 1293.2 1321.5 1325.8 18135.2 18377.5 18794.5 18954.5 19063.1 47623.8 47713.5 50025.5 50717.5 50807.7 69749.7 70287.0 70461.0 71713.3 72056.0 11649.2 12596.4 12893.5 12926.9 13182.7 13797.5 14042.4 14087.8 14140.4 14327.6 12937.4 13004.4 13043.2 13066.6 13153.1 10318.1 10905.4 11312.7 11358.8 11560.4 12914.1 13106.0 13182.6 13324.2 13513.3 12546.4 12734.7 12820.9 12907.9 13171.0 11454.9 11544.0 11572.3 11611.9 11738.0 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | [#3] mootools.html Cycles:180 Average:7360.25 Median:2438.29 stddev:4688.52 (192.3%) stddev-sans-first:4680.45 13:00:59 INFO - PROCESS | 6727 | Values: 1422.4 1573.6 1578.9 1585.9 1588.5 9870.7 11365.8 11601.5 11610.0 11617.4 11702.9 11800.4 11804.4 11809.7 11811.6 2016.7 2054.5 2522.0 2564.0 2565.2 2497.8 2527.2 2533.4 2536.5 2550.8 2468.3 2488.4 2496.4 2496.8 2498.1 2458.1 2476.0 2481.4 2483.2 2486.2 2306.4 2315.7 2384.9 2397.2 2418.5 11932.0 11965.6 12108.6 12120.8 12132.5 5961.8 6123.1 6125.1 6127.5 6127.8 3461.9 3527.1 3533.9 3586.7 3598.4 1264.3 1300.7 1311.1 1332.9 1399.0 18022.7 18195.9 18201.4 18216.7 18244.9 4850.1 4972.7 5013.7 5087.3 5097.4 14981.9 15024.1 15028.2 15042.9 15045.7 6184.0 6194.8 6226.0 6226.6 6230.1 5526.4 5545.9 5589.4 5637.7 5655.1 4283.4 4312.1 4460.7 4552.8 4564.6 1134.6 1146.7 1169.9 1171.9 1174.3 1026.9 1055.2 1060.1 1064.5 1067.3 7500.7 7517.0 7533.6 7545.2 7640.0 1087.9 1090.4 1099.0 1099.1 1099.5 4609.2 4627.3 4639.0 4642.8 4650.0 14359.9 14443.9 14500.2 14676.4 14723.5 14269.0 14502.2 14595.7 14599.0 14694.8 10431.7 10486.2 10618.4 10631.9 10639.8 7436.3 7575.6 7615.6 7649.0 7747.0 12520.1 12564.5 12617.1 12623.2 12719.4 10454.7 12546.5 12734.6 12758.5 12991.5 10147.3 10252.0 10344.0 10347.2 10350.2 11392.3 11426.3 11452.1 11452.5 11491.3 9350.8 9463.8 9484.4 9521.2 9631.0 9103.6 9240.7 9315.1 9329.6 9847.0 8910.9 8918.9 8954.5 8971.1 9055.2 6783.3 6846.7 6867.5 6911.1 6930.2 6562.4 6627.3 6753.7 6790.8 6817.8 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | [#4] prototype.html Cycles:180 Average:4797.68 Median:5285.10 stddev:1277.94 (24.2%) stddev-sans-first:1238.58 13:00:59 INFO - PROCESS | 6727 | Values: 420.7 443.1 445.2 446.6 450.8 5171.2 5265.7 5284.4 5285.8 5296.0 5014.5 5204.1 5222.3 5222.9 5225.7 4303.4 4310.8 4319.7 4325.6 4328.4 4333.9 4339.8 4354.6 4355.3 4355.7 4315.0 4315.5 4332.0 4354.8 4374.7 4172.0 4275.2 4281.7 4309.4 4321.8 4053.4 4080.3 4080.4 4094.4 4100.5 5144.8 5167.9 5175.6 5187.9 5189.8 5022.4 5041.4 5050.1 5103.2 5113.7 5458.0 5479.4 5498.0 5505.6 5505.9 4551.4 4567.6 4583.6 4607.5 4619.4 5436.7 5599.8 5650.6 5673.9 5688.2 4704.9 4710.9 4731.6 4741.0 4776.8 4761.4 4801.3 4829.2 4830.0 4862.9 4866.5 5065.9 5075.2 5099.4 5104.5 5501.3 5508.9 5519.2 5522.2 5545.9 1564.7 1653.4 1662.7 1671.4 1673.2 1255.4 1269.4 1280.7 1284.1 1301.8 5375.7 5376.3 5394.9 5455.4 5458.3 5693.7 5699.4 5705.7 5716.9 5719.3 5138.4 5298.5 5324.8 5369.2 5469.6 2907.5 2948.1 2959.5 2959.7 2965.3 5784.7 5840.0 5844.9 5846.6 5884.3 5457.0 5524.3 5559.4 5678.9 5758.2 5759.9 5780.4 5790.7 5797.5 5846.3 5567.6 5587.4 5598.0 5630.2 5673.7 5420.3 5574.6 5591.7 5617.2 5649.2 5884.4 5890.6 5906.5 5908.8 5910.8 4500.0 4717.5 4755.1 4762.5 4768.5 5625.1 5658.2 5665.2 5682.7 5723.7 5522.9 5542.7 5544.9 5559.7 5561.4 5254.4 5489.3 5537.8 5538.3 5558.2 5489.3 5501.3 5512.9 5526.1 5541.8 5553.6 5557.0 5559.9 5576.8 5578.5 5174.0 5366.9 5380.9 5384.9 5424.3 13:00:59 INFO - PROCESS | 6727 | 13:00:59 INFO - PROCESS | 6727 | [#5] yui.html Cycles:180 Average:18971.97 Median:22937.04 stddev:23386.46 (102.0%) stddev-sans-first:23412.48 13:00:59 INFO - PROCESS | 6727 | Values: 852.9 863.4 892.8 893.4 906.4 17600.6 19046.4 19456.7 19719.1 19876.0 18849.4 19432.0 20051.3 20239.1 20282.3 899.5 933.5 940.7 941.1 941.3 899.0 918.6 927.3 928.5 928.7 964.6 965.5 966.2 967.3 967.5 960.0 964.1 964.7 964.9 966.2 706.6 706.7 708.0 709.1 709.1 16959.1 16967.9 16995.7 19093.6 19410.4 15944.5 16266.6 16375.7 16434.3 16903.2 13472.3 13830.4 13862.4 13957.2 14036.0 4020.2 4051.8 4084.2 4131.9 4222.5 79376.0 83838.6 85033.6 87596.1 87638.0 12816.9 12841.4 12864.5 12887.4 12927.0 24027.2 24148.8 24279.9 24388.5 24544.6 9047.3 9171.4 9180.5 9408.3 9432.6 5298.6 5315.7 5395.1 5395.1 5398.2 3823.6 3883.1 3921.6 3923.1 3931.7 655.5 656.0 656.8 664.1 668.6 779.5 803.4 809.3 813.0 826.4 10857.0 11809.5 11944.9 12107.8 12388.7 833.6 840.1 845.2 862.3 862.5 5428.1 5433.7 5475.0 5500.0 5604.9 83363.0 85165.6 85190.0 86459.0 87433.6 62127.7 70039.6 76765.6 77288.6 79353.6 55506.0 55731.4 67509.3 67990.7 68909.7 11758.1 12071.8 12131.2 12372.1 12413.4 39548.0 41651.6 43119.6 43120.0 43883.3 50068.0 50719.5 50743.7 52588.2 53510.7 13367.6 13633.8 13642.5 13897.2 14050.2 15459.4 15710.9 15747.3 16230.8 16738.8 15165.2 15605.0 15650.3 16449.7 16483.4 14590.9 15104.6 15269.5 15898.9 16159.0 14449.8 14696.4 14732.6 14906.9 14957.7 10291.3 10470.6 10621.6 10837.6 11077.3 12163.8 12260.3 12297.4 12750.0 12761.9 13:00:59 INFO - PROCESS | 6727 | -------- Summary: end -------- 13:00:59 INFO - PROCESS | 6727 | 13:01:00 INFO - TEST-INFO | 6727: exit 0 13:01:00 INFO - TEST-OK | dromaeo_css | took 1114902ms 13:01:00 INFO - TEST-START | kraken 13:01:00 INFO - operating with platform_type : linux_ 13:01:00 INFO - Initialising browser for kraken test... 13:01:00 INFO - TEST-INFO | started process 7030 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIFjbI1/profile http://localhost:44715/getInfo.html) 13:01:03 INFO - PROCESS | 7030 | __metrics Screen width/height:1600/1200 13:01:03 INFO - PROCESS | 7030 | colorDepth:24 13:01:03 INFO - PROCESS | 7030 | Browser inner width/height: 1024/697 13:01:03 INFO - PROCESS | 7030 | __metrics 13:01:04 INFO - PROCESS | 7030 | 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:01:04 INFO - PROCESS | 7030 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:01:05 INFO - TEST-INFO | 7030: exit 0 13:01:05 INFO - Browser initialized. 13:01:05 INFO - Running cycle 1/1 for kraken test... 13:01:05 INFO - TEST-INFO | started process 7122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIFjbI1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:17 INFO - PROCESS | 7122 | RSS: Main: 141717504 13:01:17 INFO - PROCESS | 7122 | 13:01:19 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44715/tests/kraken/driver.html?testName=audio-beat-detection) 13:01:20 INFO - PROCESS | 7122 | RSS: Main: 183656448 13:01:20 INFO - PROCESS | 7122 | 13:01:23 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44715/tests/kraken/driver.html?testName=audio-dft) 13:01:23 INFO - PROCESS | 7122 | RSS: Main: 174407680 13:01:23 INFO - PROCESS | 7122 | 13:01:26 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44715/tests/kraken/driver.html?testName=audio-fft) 13:01:27 INFO - PROCESS | 7122 | RSS: Main: 265449472 13:01:27 INFO - PROCESS | 7122 | 13:01:29 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44715/tests/kraken/driver.html?testName=audio-oscillator) 13:01:29 INFO - PROCESS | 7122 | RSS: Main: 171343872 13:01:29 INFO - PROCESS | 7122 | 13:01:32 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44715/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:01:32 INFO - PROCESS | 7122 | RSS: Main: 174256128 13:01:32 INFO - PROCESS | 7122 | 13:01:35 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44715/tests/kraken/driver.html?testName=imaging-darkroom) 13:01:36 INFO - PROCESS | 7122 | RSS: Main: 211660800 13:01:36 INFO - PROCESS | 7122 | 13:01:39 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44715/tests/kraken/driver.html?testName=imaging-desaturate) 13:01:40 INFO - PROCESS | 7122 | RSS: Main: 212680704 13:01:40 INFO - PROCESS | 7122 | 13:01:42 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44715/tests/kraken/driver.html?testName=json-parse-financial) 13:01:43 INFO - PROCESS | 7122 | RSS: Main: 211697664 13:01:43 INFO - PROCESS | 7122 | 13:01:45 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44715/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:01:45 INFO - PROCESS | 7122 | RSS: Main: 173985792 13:01:45 INFO - PROCESS | 7122 | 13:01:47 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:01:47 INFO - PROCESS | 7122 | RSS: Main: 180490240 13:01:47 INFO - PROCESS | 7122 | 13:01:49 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:01:50 INFO - PROCESS | 7122 | RSS: Main: 190820352 13:01:50 INFO - PROCESS | 7122 | 13:01:52 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:01:53 INFO - PROCESS | 7122 | RSS: Main: 198283264 13:01:53 INFO - PROCESS | 7122 | 13:01:56 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:01:57 INFO - PROCESS | 7122 | RSS: Main: 195645440 13:01:57 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | Cycle 1(1): loaded http://localhost:44715/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44715/tests/kraken/driver.html?testName=ai-astar) 13:01:58 INFO - PROCESS | 7122 | RSS: Main: 245960704 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | __start_tp_report 13:01:58 INFO - PROCESS | 7122 | _x_x_mozilla_page_load 13:01:58 INFO - PROCESS | 7122 | _x_x_mozilla_page_load_details 13:01:58 INFO - PROCESS | 7122 | |i|pagename|runs| 13:01:58 INFO - PROCESS | 7122 | |0;ai-astar;104;113;112;112;114;113;114;115;114;114 13:01:58 INFO - PROCESS | 7122 | |1;audio-beat-detection;145;119;121;148;116;116;115;115;133;117 13:01:58 INFO - PROCESS | 7122 | |2;audio-dft;160;161;161;161;161;161;161;161;159;160 13:01:58 INFO - PROCESS | 7122 | |3;audio-fft;73;75;76;122;76;76;77;77;106;75 13:01:58 INFO - PROCESS | 7122 | |4;audio-oscillator;82;81;102;83;82;81;93;83;83;109 13:01:58 INFO - PROCESS | 7122 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;112;112 13:01:58 INFO - PROCESS | 7122 | |6;imaging-darkroom;146;146;146;146;147;146;146;145;147;146 13:01:58 INFO - PROCESS | 7122 | |7;imaging-desaturate;94;94;94;95;94;94;95;94;95;93 13:01:58 INFO - PROCESS | 7122 | |8;json-parse-financial;57;53;56;53;53;52;54;56;54;53 13:01:58 INFO - PROCESS | 7122 | |9;json-stringify-tinderbox;54;53;52;54;52;54;52;53;54;53 13:01:58 INFO - PROCESS | 7122 | |10;stanford-crypto-aes;79;78;85;81;84;85;85;75;89;75 13:01:58 INFO - PROCESS | 7122 | |11;stanford-crypto-ccm;120;121;120;122;119;121;122;120;161;118 13:01:58 INFO - PROCESS | 7122 | |12;stanford-crypto-pbkdf2;161;160;164;164;160;163;163;163;160;168 13:01:58 INFO - PROCESS | 7122 | |13;stanford-crypto-sha256-iterative;60;59;63;59;60;60;59;58;60;59 13:01:58 INFO - PROCESS | 7122 | __end_tp_report 13:01:58 INFO - PROCESS | 7122 | __start_cc_report 13:01:58 INFO - PROCESS | 7122 | _x_x_mozilla_cycle_collect,1692 13:01:58 INFO - PROCESS | 7122 | __end_cc_report 13:01:58 INFO - PROCESS | 7122 | __startTimestamp1495483318919__endTimestamp 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | ------- Summary: start ------- 13:01:58 INFO - PROCESS | 7122 | Number of tests: 14 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#0] ai-astar Cycles:10 Average:112.50 Median:114.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 13:01:58 INFO - PROCESS | 7122 | Values: 104.0 113.0 112.0 112.0 114.0 113.0 114.0 115.0 114.0 114.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:120.00 stddev:12.77 (10.6%) stddev-sans-first:11.19 13:01:58 INFO - PROCESS | 7122 | Values: 145.0 119.0 121.0 148.0 116.0 116.0 115.0 115.0 133.0 117.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 13:01:58 INFO - PROCESS | 7122 | Values: 160.0 161.0 161.0 161.0 161.0 161.0 161.0 161.0 159.0 160.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#3] audio-fft Cycles:10 Average:83.30 Median:76.00 stddev:16.65 (21.9%) stddev-sans-first:17.24 13:01:58 INFO - PROCESS | 7122 | Values: 73.0 75.0 76.0 122.0 76.0 76.0 77.0 77.0 106.0 75.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:82.50 stddev:10.04 (12.2%) stddev-sans-first:10.42 13:01:58 INFO - PROCESS | 7122 | Values: 82.0 81.0 102.0 83.0 82.0 81.0 93.0 83.0 83.0 109.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:01:58 INFO - PROCESS | 7122 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 112.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 13:01:58 INFO - PROCESS | 7122 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 145.0 147.0 146.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 13:01:58 INFO - PROCESS | 7122 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 93.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:1.66 (3.1%) stddev-sans-first:1.39 13:01:58 INFO - PROCESS | 7122 | Values: 57.0 53.0 56.0 53.0 53.0 52.0 54.0 56.0 54.0 53.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#9] json-stringify-tinderbox Cycles:10 Average:53.10 Median:53.50 stddev:0.88 (1.6%) stddev-sans-first:0.87 13:01:58 INFO - PROCESS | 7122 | Values: 54.0 53.0 52.0 54.0 52.0 54.0 52.0 53.0 54.0 53.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:84.50 stddev:4.74 (5.6%) stddev-sans-first:4.94 13:01:58 INFO - PROCESS | 7122 | Values: 79.0 78.0 85.0 81.0 84.0 85.0 85.0 75.0 89.0 75.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:121.00 stddev:12.92 (10.7%) stddev-sans-first:13.61 13:01:58 INFO - PROCESS | 7122 | Values: 120.0 121.0 120.0 122.0 119.0 121.0 122.0 120.0 161.0 118.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.60 Median:163.00 stddev:2.50 (1.5%) stddev-sans-first:2.59 13:01:58 INFO - PROCESS | 7122 | Values: 161.0 160.0 164.0 164.0 160.0 163.0 163.0 163.0 160.0 168.0 13:01:58 INFO - PROCESS | 7122 | 13:01:58 INFO - PROCESS | 7122 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.70 Median:60.00 stddev:1.34 (2.2%) stddev-sans-first:1.41 13:01:58 INFO - PROCESS | 7122 | Values: 60.0 59.0 63.0 59.0 60.0 60.0 59.0 58.0 60.0 59.0 13:01:58 INFO - PROCESS | 7122 | -------- Summary: end -------- 13:01:58 INFO - PROCESS | 7122 | 13:01:59 INFO - TEST-INFO | 7122: exit 0 13:01:59 INFO - TEST-OK | kraken | took 59917ms 13:01:59 INFO - SUITE-END | took 1174s 13:02:00 INFO - Completed test suite (00:19:34) 13:02:00 INFO - javascript benchmark 13:02:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7977.222379886315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.135770378553, 3426.0749597608647, 3444.0356700123957, 3458.118179864134, 3499.877504287349, 5709.257796486456, 5800.970995145019, 5822.767089316433, 5825.970870145648, 5838.503727183189, 5783.5373170146395, 5794.739236734357, 5841.503472204857, 5845.7369418376165, 5851.941480585193, 5177.171652535595, 5192.844214673566, 5201.9479805201845, 5258.894822103555, 5262.763175657085, 5087.186050231945, 5119.257707632391, 5287.550558202538, 5301.41684414716, 5302.973485132569, 5130.333056702623, 5160.25176349431, 5179.533841954223, 5219.45195754446, 5232.45059268777, 5243.10867152585, 5244.606654500909, 5251.949610077962, 5262.473752624745, 5265.183896496049, 5163.0, 5185.766640501187, 5229.137192363255, 5233.712145831978, 5258.81594144207, 5572.219889215513, 5604.467575580314, 5610.607257491977, 5623.887522249573, 5780.364159942403, 6112.32111068014, 6114.755409783604, 6124.418180272867, 6126.540509461808, 6130.448259656652, 6242.937570624281, 6250.124982502408, 6269.216347956505, 6274.498040156776, 6279.780207692709, 5792.710364481759, 5822.068469044975, 5833.066709326572, 5850.297964244275, 5863.061910094364, 6122.051082082285, 6125.41859352575, 6247.843803904848, 6340.4293613575, 6349.0476428536085, 6118.832384214075, 6121.422488707753, 6133.76314253068, 6134.222293197073, 6137.358988059894, 6188.195534580475, 6210.627362358273, 6225.3774622537385, 6230.345813689588, 6235.781747638811, 5977.1333156692035, 6023.608465449819, 6198.783042593487, 6205.5345849061505, 6208.658523781145, 6116.724747386378, 6120.234970628671, 6124.116761842097, 6128.014278093809, 6131.713108457284, 4694.788734506956, 4710.29345598163, 4711.1519926413575, 4715.717056976592, 4734.289856521549, 4111.60939710727, 4180.1012782251355, 4185.602367775056, 4192.392103145027, 4212.199682060399, 5799.739011744482, 5819.330776960619, 5841.649501029951, 5845.824625261249, 5923.318818335946, 6133.080037994337, 6172.321044685081, 6192.040233763774, 6194.5354098442795, 6202.472789812826, 5919.526437884959, 5921.970390148022, 5926.733297001645, 5929.525637948954, 5937.406259374028, 5089.923651145235, 5121.487851214849, 5165.586753059822, 5230.34620672416, 5232.3459567554055, 6387.712552935129, 6393.1369265148605, 6393.552451328363, 6398.936010639834, 6401.9039714404325, 6184.041473571604, 6184.81445556634, 6184.876302474016, 6191.288001879841, 6192.442680158807, 6220.129181914626, 6245.969415046467, 6339.144215531029, 6385.29761726219, 6395.680215989275, 6062.363451837494, 6064.332923378337, 6129.756146212572, 6133.457080270768, 6138.508919286357, 6058.151858739692, 6096.177016102769, 6261.843453913506, 6268.561200716089, 6268.655223962726, 6004.789832355847, 6004.939950600438, 6033.637981721111, 6037.63774173551, 6142.539309551891, 5899.587028907935, 6123.785667501616, 6132.2947860996555, 6133.049377346519, 6134.263888333428, 5989.52083833301, 6037.0, 6042.969785151046, 6125.264968203844, 6131.233595800525, 6114.714308451384, 6137.324894261538, 6147.108669243024, 6150.907736384047, 6153.66154861469, 5998.670073145844, 6005.279366476051, 6010.639361638316, 6013.879722405615, 6030.457258846549], "value": 5695.62981636902, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.4649291988532, 854.4232642965997, 861.6208868098037, 936.1948724097275, 945.8959514453413, 31872.0, 45037.77481112594, 45591.316130258034, 45758.542414575844, 45844.083118337614, 41991.0, 42134.789326053404, 42189.367159492635, 46252.0, 46309.76845115778, 7723.420743444264, 7905.525668459911, 9230.353875228737, 9372.625094996192, 9378.0, 10748.516316765765, 10954.123670106412, 10957.726056848564, 10961.451927403637, 10963.83554246687, 11115.555377784878, 11116.777664446707, 11151.219414640978, 11153.386563738992, 11155.0, 11034.227604067719, 11053.834192487118, 11057.668269951873, 11075.390853503053, 11079.279846810012, 10700.571977120906, 10749.408782516959, 10757.515911783988, 10760.0, 10761.0, 43034.784826075986, 43109.13781724348, 43202.0, 43228.3515747264, 43246.35130473045, 4317.0934103838235, 4354.782260886943, 4390.999999999968, 4478.820847166109, 4479.3281007848755, 30335.544966825517, 30515.61552907968, 30620.693793062008, 30815.0, 32248.6775132248, 4170.562090980434, 4224.54253282617, 4246.4691913510815, 4276.444062271916, 4313.590208930146, 239848.0, 240049.0, 240084.0, 241191.99999999825, 241573.0, 54197.1870421944, 54389.72805135949, 54402.0, 54429.45570544323, 54444.0, 57456.71271643615, 57459.0, 57521.0, 57527.424725753044, 57556.71221643949, 15654.217289135726, 16191.919040404959, 16202.108884011257, 16204.75692864608, 16208.0, 12252.264864108181, 12420.068494862922, 13355.931525477938, 14036.789448158286, 14051.156930584199, 14026.438942442392, 14077.788833167511, 14111.506097286545, 14163.929180354033, 14175.362108705132, 5686.431356864363, 5786.855328616734, 5812.941870581325, 5824.388878895112, 5950.107483877453, 1023.4166525080633, 1028.6451174344838, 1036.108946306176, 1049.0872940541778, 1056.3556230699267, 29384.559231611544, 29643.55534666982, 29827.0, 30263.0, 30392.240193995327, 1168.784464157267, 1176.611718133014, 1177.4054102678265, 1202.5250026239587, 1222.3643705273387, 14603.707925841423, 14739.55781326562, 14926.179060151795, 14973.550793476214, 15000.849991500163, 342686.0, 343111.0, 343937.0, 344514.0, 345290.0, 50000.0, 50008.74995624999, 50057.49942500601, 50097.74951125221, 50148.49851501511, 1295.9805602915965, 1302.5571305756089, 1304.3478260869565, 1322.5966080345395, 1507.3593722667822, 28611.570826438037, 28887.7111228885, 29143.0, 29528.557071643514, 29855.552166717953, 38194.809025954695, 38503.807480962416, 38588.421173681854, 38597.421038685, 38618.22763544769, 324730.0, 324732.0, 324949.0, 325044.0, 429911.0, 26716.465670686088, 31348.0, 31527.0, 31767.682323177396, 31776.0, 33080.83459582687, 40137.799311004426, 41062.79468602638, 41688.79155604202, 44688.77655611701, 32755.181120472178, 32993.67006330002, 33102.834485827414, 33185.66814331922, 33306.33387332288, 32737.67262327346, 32822.343553128325, 33021.17447063747, 33099.83450082734, 33561.818481798604, 37577.248455030196, 38060.0, 38209.617903821716, 38395.23209535738, 38627.03432414212, 30575.694243057285, 31345.216369590944, 31454.21364465815, 31819.20451988719, 32130.35739285248, 24355.0, 25182.496350072528, 25623.10319138821, 25677.229683109537, 25787.871060645328], "value": 19125.03979648908, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.60508567717426, 473.2428115015975, 479.31218701163834, 492.0675320268092, 492.25177729850617, 20484.385468435932, 22361.32916012518, 22956.54086918265, 23248.95379707915, 23416.946237419314, 22937.082516699313, 23126.190583329586, 23653.0, 23703.170389036386, 23832.761672383273, 6284.654344011066, 6657.733690652392, 6699.631520266384, 6703.1956165260235, 6703.363180497845, 6582.078509008718, 6722.563033402837, 7374.557526548398, 7653.923460765377, 7852.921470785304, 7122.323379278961, 7823.726169584065, 7842.803929901741, 7852.685892564291, 7853.214678532158, 7304.561726296412, 7394.26057394259, 7396.704131834747, 7398.260173982612, 7402.7409040683315, 7309.195988441267, 7698.730544430974, 7739.458237923292, 7744.148143704188, 7749.651265693057, 26486.205413837615, 27403.588946165823, 27514.587281190597, 27531.724682753316, 27634.723652763416, 3058.663547009827, 3161.3677264546955, 3181.904542863718, 3198.4882418813113, 3252.593425821772, 16643.33426662932, 16716.41492547755, 16734.24695888688, 16826.83173168265, 17127.914360428244, 2300.620397634388, 2336.1356298169617, 2344.5310937812337, 2422.842515236504, 2429.9028038878423, 120956.39521802422, 125160.0, 125706.37146814207, 125885.0, 126237.0, 18282.634347313244, 18553.814461855207, 18595.163217655238, 18828.905855470635, 19181.520461988282, 32735.672643273738, 32824.671753282164, 32916.0, 32916.67083329184, 33085.503717444255, 10920.617778377718, 10943.671689849318, 10951.3429194247, 11055.391953442639, 11080.168987325982, 9186.265098792213, 9202.769930751785, 9267.490288034223, 9377.671781487616, 9478.952605237067, 9175.495347755937, 9541.856872146924, 9628.0, 9632.229421646252, 9771.0, 3366.9494957575657, 3578.248567800739, 3933.350997085449, 3962.484876965976, 4103.297131690315, 950.1401231885155, 975.4098770244042, 975.6438899801664, 976.6630512473185, 1010.6260683546988, 21750.56498870014, 21964.89017554934, 22004.669929951062, 22211.888940555193, 22395.32814015582, 977.0766625538939, 979.1921664626569, 987.5111819649176, 1000.0799264676369, 1005.1054561440324, 11106.222564420576, 11931.88068119325, 12003.039756819433, 12099.455524501242, 12272.0, 651006.0, 651163.0, 652832.0, 656061.0, 676470.0, 113943.0, 114832.42583787028, 114961.42519287518, 117219.0, 117984.41007794905, 1268.8659012239752, 1287.1118927939879, 1293.1982171054194, 1321.4846209978116, 1325.8077578751027, 18135.183916723516, 18377.54056148607, 18794.530136746143, 18954.52613684669, 19063.14215860262, 47623.761881190374, 47713.52286477091, 50025.49974500354, 50717.49282507128, 50807.74596126996, 69749.65125174342, 70287.0, 70461.0, 71713.28286717067, 72056.0, 11649.242799218024, 12596.433160507615, 12893.548725794928, 12926.93536532349, 13182.670433239246, 13797.51708690191, 14042.438302468196, 14087.788683169556, 14140.43438262458, 14327.641808954442, 12937.353132343156, 13004.414801334153, 13043.217406955613, 13066.608001759962, 13153.07928445038, 10318.122959548522, 10905.400202988912, 11312.717182070513, 11358.829617555579, 11560.421978900853, 12914.09601327898, 13106.0, 13182.60452186436, 13324.200547967153, 13513.324333783468, 12546.372681366078, 12734.745305094031, 12820.935895320463, 12907.870921290667, 13171.0, 11454.94272528632, 11544.0, 11572.305661660328, 11611.88388116108, 11738.0], "value": 11833.823292628938, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.4168091082659, 1573.5908663747423, 1578.921053947303, 1585.8731301495877, 1588.491682661548, 9870.654527091554, 11365.772684546304, 11601.477933492992, 11609.999999999989, 11617.361045142514, 11702.941485292582, 11800.35098069608, 11804.350760708157, 11809.70475738107, 11811.645650630473, 2016.707577401276, 2054.527458684496, 2521.9747802521924, 2563.9615955537943, 2565.2047865161767, 2497.8126640501946, 2527.178666933245, 2533.3919859233742, 2536.480021595578, 2550.77043066124, 2468.2965354873863, 2488.415222422729, 2496.4133428644254, 2496.8252222344445, 2498.0757119865675, 2458.0659349447274, 2476.021971321327, 2481.367251350894, 2483.192962287255, 2486.2416962826333, 2306.4349234437504, 2315.7452680205165, 2384.9046038158453, 2397.1969390254285, 2418.5042066376527, 11932.0, 11965.641030769091, 12108.636740897788, 12120.757584848254, 12132.45403956824, 5961.821145365646, 6123.142760013601, 6125.050617154348, 6127.509799216052, 6127.754889804402, 3461.913452163691, 3527.1358517163203, 3533.8939831805087, 3586.677199052072, 3598.3702852001056, 1264.2856785915928, 1300.655326338521, 1311.1018952017803, 1332.8933685305249, 1398.9510367137202, 18022.729659055123, 18195.909020454812, 18201.36295229674, 18216.726749098772, 18244.90877545604, 4850.054239423279, 4972.65191436596, 5013.749312534359, 5087.262346959744, 5097.388313402415, 14981.850181498263, 15024.09855408679, 15028.248587570577, 15042.924785376003, 15045.699086018218, 6184.0, 6194.752209911553, 6226.0, 6226.62640241587, 6230.06549017651, 5526.364468086141, 5545.944540554624, 5589.357223919302, 5637.689927054052, 5655.066913959151, 4283.378910058024, 4312.137572485515, 4460.732356058582, 4552.840650577214, 4564.6348292136545, 1134.6085600467825, 1146.696125526736, 1169.9415029248503, 1171.8945294923499, 1174.301290732013, 1026.8921763214905, 1055.1822337688352, 1060.0618452669444, 1064.4518073192312, 1067.3275836223129, 7500.662470188855, 7517.0, 7533.585652789149, 7545.170031296553, 7639.961800191075, 1087.8585783848207, 1090.378484263962, 1099.0328510910347, 1099.1371773158032, 1099.483242875847, 4609.216433206296, 4627.259638457831, 4638.97680511602, 4642.814287428465, 4650.0, 14359.92820035893, 14443.92778036124, 14500.202488863213, 14676.412943482355, 14723.484678036217, 14269.0, 14502.202378869264, 14595.708085838223, 14599.0, 14694.779578306334, 10431.687049388378, 10486.213533984981, 10618.36289822613, 10631.946840265904, 10639.840402393971, 7436.256374362737, 7575.621218939141, 7615.619219039137, 7648.961755191411, 7746.978678315574, 12520.123591348518, 12564.497420103458, 12617.116801824151, 12623.179493332904, 12719.42762575705, 10454.68635940923, 12546.498140074294, 12734.61796146117, 12758.48966041348, 12991.480340786262, 10147.34042287249, 10252.0, 10344.0, 10347.223958203014, 10350.171986240932, 11392.25950313227, 11426.314421134759, 11452.08383329315, 11452.541898323974, 11491.310521368745, 9350.766230844283, 9463.763405914908, 9484.383515071499, 9521.238300936047, 9631.0, 9103.590338434919, 9240.722778316662, 9315.068493150902, 9329.58016889228, 9847.0, 8910.910890891268, 8918.86621700688, 8954.462732236087, 8971.102889711237, 9055.18503334703, 6783.287754785676, 6846.657667116525, 6867.450603951772, 6911.067005954132, 6930.237673855972, 6562.442192413699, 6627.304133065959, 6753.662316884234, 6790.830229244309, 6817.761378351734], "value": 5564.549826096804, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.68658849157384, 443.0673432424747, 445.18531088108745, 446.6449172907538, 450.76334924164814, 5171.2243163525445, 5265.684058956465, 5284.44513326101, 5285.762140703668, 5296.0, 5014.473480284563, 5204.115300398932, 5222.294990176325, 5222.858627962536, 5225.712585807779, 4303.376010478479, 4310.806013729381, 4319.697621166504, 4325.589069038437, 4328.39402483651, 4333.891652708676, 4339.848105316315, 4354.629856462205, 4355.324924636687, 4355.6515478761785, 4314.956850431487, 4315.4605674290715, 4331.963976930838, 4354.804033818486, 4374.7094607090985, 4171.979140104295, 4275.230458517465, 4281.7002809803325, 4309.439772829513, 4321.783910804447, 4053.412255222976, 4080.326746086922, 4080.3678528588507, 4094.4472496213225, 4100.507939047304, 5144.768485418165, 5167.863070124604, 5175.637705360627, 5187.922181167285, 5189.844304670866, 5022.372203474566, 5041.445441001487, 5050.080969231705, 5103.183490641517, 5113.718745468997, 5458.0, 5479.383581843365, 5498.0, 5505.5775458798, 5505.862353441156, 4551.362809206714, 4567.634589232854, 4583.633309335245, 4607.52132140232, 4619.399478067863, 5436.700981446019, 5599.7600959616475, 5650.63270887391, 5673.8581535461535, 5688.203651488795, 4704.929426058612, 4710.882227944328, 4731.645126615517, 4740.95259047412, 4776.785044672998, 4761.40482439695, 4801.303810947393, 4829.154897892855, 4829.95170048295, 4862.930155365814, 4866.537678920497, 5065.8733531662, 5075.1625981712605, 5099.413567439718, 5104.515071068243, 5501.284832971768, 5508.944910550923, 5519.199716041152, 5522.189689114579, 5545.889082218333, 1564.702706485764, 1653.4460955579775, 1662.7339625659836, 1671.3899426709406, 1673.21358961288, 1255.3974092435683, 1269.3526301586073, 1280.7182419867613, 1284.1460428814732, 1301.8307620009339, 5375.650582712111, 5376.274202982548, 5394.892102158014, 5455.399906010336, 5458.344998600193, 5693.686847223443, 5699.430056994267, 5705.659170095024, 5716.914246286309, 5719.3422756382715, 5138.3833939927445, 5298.470152984671, 5324.760385782649, 5369.167778994262, 5469.644473109235, 2907.5057240269207, 2948.0860933110803, 2959.4820906341306, 2959.70622767357, 2965.303153758865, 5784.71076446176, 5839.970800145972, 5844.88310233793, 5846.649201047951, 5884.323302820145, 5457.0, 5524.254225679562, 5559.444055594408, 5678.858028549319, 5758.193852860602, 5759.884802303931, 5780.393058728857, 5790.71046447676, 5797.4782269595935, 5846.327672317738, 5567.638103523258, 5587.385387607441, 5598.0, 5630.23991761107, 5673.659580425187, 5420.349558053059, 5574.609777315549, 5591.664500130005, 5617.185508101385, 5649.209110724584, 5884.411208973467, 5890.617109887844, 5906.497947674496, 5908.793192238251, 5910.7931222407005, 4500.032493014018, 4717.499725145678, 4755.120302744003, 4762.52374762521, 4768.546988036131, 5625.115586278479, 5658.20785090096, 5665.235193248858, 5682.659040457585, 5723.742431590672, 5522.944770552243, 5542.72286385671, 5544.861378465571, 5559.691983269948, 5561.443855614406, 5254.369475662945, 5489.258950041693, 5537.8061767837935, 5538.335399752055, 5558.221848941071, 5489.313835770528, 5501.2945986744235, 5512.944870551243, 5526.143447765603, 5541.778328866801, 5553.556075420339, 5556.972215138899, 5559.861003474945, 5576.77692892296, 5578.497935185853, 5173.974130129325, 5366.892662146814, 5380.919286210632, 5384.9192262116885, 5424.294841670558], "value": 4432.22428088925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.9402941794073, 863.365426411587, 892.7723430525215, 893.4282059481933, 906.3791302957475, 17600.64798704027, 19046.428607141774, 19456.70814937777, 19719.1126399312, 19875.99999999998, 18849.43451696448, 19432.0, 20051.298204562805, 20239.089240984154, 20282.29011984581, 899.4917871402653, 933.5145724223433, 940.731891410948, 941.1482608239554, 941.3316545252878, 898.9730308090768, 918.6049998500642, 927.3276874266137, 928.4522131942152, 928.6610387208653, 964.6286179820785, 965.4931161140387, 966.2028826218362, 967.3083745122236, 967.5210770668529, 960.0303693269848, 964.064857088623, 964.6527250189926, 964.8793900762405, 966.246327864267, 706.5548704316247, 706.6925887239016, 708.0158579574396, 709.0923617769264, 709.1419382547073, 16959.067251301174, 16967.915160424243, 16995.745063823928, 19093.61812763751, 19410.417687469257, 15944.521664350088, 16266.593335166715, 16375.672486550202, 16434.26045827929, 16903.23935422909, 13472.25902575358, 13830.446782128703, 13862.4455021799, 13957.232352220624, 14036.0, 4020.2361551305453, 4051.817668204938, 4084.1627466369323, 4131.876043718693, 4222.493300803892, 79376.0, 83838.6108988092, 85033.57483212545, 87596.12403875943, 87638.0, 12816.93591532055, 12841.357932103358, 12864.549740759216, 12887.355632218352, 12927.030472714585, 24027.159049433187, 24148.758512415003, 24279.878600606884, 24388.512229755306, 24544.631830522558, 9047.321450891079, 9171.358004939724, 9180.540972951325, 9408.341416100944, 9432.575534100983, 5298.629095963245, 5315.707636079976, 5395.109806881899, 5395.14129895885, 5398.163284690879, 3823.598522155189, 3883.0680636647567, 3921.568627450978, 3923.1172986077904, 3931.68546516278, 655.5312951239887, 656.022526435617, 656.7668477690352, 664.1266734244549, 668.5854770042605, 779.5244900610624, 803.3773825285334, 809.283783851284, 813.0, 826.4132465949123, 10857.0, 11809.52761889532, 11944.940275298743, 12107.75784484323, 12388.690282742824, 833.629035079396, 840.0843081041694, 845.2308399356556, 862.2713806833341, 862.4609618988133, 5428.131498960148, 5433.673979561239, 5474.972625136928, 5500.0, 5604.85987850307, 83363.0, 85165.57417212875, 85190.0, 86459.0, 87433.56283218543, 62127.68936155381, 70039.64980175067, 76765.61617191878, 77288.61355693186, 79353.60323198463, 55506.0, 55731.442685573435, 67509.3249067503, 67990.66004669946, 68909.65545172241, 11758.118141139277, 12071.818922715991, 12131.150819442908, 12372.072094592762, 12413.44139513706, 39548.0, 41651.583484164774, 43119.56880431281, 43120.0, 43883.34174987442, 50068.0, 50719.49280507147, 50743.74628126836, 52588.211176833145, 53510.73244633752, 13367.598972030855, 13633.79549306781, 13642.454301827816, 13897.235652039235, 14050.156990580597, 15459.381624734884, 15710.921445393156, 15747.291371888063, 16230.837691622932, 16738.832611674214, 15165.165915874733, 15605.0, 15650.295736691649, 16449.67100658004, 16483.423080192137, 14590.85409145895, 15104.622384440478, 15269.465568705042, 15898.920505397398, 16159.0, 14449.78325325142, 14696.41214351414, 14732.631684207552, 14906.850931490546, 14957.700845982801, 10291.33106348085, 10470.633527826489, 10621.575136994743, 10837.566497340333, 11077.335359878432, 12163.75672486563, 12260.32568208757, 12297.449943520596, 12750.0, 12761.872381276069], "value": 8104.955484774567, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 112.0, 114.0, 113.0, 114.0, 115.0, 114.0, 114.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 121.0, 148.0, 116.0, 116.0, 115.0, 115.0, 133.0, 117.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 159.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 122.0, 76.0, 76.0, 77.0, 77.0, 106.0, 75.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 102.0, 83.0, 82.0, 81.0, 93.0, 83.0, 83.0, 109.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 56.0, 53.0, 53.0, 52.0, 54.0, 56.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 54.0, 52.0, 54.0, 52.0, 53.0, 54.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 85.0, 81.0, 84.0, 85.0, 85.0, 75.0, 89.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 120.0, 122.0, 119.0, 121.0, 122.0, 120.0, 161.0, 118.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 164.0, 164.0, 160.0, 163.0, 163.0, 163.0, 160.0, 168.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 63.0, 59.0, 60.0, 60.0, 59.0, 58.0, 60.0, 59.0], "value": 59.7, "unit": "score"}]}]} 13:02:00 INFO - Return code: 0 13:02:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:02:00 INFO - # TBPL SUCCESS # 13:02:00 INFO - Running post-action listener: _resource_record_post_action 13:02:00 INFO - [mozharness: 2017-05-22 20:02:00.234901Z] Finished run-tests step (success) 13:02:00 INFO - Running post-run listener: _resource_record_post_run 13:02:00 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328175616; Read time: 12; Write time: 160372 13:02:00 INFO - TinderboxPrint: CPU usage
13.1% 13:02:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:02:00 INFO - TinderboxPrint: I/O write bytes / time
328,175,616 / 160,372 13:02:00 INFO - TinderboxPrint: CPU idle
8,246.9 (86.9%) 13:02:00 INFO - TinderboxPrint: CPU user
1,198.3 (12.6%) 13:02:00 INFO - TinderboxPrint: Swap in / out
0 / 0 13:02:00 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 90652672; Read time: 0; Write time: 58932 13:02:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 237522944; Read time: 0; Write time: 101440 13:02:00 INFO - Running post-run listener: _upload_blobber_files 13:02:00 INFO - Blob upload gear active. 13:02:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:02:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:02:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:02:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:02:01 INFO - (blobuploader) - INFO - Open directory for files ... 13:02:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:02:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:02:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:02 INFO - (blobuploader) - INFO - Done attempting. 13:02:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:02:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:02:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:03 INFO - (blobuploader) - INFO - Done attempting. 13:02:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:02:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:02:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:04 INFO - (blobuploader) - INFO - Done attempting. 13:02:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:02:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:02:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:07 INFO - (blobuploader) - INFO - Done attempting. 13:02:07 INFO - (blobuploader) - INFO - Iteration through files over. 13:02:07 INFO - Return code: 0 13:02:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:02:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:02:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/042d3e9716ec74aca1b2919f91c6e8bfb9e5c0fce1d71dd57405e1d59e05d27844791e69399b8ffa4b56b03f425e42688d93c9ceb0e2567549436894f067a754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f443751ce234151494092461bcbb66f2916ea83696a78c7343b777cbb63dea7c6213c8da59ecda0652783740405d24f499639e13516e7cb0cf55eb818a6791ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/816cd3a3f6a62673dc918e4aaaa5efad05162d293add0ff91a8f71e14d48b40ccfd6bd5827695d873d8fa90ac6ea01849b17a17706488af386561ebc8107cb92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1d7bd3381a22e48244f79387e58f74ef73f9aa54df77694817408075f79322f83fa7d421b985ac5e8b59ebf1f9ff1408ec6ef9fb4ecbe75a7761eda9c6077f9d"} 13:02:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:02:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:02:07 INFO - Contents: 13:02:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/042d3e9716ec74aca1b2919f91c6e8bfb9e5c0fce1d71dd57405e1d59e05d27844791e69399b8ffa4b56b03f425e42688d93c9ceb0e2567549436894f067a754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f443751ce234151494092461bcbb66f2916ea83696a78c7343b777cbb63dea7c6213c8da59ecda0652783740405d24f499639e13516e7cb0cf55eb818a6791ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/816cd3a3f6a62673dc918e4aaaa5efad05162d293add0ff91a8f71e14d48b40ccfd6bd5827695d873d8fa90ac6ea01849b17a17706488af386561ebc8107cb92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1d7bd3381a22e48244f79387e58f74ef73f9aa54df77694817408075f79322f83fa7d421b985ac5e8b59ebf1f9ff1408ec6ef9fb4ecbe75a7761eda9c6077f9d"} 13:02:07 INFO - Running post-run listener: copy_logs_to_upload_dir 13:02:07 INFO - Copying logs to upload dir... 13:02:07 INFO - mkdir: /builds/slave/test/build/upload/logs 13:02:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.429271 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-22 13:02:06.454473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 13:02:06.458743) ========= 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-1495480170.388738-1768667795 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/042d3e9716ec74aca1b2919f91c6e8bfb9e5c0fce1d71dd57405e1d59e05d27844791e69399b8ffa4b56b03f425e42688d93c9ceb0e2567549436894f067a754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f443751ce234151494092461bcbb66f2916ea83696a78c7343b777cbb63dea7c6213c8da59ecda0652783740405d24f499639e13516e7cb0cf55eb818a6791ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/816cd3a3f6a62673dc918e4aaaa5efad05162d293add0ff91a8f71e14d48b40ccfd6bd5827695d873d8fa90ac6ea01849b17a17706488af386561ebc8107cb92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1d7bd3381a22e48244f79387e58f74ef73f9aa54df77694817408075f79322f83fa7d421b985ac5e8b59ebf1f9ff1408ec6ef9fb4ecbe75a7761eda9c6077f9d"} build_url:https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/WXranwPGSl65o-qsjXzvpw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/042d3e9716ec74aca1b2919f91c6e8bfb9e5c0fce1d71dd57405e1d59e05d27844791e69399b8ffa4b56b03f425e42688d93c9ceb0e2567549436894f067a754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f443751ce234151494092461bcbb66f2916ea83696a78c7343b777cbb63dea7c6213c8da59ecda0652783740405d24f499639e13516e7cb0cf55eb818a6791ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/816cd3a3f6a62673dc918e4aaaa5efad05162d293add0ff91a8f71e14d48b40ccfd6bd5827695d873d8fa90ac6ea01849b17a17706488af386561ebc8107cb92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1d7bd3381a22e48244f79387e58f74ef73f9aa54df77694817408075f79322f83fa7d421b985ac5e8b59ebf1f9ff1408ec6ef9fb4ecbe75a7761eda9c6077f9d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 13:02:06.482600) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 13:02:06.482936) ========= 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-1495480170.388738-1768667795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 13:02:06.567456) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 13:02:06.567824) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 13:02:06.568162) ========= ========= Total master_lag: 0.36 =========