builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-085 starttime: 1495825195.73 results: success (0) revision: 9664d83f5c056a32a3cc29de9d5934b82809dbfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:55.727231) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:55.727765) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:55.728071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:55.775701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:55.776014) ========= 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=5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-26 11:59:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2017-05-26 11:59:56 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.163132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:55.958140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:55.961496) ========= 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=5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020046 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:56.028938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 11:59:56.032278) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9664d83f5c056a32a3cc29de9d5934b82809dbfd --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 9664d83f5c056a32a3cc29de9d5934b82809dbfd --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=5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-26 11:59:56,637 truncating revision to first 12 chars 2017-05-26 11:59:56,638 Setting DEBUG logging. 2017-05-26 11:59:56,638 attempt 1/10 2017-05-26 11:59:56,638 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9664d83f5c05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 11:59:57,237 unpacking tar archive at: mozilla-beta-9664d83f5c05/testing/mozharness/ program finished with exit code 0 elapsedTime=0.988522 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 11:59:57.154709) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:57.155064) ========= script_repo_revision: 9664d83f5c056a32a3cc29de9d5934b82809dbfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:57.159524) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:57.159828) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 11:59:57.361865) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-26 11:59:57.362146) ========= /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=5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:59:58 INFO - MultiFileLogger online at 20170526 11:59:58 in /builds/slave/test 11:59:58 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 11:59:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:59:58 INFO - {'append_to_log': False, 11:59:58 INFO - 'base_work_dir': '/builds/slave/test', 11:59:58 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 11:59:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:59:58 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 11:59:58 INFO - 'buildbot_json_path': 'buildprops.json', 11:59:58 INFO - 'config_files': ('talos/linux_config.py',), 11:59:58 INFO - 'default_actions': ('clobber', 11:59:58 INFO - 'read-buildbot-config', 11:59:58 INFO - 'download-and-extract', 11:59:58 INFO - 'populate-webroot', 11:59:58 INFO - 'create-virtualenv', 11:59:58 INFO - 'install', 11:59:58 INFO - 'run-tests'), 11:59:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:59:58 INFO - 'download_minidump_stackwalk': True, 11:59:58 INFO - 'download_symbols': 'ondemand', 11:59:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:59:58 INFO - 'tooltool.py': '/tools/tooltool.py', 11:59:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:59:58 INFO - '/tools/misc-python/virtualenv.py')}, 11:59:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:59:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:59:58 INFO - 'gecko_profile': False, 11:59:58 INFO - 'gecko_profile_interval': 0, 11:59:58 INFO - 'installer_path': 'installer.exe', 11:59:58 INFO - 'log_level': 'info', 11:59:58 INFO - 'log_name': 'talos', 11:59:58 INFO - 'log_to_console': True, 11:59:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:59:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:59:58 INFO - 'opt_config_files': (), 11:59:58 INFO - 'pip_index': False, 11:59:58 INFO - 'suite': 'dromaeojs', 11:59:58 INFO - 'system_bits': '32', 11:59:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:59:58 INFO - 'title': 'talos-linux64-ix-085', 11:59:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:59:58 INFO - 'use_talos_json': True, 11:59:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:59:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:59:58 INFO - 'work_dir': 'build'} 11:59:58 INFO - [mozharness: 2017-05-26 18:59:58.069865Z] Running clobber step. 11:59:58 INFO - Running pre-action listener: _resource_record_pre_action 11:59:58 INFO - Running main action method: clobber 11:59:58 INFO - rmtree: /builds/slave/test/build 11:59:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:59:59 INFO - Running post-action listener: _resource_record_post_action 11:59:59 INFO - [mozharness: 2017-05-26 18:59:59.088650Z] Finished clobber step (success) 11:59:59 INFO - [mozharness: 2017-05-26 18:59:59.088750Z] Running read-buildbot-config step. 11:59:59 INFO - Running pre-action listener: _resource_record_pre_action 11:59:59 INFO - Running main action method: read_buildbot_config 11:59:59 INFO - Using buildbot properties: 11:59:59 INFO - { 11:59:59 INFO - "project": "", 11:59:59 INFO - "product": "firefox", 11:59:59 INFO - "who": "ryanvm@gmail.com", 11:59:59 INFO - "installer_path": "public/build/target.tar.bz2", 11:59:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 11:59:59 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 11:59:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 11:59:59 INFO - "stage_platform": "linux64", 11:59:59 INFO - "basedir": "/builds/slave/test", 11:59:59 INFO - "buildnumber": 57, 11:59:59 INFO - "platform": "ubuntu64_hw", 11:59:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:59:59 INFO - "slavebuilddir": "test", 11:59:59 INFO - "taskId": "SBVy_f4YQb2c85c0NgsDaw", 11:59:59 INFO - "branch": "mozilla-beta", 11:59:59 INFO - "script_repo_revision": "production", 11:59:59 INFO - "revision": "9664d83f5c056a32a3cc29de9d5934b82809dbfd", 11:59:59 INFO - "slavename": "talos-linux64-ix-085", 11:59:59 INFO - "repo_path": "releases/mozilla-beta" 11:59:59 INFO - } 11:59:59 INFO - Finding installer, test and symbols from parent task. 11:59:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SBVy_f4YQb2c85c0NgsDaw'}, attempt #1 11:59:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SBVy_f4YQb2c85c0NgsDaw'}, attempt #1 12:00:00 INFO - Task dependencies: caZ_lRPARI-V1pPJoNd-Sg 12:00:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg'}, attempt #1 12:00:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2 12:00:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/test_packages.json 12:00:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.crashreporter-symbols.zip 12:00:00 INFO - Running post-action listener: _resource_record_post_action 12:00:00 INFO - [mozharness: 2017-05-26 19:00:00.465126Z] Finished read-buildbot-config step (success) 12:00:00 INFO - [mozharness: 2017-05-26 19:00:00.465343Z] Running download-and-extract step. 12:00:00 INFO - Running pre-action listener: _resource_record_pre_action 12:00:00 INFO - Running main action method: download_and_extract 12:00:00 INFO - mkdir: /builds/slave/test/build/tests 12:00:00 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:00:00 INFO - trying https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.test_packages.json 12:00:00 INFO - Downloading https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:00:01 INFO - Downloaded 986 bytes. 12:00:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:00:01 INFO - Using the following test package requirements: 12:00:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:00:01 INFO - u'common': [u'target.common.tests.zip'], 12:00:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:00:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:00:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:00:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:00:01 INFO - u'mozbase': [u'target.common.tests.zip'], 12:00:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:00:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:00:01 INFO - u'web-platform': [u'target.common.tests.zip', 12:00:01 INFO - u'target.web-platform.tests.zip'], 12:00:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:00:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:00:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.common.tests.zip 12:00:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:00:01 INFO - Fetch https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.common.tests.zip into memory 12:00:02 INFO - Expected file size: 59711229 12:00:02 INFO - Obtained file size: 59711229 12:00:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:00:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.common.tests.zip 12:00:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:00:12 INFO - Fetch https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.common.tests.zip into memory 12:00:14 INFO - Expected file size: 59711229 12:00:14 INFO - Obtained file size: 59711229 12:00:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.talos.tests.zip 12:00:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:00:25 INFO - Fetch https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.talos.tests.zip into memory 12:00:26 INFO - Expected file size: 14404449 12:00:26 INFO - Obtained file size: 14404449 12:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:26 INFO - trying https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2 12:00:26 INFO - Downloading https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:00:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:00:28 INFO - Downloaded 59692341 bytes. 12:00:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2 12:00:28 INFO - mkdir: /builds/slave/test/properties 12:00:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:00:28 INFO - Writing to file /builds/slave/test/properties/build_url 12:00:28 INFO - Contents: 12:00:28 INFO - build_url:https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2 12:00:28 INFO - Running post-action listener: _resource_record_post_action 12:00:28 INFO - Running post-action listener: set_extra_try_arguments 12:00:28 INFO - [mozharness: 2017-05-26 19:00:28.926153Z] Finished download-and-extract step (success) 12:00:28 INFO - [mozharness: 2017-05-26 19:00:28.926378Z] Running populate-webroot step. 12:00:28 INFO - Running pre-action listener: _resource_record_pre_action 12:00:28 INFO - Running main action method: populate_webroot 12:00:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:00:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:00:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:00:28 INFO - '--tppagecycles', 12:00:28 INFO - '7'], 12:00:28 INFO - 'tests': ['tsvgm']}}, 12:00:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:00:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:00:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:00:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:00:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:00:28 INFO - 'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:00:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:00:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:00:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:00:28 INFO - 'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['damp', 'tps']}, 12:00:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:00:28 INFO - 'tests': ['damp', 'tps']}, 12:00:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['dromaeo_dom']}, 12:00:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:00:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['basic_compositor_video']}, 12:00:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:00:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['a11yr', 12:00:28 INFO - 'ts_paint', 12:00:28 INFO - 'tpaint', 12:00:28 INFO - 'sessionrestore', 12:00:28 INFO - 'sessionrestore_no_auto_restore', 12:00:28 INFO - 'tabpaint']}, 12:00:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:00:28 INFO - 'ts_paint', 12:00:28 INFO - 'tpaint', 12:00:28 INFO - 'sessionrestore', 12:00:28 INFO - 'sessionrestore_no_auto_restore', 12:00:28 INFO - 'tabpaint']}, 12:00:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:00:28 INFO - 'ts_paint', 12:00:28 INFO - 'tpaint', 12:00:28 INFO - 'sessionrestore', 12:00:28 INFO - 'sessionrestore_no_auto_restore', 12:00:28 INFO - 'tabpaint']}, 12:00:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:00:28 INFO - 'ts_paint', 12:00:28 INFO - 'tpaint', 12:00:28 INFO - 'sessionrestore', 12:00:28 INFO - 'sessionrestore_no_auto_restore', 12:00:28 INFO - 'tabpaint']}, 12:00:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['a11yr', 12:00:28 INFO - 'ts_paint', 12:00:28 INFO - 'tpaint', 12:00:28 INFO - 'sessionrestore', 12:00:28 INFO - 'sessionrestore_no_auto_restore', 12:00:28 INFO - 'tabpaint']}, 12:00:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['a11yr', 12:00:28 INFO - 'ts_paint', 12:00:28 INFO - 'tpaint', 12:00:28 INFO - 'sessionrestore', 12:00:28 INFO - 'sessionrestore_no_auto_restore', 12:00:28 INFO - 'tabpaint']}, 12:00:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['tsvgx', 12:00:28 INFO - 'tsvgr_opacity', 12:00:28 INFO - 'tart', 12:00:28 INFO - 'tscrollx', 12:00:28 INFO - 'cart', 12:00:28 INFO - 'tsvg_static']}, 12:00:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:00:28 INFO - 'tsvgr_opacity', 12:00:28 INFO - 'tart', 12:00:28 INFO - 'tscrollx', 12:00:28 INFO - 'cart', 12:00:28 INFO - 'tsvg_static']}, 12:00:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:00:28 INFO - 'talos_options': ['--disable-e10s'], 12:00:28 INFO - 'tests': ['tp5o']}, 12:00:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:00:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:00:28 INFO - 'talos_options': ['--disable-e10s', 12:00:28 INFO - '--xperf_path', 12:00:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:00:28 INFO - 'tests': ['tp5n']}, 12:00:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:00:28 INFO - 'talos_options': ['--xperf_path', 12:00:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:00:28 INFO - 'tests': ['tp5n']}}, 12:00:28 INFO - 'talos.zip': {'path': '', 12:00:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:00:28 INFO - Running post-action listener: _resource_record_post_action 12:00:28 INFO - [mozharness: 2017-05-26 19:00:28.948927Z] Finished populate-webroot step (success) 12:00:28 INFO - [mozharness: 2017-05-26 19:00:28.949017Z] Running create-virtualenv step. 12:00:28 INFO - Running pre-action listener: _resource_record_pre_action 12:00:28 INFO - Running main action method: create_virtualenv 12:00:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:00:28 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:00:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:00:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:00:29 INFO - Using real prefix '/usr' 12:00:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:00:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:00:31 INFO - Installing pip.................done. 12:00:31 INFO - Return code: 0 12:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:00:31 INFO - Reading from file tmpfile_stdout 12:00:31 INFO - Output received: 12:00:31 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:00:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:00:31 INFO - Using env: {'DISPLAY': ':0', 12:00:31 INFO - 'HOME': '/home/cltbld', 12:00:31 INFO - 'LANG': 'en_US.UTF-8', 12:00:31 INFO - 'LANGUAGE': 'en_US:en', 12:00:31 INFO - 'LOGNAME': 'cltbld', 12:00:31 INFO - 'MAIL': '/var/mail/cltbld', 12:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:31 INFO - 'MOZ_NO_REMOTE': '1', 12:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:31 INFO - 'NO_EM_RESTART': '1', 12:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:31 INFO - 'PWD': '/builds/slave/test', 12:00:31 INFO - 'SHELL': '/bin/bash', 12:00:31 INFO - 'SHLVL': '1', 12:00:31 INFO - 'TERM': 'linux', 12:00:31 INFO - 'TMOUT': '86400', 12:00:31 INFO - 'USER': 'cltbld', 12:00:31 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:31 INFO - '_': '/tools/buildbot/bin/python'} 12:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:31 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:00:31 INFO - Cleaning up... 12:00:31 INFO - Return code: 0 12:00:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:00:31 INFO - Using env: {'DISPLAY': ':0', 12:00:31 INFO - 'HOME': '/home/cltbld', 12:00:31 INFO - 'LANG': 'en_US.UTF-8', 12:00:31 INFO - 'LANGUAGE': 'en_US:en', 12:00:31 INFO - 'LOGNAME': 'cltbld', 12:00:31 INFO - 'MAIL': '/var/mail/cltbld', 12:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:31 INFO - 'MOZ_NO_REMOTE': '1', 12:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:31 INFO - 'NO_EM_RESTART': '1', 12:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:31 INFO - 'PWD': '/builds/slave/test', 12:00:31 INFO - 'SHELL': '/bin/bash', 12:00:31 INFO - 'SHLVL': '1', 12:00:31 INFO - 'TERM': 'linux', 12:00:31 INFO - 'TMOUT': '86400', 12:00:31 INFO - 'USER': 'cltbld', 12:00:31 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:31 INFO - '_': '/tools/buildbot/bin/python'} 12:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:32 INFO - Downloading/unpacking psutil>=3.1.1 12:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:00:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:00:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:00:33 INFO - Installing collected packages: psutil 12:00:33 INFO - Running setup.py install for psutil 12:00:33 INFO - building 'psutil._psutil_linux' extension 12:00:33 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:00:33 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:00:33 INFO - building 'psutil._psutil_posix' extension 12:00:33 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:00:33 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:00:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:00:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:00:33 INFO - Successfully installed psutil 12:00:33 INFO - Cleaning up... 12:00:33 INFO - Return code: 0 12:00:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:00:33 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:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:33 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:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:33 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:33 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:00:33 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:00:33 INFO - Using env: {'DISPLAY': ':0', 12:00:33 INFO - 'HOME': '/home/cltbld', 12:00:33 INFO - 'LANG': 'en_US.UTF-8', 12:00:33 INFO - 'LANGUAGE': 'en_US:en', 12:00:33 INFO - 'LOGNAME': 'cltbld', 12:00:33 INFO - 'MAIL': '/var/mail/cltbld', 12:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:33 INFO - 'MOZ_NO_REMOTE': '1', 12:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:33 INFO - 'NO_EM_RESTART': '1', 12:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:33 INFO - 'PWD': '/builds/slave/test', 12:00:33 INFO - 'SHELL': '/bin/bash', 12:00:33 INFO - 'SHLVL': '1', 12:00:33 INFO - 'TERM': 'linux', 12:00:33 INFO - 'TMOUT': '86400', 12:00:33 INFO - 'USER': 'cltbld', 12:00:33 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:33 INFO - '_': '/tools/buildbot/bin/python'} 12:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:00:35 INFO - Installing collected packages: mozsystemmonitor 12:00:35 INFO - Running setup.py install for mozsystemmonitor 12:00:35 INFO - Successfully installed mozsystemmonitor 12:00:35 INFO - Cleaning up... 12:00:35 INFO - Return code: 0 12:00:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:00:35 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:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:35 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:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:35 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:35 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:00:35 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:00:35 INFO - Using env: {'DISPLAY': ':0', 12:00:35 INFO - 'HOME': '/home/cltbld', 12:00:35 INFO - 'LANG': 'en_US.UTF-8', 12:00:35 INFO - 'LANGUAGE': 'en_US:en', 12:00:35 INFO - 'LOGNAME': 'cltbld', 12:00:35 INFO - 'MAIL': '/var/mail/cltbld', 12:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:35 INFO - 'MOZ_NO_REMOTE': '1', 12:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:35 INFO - 'NO_EM_RESTART': '1', 12:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:35 INFO - 'PWD': '/builds/slave/test', 12:00:35 INFO - 'SHELL': '/bin/bash', 12:00:35 INFO - 'SHLVL': '1', 12:00:35 INFO - 'TERM': 'linux', 12:00:35 INFO - 'TMOUT': '86400', 12:00:35 INFO - 'USER': 'cltbld', 12:00:35 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:35 INFO - '_': '/tools/buildbot/bin/python'} 12:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:35 INFO - Downloading/unpacking jsonschema==2.5.1 12:00:35 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:00:35 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:00:35 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:00:35 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:00:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:00:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:00:36 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:00:36 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:00:36 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:00:36 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:00:36 INFO - Downloading functools32-3.2.3-2.tar.gz 12:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:00:36 INFO - warning: no files found matching '*.txt' 12:00:36 INFO - no previously-included directories found matching 'build' 12:00:36 INFO - no previously-included directories found matching 'dist' 12:00:36 INFO - no previously-included directories found matching '.git*' 12:00:36 INFO - Installing collected packages: jsonschema, functools32 12:00:36 INFO - Running setup.py install for functools32 12:00:36 INFO - warning: no files found matching '*.txt' 12:00:36 INFO - no previously-included directories found matching 'build' 12:00:36 INFO - no previously-included directories found matching 'dist' 12:00:36 INFO - no previously-included directories found matching '.git*' 12:00:36 INFO - Successfully installed jsonschema functools32 12:00:36 INFO - Cleaning up... 12:00:37 INFO - Return code: 0 12:00:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:37 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:37 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:00:37 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:00:37 INFO - Using env: {'DISPLAY': ':0', 12:00:37 INFO - 'HOME': '/home/cltbld', 12:00:37 INFO - 'LANG': 'en_US.UTF-8', 12:00:37 INFO - 'LANGUAGE': 'en_US:en', 12:00:37 INFO - 'LOGNAME': 'cltbld', 12:00:37 INFO - 'MAIL': '/var/mail/cltbld', 12:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:37 INFO - 'MOZ_NO_REMOTE': '1', 12:00:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:37 INFO - 'NO_EM_RESTART': '1', 12:00:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:37 INFO - 'PWD': '/builds/slave/test', 12:00:37 INFO - 'SHELL': '/bin/bash', 12:00:37 INFO - 'SHLVL': '1', 12:00:37 INFO - 'TERM': 'linux', 12:00:37 INFO - 'TMOUT': '86400', 12:00:37 INFO - 'USER': 'cltbld', 12:00:37 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:37 INFO - '_': '/tools/buildbot/bin/python'} 12:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:00:37 INFO - Cleaning up... 12:00:37 INFO - Return code: 0 12:00:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:37 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:37 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:00:37 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:00:37 INFO - Using env: {'DISPLAY': ':0', 12:00:37 INFO - 'HOME': '/home/cltbld', 12:00:37 INFO - 'LANG': 'en_US.UTF-8', 12:00:37 INFO - 'LANGUAGE': 'en_US:en', 12:00:37 INFO - 'LOGNAME': 'cltbld', 12:00:37 INFO - 'MAIL': '/var/mail/cltbld', 12:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:37 INFO - 'MOZ_NO_REMOTE': '1', 12:00:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:37 INFO - 'NO_EM_RESTART': '1', 12:00:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:37 INFO - 'PWD': '/builds/slave/test', 12:00:37 INFO - 'SHELL': '/bin/bash', 12:00:37 INFO - 'SHLVL': '1', 12:00:37 INFO - 'TERM': 'linux', 12:00:37 INFO - 'TMOUT': '86400', 12:00:37 INFO - 'USER': 'cltbld', 12:00:37 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:37 INFO - '_': '/tools/buildbot/bin/python'} 12:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:37 INFO - Downloading/unpacking blobuploader==1.2.4 12:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:38 INFO - Downloading blobuploader-1.2.4.tar.gz 12:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:00:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:00:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:39 INFO - Downloading docopt-0.6.1.tar.gz 12:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:00:39 INFO - Installing collected packages: blobuploader, requests, docopt 12:00:39 INFO - Running setup.py install for blobuploader 12:00:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:00:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:00:39 INFO - Running setup.py install for requests 12:00:39 INFO - Running setup.py install for docopt 12:00:40 INFO - Successfully installed blobuploader requests docopt 12:00:40 INFO - Cleaning up... 12:00:40 INFO - Return code: 0 12:00:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:40 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:40 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:00:40 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:00:40 INFO - Using env: {'DISPLAY': ':0', 12:00:40 INFO - 'HOME': '/home/cltbld', 12:00:40 INFO - 'LANG': 'en_US.UTF-8', 12:00:40 INFO - 'LANGUAGE': 'en_US:en', 12:00:40 INFO - 'LOGNAME': 'cltbld', 12:00:40 INFO - 'MAIL': '/var/mail/cltbld', 12:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:40 INFO - 'MOZ_NO_REMOTE': '1', 12:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:40 INFO - 'NO_EM_RESTART': '1', 12:00:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:40 INFO - 'PWD': '/builds/slave/test', 12:00:40 INFO - 'SHELL': '/bin/bash', 12:00:40 INFO - 'SHLVL': '1', 12:00:40 INFO - 'TERM': 'linux', 12:00:40 INFO - 'TMOUT': '86400', 12:00:40 INFO - 'USER': 'cltbld', 12:00:40 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:40 INFO - '_': '/tools/buildbot/bin/python'} 12:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:00:40 INFO - Running setup.py (path:/tmp/pip-7InE8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:00:40 INFO - Running setup.py (path:/tmp/pip-jQGY2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:00:40 INFO - Running setup.py (path:/tmp/pip-YIJC6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:00:40 INFO - Running setup.py (path:/tmp/pip-BOjq72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:00:40 INFO - Running setup.py (path:/tmp/pip-ReoDaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:00:40 INFO - Running setup.py (path:/tmp/pip-M4VMo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:00:40 INFO - Running setup.py (path:/tmp/pip-REbzhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:00:40 INFO - Running setup.py (path:/tmp/pip-UAcGAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:00:40 INFO - Running setup.py (path:/tmp/pip-iXnfEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:00:40 INFO - Running setup.py (path:/tmp/pip-bue71X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:00:40 INFO - Running setup.py (path:/tmp/pip-dasUzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:00:41 INFO - Running setup.py (path:/tmp/pip-LvNV7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:00:41 INFO - Running setup.py (path:/tmp/pip-tO3g6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:00:41 INFO - Running setup.py (path:/tmp/pip-jpHOb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:41 INFO - Running setup.py (path:/tmp/pip-7qUDvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:00:41 INFO - Running setup.py (path:/tmp/pip-zzhCcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:00:41 INFO - Running setup.py (path:/tmp/pip-2SiUWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:00:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:00:41 INFO - Running setup.py install for manifestparser 12:00:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:00:41 INFO - Running setup.py install for mozcrash 12:00:41 INFO - Running setup.py install for mozdebug 12:00:41 INFO - Running setup.py install for mozdevice 12:00:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:00:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:00:41 INFO - Running setup.py install for mozfile 12:00:41 INFO - Running setup.py install for mozhttpd 12:00:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for mozinfo 12:00:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for mozInstall 12:00:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for mozleak 12:00:42 INFO - Running setup.py install for mozlog 12:00:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for moznetwork 12:00:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for mozprocess 12:00:42 INFO - Running setup.py install for mozprofile 12:00:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for mozrunner 12:00:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for mozscreenshot 12:00:43 INFO - Running setup.py install for moztest 12:00:43 INFO - Running setup.py install for mozversion 12:00:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:00:43 INFO - Cleaning up... 12:00:43 INFO - Return code: 0 12:00:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:00:43 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:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:43 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:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:43 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:43 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:00:43 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:00:43 INFO - Using env: {'DISPLAY': ':0', 12:00:43 INFO - 'HOME': '/home/cltbld', 12:00:43 INFO - 'LANG': 'en_US.UTF-8', 12:00:43 INFO - 'LANGUAGE': 'en_US:en', 12:00:43 INFO - 'LOGNAME': 'cltbld', 12:00:43 INFO - 'MAIL': '/var/mail/cltbld', 12:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:43 INFO - 'MOZ_NO_REMOTE': '1', 12:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:43 INFO - 'NO_EM_RESTART': '1', 12:00:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:43 INFO - 'PWD': '/builds/slave/test', 12:00:43 INFO - 'SHELL': '/bin/bash', 12:00:43 INFO - 'SHLVL': '1', 12:00:43 INFO - 'TERM': 'linux', 12:00:43 INFO - 'TMOUT': '86400', 12:00:43 INFO - 'USER': 'cltbld', 12:00:43 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:43 INFO - '_': '/tools/buildbot/bin/python'} 12:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:00:43 INFO - Running setup.py (path:/tmp/pip-OTBYwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:00:43 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:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:00:43 INFO - Running setup.py (path:/tmp/pip-vwbiGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:00:43 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:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:00:43 INFO - Running setup.py (path:/tmp/pip-BKFoCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:00:43 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:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:00:43 INFO - Running setup.py (path:/tmp/pip-UTHdEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:00:43 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:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:00:43 INFO - Running setup.py (path:/tmp/pip-dAUW4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:00:43 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:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:00:43 INFO - Running setup.py (path:/tmp/pip-QPbrr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:00:43 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:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:00:43 INFO - Running setup.py (path:/tmp/pip-8AWa4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:00:44 INFO - Running setup.py (path:/tmp/pip-5ZPWoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:00:44 INFO - Running setup.py (path:/tmp/pip-PtYwau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:00:44 INFO - Running setup.py (path:/tmp/pip-fnRvxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:00:44 INFO - Running setup.py (path:/tmp/pip-sceRCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:00:44 INFO - Running setup.py (path:/tmp/pip-ledzwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:00:44 INFO - Running setup.py (path:/tmp/pip-Zr2L3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:00:44 INFO - Running setup.py (path:/tmp/pip-Y_ptyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:44 INFO - Running setup.py (path:/tmp/pip-Lm2M2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:00:44 INFO - Running setup.py (path:/tmp/pip-am5fgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:00:44 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:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:00:44 INFO - Running setup.py (path:/tmp/pip-xTCxcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:00:44 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:00:44 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:00:44 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:00:44 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:00:44 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:00:44 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:00:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:00:44 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:00:44 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:00:44 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:00:44 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:00:45 INFO - Downloading blessings-1.6.tar.gz 12:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:00:45 INFO - Installing collected packages: blessings 12:00:45 INFO - Running setup.py install for blessings 12:00:46 INFO - Successfully installed blessings 12:00:46 INFO - Cleaning up... 12:00:46 INFO - Return code: 0 12:00:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:00:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:00:46 INFO - Reading from file tmpfile_stdout 12:00:46 INFO - Current package versions: 12:00:46 INFO - argparse == 1.2.1 12:00:46 INFO - blessings == 1.6 12:00:46 INFO - blobuploader == 1.2.4 12:00:46 INFO - docopt == 0.6.1 12:00:46 INFO - functools32 == 3.2.3-2 12:00:46 INFO - jsonschema == 2.5.1 12:00:46 INFO - manifestparser == 1.1 12:00:46 INFO - mozInstall == 1.12 12:00:46 INFO - mozcrash == 1.0 12:00:46 INFO - mozdebug == 0.1 12:00:46 INFO - mozdevice == 0.49 12:00:46 INFO - mozfile == 1.2 12:00:46 INFO - mozhttpd == 0.7 12:00:46 INFO - mozinfo == 0.9 12:00:46 INFO - mozleak == 0.1 12:00:46 INFO - mozlog == 3.4 12:00:46 INFO - moznetwork == 0.27 12:00:46 INFO - mozprocess == 0.25 12:00:46 INFO - mozprofile == 0.28 12:00:46 INFO - mozrunner == 6.13 12:00:46 INFO - mozscreenshot == 0.1 12:00:46 INFO - mozsystemmonitor == 0.3 12:00:46 INFO - moztest == 0.8 12:00:46 INFO - mozversion == 1.4 12:00:46 INFO - psutil == 3.1.1 12:00:46 INFO - requests == 1.2.3 12:00:46 INFO - wsgiref == 0.1.2 12:00:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:00:46 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:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:46 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:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:46 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:46 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:00:46 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:00:46 INFO - Using env: {'DISPLAY': ':0', 12:00:46 INFO - 'HOME': '/home/cltbld', 12:00:46 INFO - 'LANG': 'en_US.UTF-8', 12:00:46 INFO - 'LANGUAGE': 'en_US:en', 12:00:46 INFO - 'LOGNAME': 'cltbld', 12:00:46 INFO - 'MAIL': '/var/mail/cltbld', 12:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:46 INFO - 'MOZ_NO_REMOTE': '1', 12:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:46 INFO - 'NO_EM_RESTART': '1', 12:00:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:46 INFO - 'PWD': '/builds/slave/test', 12:00:46 INFO - 'SHELL': '/bin/bash', 12:00:46 INFO - 'SHLVL': '1', 12:00:46 INFO - 'TERM': 'linux', 12:00:46 INFO - 'TMOUT': '86400', 12:00:46 INFO - 'USER': 'cltbld', 12:00:46 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:46 INFO - '_': '/tools/buildbot/bin/python'} 12:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 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:00:46 INFO - Cleaning up... 12:00:46 INFO - Return code: 0 12:00:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:00:46 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:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:46 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:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:46 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 0x1ef61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2194310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, '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': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', '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:00:46 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:00:46 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:00:46 INFO - Using env: {'DISPLAY': ':0', 12:00:46 INFO - 'HOME': '/home/cltbld', 12:00:46 INFO - 'LANG': 'en_US.UTF-8', 12:00:46 INFO - 'LANGUAGE': 'en_US:en', 12:00:46 INFO - 'LOGNAME': 'cltbld', 12:00:46 INFO - 'MAIL': '/var/mail/cltbld', 12:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:46 INFO - 'MOZ_NO_REMOTE': '1', 12:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:46 INFO - 'NO_EM_RESTART': '1', 12:00:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:46 INFO - 'PWD': '/builds/slave/test', 12:00:46 INFO - 'SHELL': '/bin/bash', 12:00:46 INFO - 'SHLVL': '1', 12:00:46 INFO - 'TERM': 'linux', 12:00:46 INFO - 'TMOUT': '86400', 12:00:46 INFO - 'USER': 'cltbld', 12:00:46 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:46 INFO - '_': '/tools/buildbot/bin/python'} 12:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:00:46 INFO - Cleaning up... 12:00:46 INFO - Return code: 0 12:00:46 INFO - Running post-action listener: _resource_record_post_action 12:00:46 INFO - Running post-action listener: _start_resource_monitoring 12:00:46 INFO - Starting resource monitoring. 12:00:46 INFO - [mozharness: 2017-05-26 19:00:46.775306Z] Finished create-virtualenv step (success) 12:00:46 INFO - [mozharness: 2017-05-26 19:00:46.775826Z] Running install step. 12:00:46 INFO - Running pre-action listener: _resource_record_pre_action 12:00:46 INFO - Running main action method: install 12:00:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:00:46 INFO - Reading from file tmpfile_stdout 12:00:46 INFO - Detecting whether we're running mozinstall >=1.0... 12:00:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:00:47 INFO - Reading from file tmpfile_stdout 12:00:47 INFO - Output received: 12:00:47 INFO - Usage: mozinstall [options] installer 12:00:47 INFO - Options: 12:00:47 INFO - -h, --help show this help message and exit 12:00:47 INFO - -d DEST, --destination=DEST 12:00:47 INFO - Directory to install application into. [default: 12:00:47 INFO - "/builds/slave/test"] 12:00:47 INFO - --app=APP Application being installed. [default: firefox] 12:00:47 INFO - mkdir: /builds/slave/test/build/application 12:00:47 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:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:01:01 INFO - Reading from file tmpfile_stdout 12:01:01 INFO - Output received: 12:01:01 INFO - /builds/slave/test/build/application/firefox/firefox 12:01:01 INFO - Running post-action listener: _resource_record_post_action 12:01:01 INFO - [mozharness: 2017-05-26 19:01:01.086702Z] Finished install step (success) 12:01:01 INFO - [mozharness: 2017-05-26 19:01:01.086943Z] Running run-tests step. 12:01:01 INFO - Running pre-action listener: _resource_record_pre_action 12:01:01 INFO - Running main action method: run_tests 12:01:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:01:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:01:01 INFO - Python 2.7.3 12:01:01 INFO - Return code: 0 12:01:01 INFO - grabbing minidump binary from tooltool 12:01: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:01:01 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 0x2191400>, '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 0x21923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2192840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:01:01 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:01:01 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:01:01 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:01:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:01:01 INFO - Return code: 0 12:01:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:01:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:01:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:01:01 INFO - ENV: RUST_BACKTRACE is now 1 12:01:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:01:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:01:01 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/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--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:01:01 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/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-085 --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:01:01 INFO - Using env: {'DISPLAY': ':0', 12:01:01 INFO - 'HOME': '/home/cltbld', 12:01:01 INFO - 'LANG': 'en_US.UTF-8', 12:01:01 INFO - 'LANGUAGE': 'en_US:en', 12:01:01 INFO - 'LOGNAME': 'cltbld', 12:01:01 INFO - 'MAIL': '/var/mail/cltbld', 12:01:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:01:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:01 INFO - 'MOZ_NO_REMOTE': '1', 12:01:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:01 INFO - 'NO_EM_RESTART': '1', 12:01:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:01 INFO - 'PWD': '/builds/slave/test', 12:01:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:01:01 INFO - 'RUST_BACKTRACE': '1', 12:01:01 INFO - 'SHELL': '/bin/bash', 12:01:01 INFO - 'SHLVL': '1', 12:01:01 INFO - 'TERM': 'linux', 12:01:01 INFO - 'TMOUT': '86400', 12:01:01 INFO - 'USER': 'cltbld', 12:01:01 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276', 12:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:01 INFO - '_': '/tools/buildbot/bin/python'} 12:01:01 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/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--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:01:01 INFO - mozversion application_buildid: 20170526171548 12:01:01 INFO - mozversion application_changeset: 9664d83f5c056a32a3cc29de9d5934b82809dbfd 12:01:01 INFO - mozversion application_display_name: Firefox 12:01:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:01:01 INFO - mozversion application_name: Firefox 12:01:01 INFO - mozversion application_remotingname: firefox 12:01:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:01:01 INFO - mozversion application_vendor: Mozilla 12:01:01 INFO - mozversion application_version: 54.0 12:01:01 INFO - mozversion platform_buildid: 20170526171548 12:01:01 INFO - mozversion platform_changeset: 9664d83f5c056a32a3cc29de9d5934b82809dbfd 12:01:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:01:01 INFO - mozversion platform_version: 54.0 12:01:01 INFO - using testdate: 1495825261 12:01:01 INFO - actual date: 1495825261 12:01:01 INFO - starting webserver on 'localhost:43133' 12:01:01 INFO - SUITE-START | Running 2 tests 12:01:01 INFO - TEST-START | dromaeo_css 12:01:01 INFO - operating with platform_type : linux_ 12:01:01 INFO - Initialising browser for dromaeo_css test... 12:01:01 INFO - TEST-INFO | started process 7346 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_grcDR/profile http://localhost:43133/getInfo.html) 12:01:05 INFO - PROCESS | 7346 | __metrics Screen width/height:1600/1200 12:01:05 INFO - PROCESS | 7346 | colorDepth:24 12:01:05 INFO - PROCESS | 7346 | Browser inner width/height: 1024/697 12:01:05 INFO - PROCESS | 7346 | __metrics 12:01:06 INFO - PROCESS | 7346 | 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:01:06 INFO - PROCESS | 7346 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:01:07 INFO - PROCESS | 7346 | console.error: PushService: 12:01:07 INFO - PROCESS | 7346 | stateChangeProcessEnqueue: Error transitioning state 12:01:07 INFO - PROCESS | 7346 | UnknownError 12:01:07 INFO - TEST-INFO | 7346: exit 0 12:01:07 INFO - Browser initialized. 12:01:07 INFO - Running cycle 1/1 for dromaeo_css test... 12:01:07 INFO - TEST-INFO | started process 7440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_grcDR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:01:19 INFO - PROCESS | 7440 | RSS: Main: 141340672 12:01:19 INFO - PROCESS | 7440 | 12:04:21 INFO - PROCESS | 7440 | Cycle 1(1): loaded http://localhost:43133/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43133/tests/dromaeo/cssquery-ext.html) 12:04:23 INFO - PROCESS | 7440 | RSS: Main: 277823488 12:04:23 INFO - PROCESS | 7440 | 12:07:25 INFO - PROCESS | 7440 | Cycle 1(1): loaded http://localhost:43133/tests/dromaeo/cssquery-ext.html (next: http://localhost:43133/tests/dromaeo/cssquery-jquery.html) 12:07:26 INFO - PROCESS | 7440 | RSS: Main: 219451392 12:07:26 INFO - PROCESS | 7440 | 12:10:27 INFO - PROCESS | 7440 | Cycle 1(1): loaded http://localhost:43133/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43133/tests/dromaeo/cssquery-mootools.html) 12:10:28 INFO - PROCESS | 7440 | RSS: Main: 171216896 12:10:28 INFO - PROCESS | 7440 | 12:13:30 INFO - PROCESS | 7440 | Cycle 1(1): loaded http://localhost:43133/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43133/tests/dromaeo/cssquery-prototype.html) 12:13:30 INFO - PROCESS | 7440 | RSS: Main: 171773952 12:13:30 INFO - PROCESS | 7440 | 12:16:33 INFO - PROCESS | 7440 | Cycle 1(1): loaded http://localhost:43133/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43133/tests/dromaeo/cssquery-yui.html) 12:16:34 INFO - PROCESS | 7440 | RSS: Main: 176394240 12:16:34 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | Cycle 1(1): loaded http://localhost:43133/tests/dromaeo/cssquery-yui.html (next: http://localhost:43133/tests/dromaeo/cssquery-dojo.html) 12:19:36 INFO - PROCESS | 7440 | RSS: Main: 264507392 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | __start_tp_report 12:19:36 INFO - PROCESS | 7440 | _x_x_mozilla_page_load 12:19:36 INFO - PROCESS | 7440 | _x_x_mozilla_page_load_details 12:19:36 INFO - PROCESS | 7440 | |i|pagename|runs| 12:19:36 INFO - PROCESS | 7440 | |0;dojo.html;3425.1094715374;3449.7412694047953;3452.6029506606747;3469.0980345110265;3493.4410494320914;5738.7417566209515;5792.913106303404;5803.593748437611;5809.912851307228;5815.5638327125425;5765.423457654243;5821.417858214187;5827.796027139051;5828.0675091985295;5828.300603927534;5202.4537423570455;5263;5293.6294459387855;5296.285001524802;5330.307060082184;5044.470330615288;5148.691078535281;5342.091844386464;5351.384590772072;5353.491418315254;5242.423333433319;5252.579793616502;5280.128778751501;5298.92051619226;5300.363956325246;5317.574594032468;5323.893522129555;5332.14685650296;5332.386775520826;5339.599530035263;5073.340465739467;5205.531502164823;5257.1588545832865;5313.3092697949405;5314.388845282765;5631.465010823965;5659.801906933238;5717.456841600041;5734.827955161351;5735.684537350444;6186.505079593667;6214.875702485925;6219;6227.532935029845;6228.87542249157;6195.370953080206;6262.561620686553;6266;6280;6280.340564240734;5630.634008789416;5649.152627105926;5745.7127143643065;5782.277215348082;5795.9420405796245;6313.337099604567;6325.240971083499;6327.367263273728;6328.145700330489;6330.651814150174;5983.790567330209;6049.909251361233;6054.485368743618;6098.634081955097;6102.420270074336;6002.219711437485;6030.366811484819;6043.335233124353;6111.297200821873;6215.782447614311;6217.564770466024;6237.844053898689;6239.220097487814;6239.438450539473;6239.532035097386;6049.576529642883;6068;6101.237345331833;6105.8168254952425;6115.174451448997;4702.3416721659;4712.057588482317;4746.95253047472;4765.1660959331975;4793.065352256276;4186.079062606222;4270.316749320094;4291.957080429156;4292.055747735493;4326.307790753464;5781.8554536136935;5864.354920958691;5868.618539794899;5909.8227053188475;5914.142449344907;6161.771459377412;6188.938110618927;6197;6199.442050215503;6202.193714817045;5766.452187042224;5766.711664416763;5777.740001699934;5824.767009319665;5828.94171058284;5126.436092029874;5137.101007323703;5204.193350030751;5206.0368831766245;5208.322918020633;6436.645984470899;6457.7094030767785;6462.838429039218;6473.773417930444;6474.6762661866715;6156.476699480594;6175.3515880831865;6220.875582488235;6229.657368844757;6233.812985610439;6119.136988619874;6226.688665566794;6296.0555916612875;6357.936420635734;6450.870982580416;6152.692365381802;6154.507639388748;6162.568620196544;6162.722677479434;6166.260048794174;6251.312355640966;6257.655828929453;6267.059941008885;6269.529785266214;6276.6547839869245;5777.62445441045;5878.3239927407185;5973.522118230444;6059.878802423838;6071.666058366655;6079.148919151234;6086.6348019119;6103.05402662588;6103.572749907641;6120.143179954721;6002.549808764273;6055;6055.909161362495;6075.908861367171;6080.604760690541;5822.3304320003745;5849.210356601804;5894.734736936763;5897.852553686193;6029.668368239789;5986.07215881539;6005.879882402415;6007.669578173242;6015.157877897011;6015.939840601713 12:19:36 INFO - PROCESS | 7440 | |1;ext.html;747.7794050755031;811.3265989228938;817.3747083481136;877.1184959116088;895.1987970766161;31908.20229494264;42957.35563966539;43079;43082.35376469355;43158.78420607896;40958.59041409585;41010.17979640405;41149.7942510287;46807.29789053167;46918.296225556645;7743.6128193590375;9310.67412640556;9458.33791634586;9459.432434053944;9463.905360946406;7761.3402860756905;10758.354498730063;11059.72350691231;11074.335539867634;11075.72310692231;11194.832077518844;11212.719682007973;11222.326660400362;11222.887771122267;11222.943885280603;11164.888351116506;11185.776284474307;11186.049185819214;11198.832017519744;11200.103991680646;10718.624848130277;10766.24636275461;10795.730106747395;10807.945960270226;10813.891861081367;44299;44652.10695786098;44699.329510057374;44778;44789.328160077624;4371.781410929472;4416.624586910117;4429.734215947054;4531.796069176895;4551.635869130461;31196.064118076494;31903.52144717831;31940.520892186636;33053.6694633053;34751.131221719406;3801.1067399161175;3870.9032274193087;3902.609739026103;3954.8813535593977;3964.5044369453817;253084;253797.7310113456;254162;254609;254814;57468;57647.711761441766;57665;57770;57787.422125778205;59092;59120;59126.40873591295;59416.40583594195;59569.106463403084;17344.47966561005;17434.825651743322;17486.038267895132;17536.386226482013;17553.122343882755;13592.592222233348;13674.247916364695;15525.689486210213;15554.844451555566;15560.144192069545;14685.706285874223;14790.408383664748;14808;14980.700385992437;15340.769888451681;6122.969385153135;6187.257529096447;6279.623222606659;6342.270638876496;6357.554971151975;1005.7284533176002;1022.1005515146624;1022.1567207054061;1022.4938655365493;1034.1623285139062;28988.42023159525;29241.415171696448;29403;29688.554671679944;29908;1097.3361116524547;1101.6694991502518;1105.2760441910561;1133.280366966982;1183.7099910521977;15533.145676987875;16746.665066698843;16777.664446711242;17632.911835441;17657.735133973;355678;358293;358324;358517;359008;61010.694946524985;61231.38768612346;61242.0813687795;61270;61274.38725612776;1574.5984773882587;1589.30865073691;1625.1224338856885;1633.4119716901905;1635.3703824027598;31570;32758.07844566389;32919.67080329262;33102.66897331092;33310.83344583359;43630.12739745251;44028;44034;44063.55936440723;44075.77962110169;319289;321470.39264803525;321717;322326;323950;25756.522897965817;32858;33084.503732444515;33237.16907077342;38278.42582361322;41731.58268417398;41964;42121.36817947795;42168.36747448852;42277.57722422737;32144.357112857146;32604.510932336507;32615.673843261262;32630.51054234141;32733.67266327306;32053.35893282168;32056;32360.190995225308;32408.35183296368;32864;36634;37220.25559488849;37702.43446348252;37736;37778.811105944296;30601.693983060773;30610;31099;31135;31438.056858294287;24402.29183957106;25039.749602503744;25276.115335963153;25287;25827.483450331263 12:19:36 INFO - PROCESS | 7440 | |2;jquery.html;456.1287940034535;468.173673466332;472.2420515073307;478.760619690155;490.94354149272834;20887.373378798642;21911.12355505778;22024.119035238593;22256.777432225692;22391.216307429244;20565.794342056575;20831;22165;22846.428839279026;23352.18267360643;5232.73836308183;6769.5599786014;6979;7003.3346832050875;7016.473764467685;6905.447564194852;6946.2011868635;7011.579305241702;7014.579125252476;7018.82452938678;6729.427998620124;6861.1766588009505;6885.758998435056;6886.552374095692;6896.448284137257;6629.701663425158;6775.254721980578;6827.829304267433;6859.691313890862;6877.793666189998;6664.233613134503;6682.131322928037;6693.46452283821;6695.531312808105;6698.062271282024;24263.636045459156;24844.3788905277;24937.875310623513;26474.73525264742;26604.733952660612;3606.783592984429;3663.377225871608;3699.7595156314756;3715.492042871051;3769.736118471708;15997.680046399008;16149.434769783118;16235.993824126666;16529.33882644693;16836.07401592912;2275.5676421479866;2326.7789559991775;2381.321323422816;2441.4628781668002;2604.296839853229;116895;120413;120980.39509802483;124596.37701811433;125183;18281.542961425806;18302.450926472226;18390.172442239862;18478.67863970557;18709.251629934923;32010.839945800122;32018.67981320157;32110.357792844014;32297.677023229466;32438.67561324404;10445.738856528496;10554.683359499228;10558.630447934285;10845.186611004206;10922.29005114665;8937.553122343857;8966.058563850833;8984.775380615407;9162.770930726652;9208.447493150432;8822.470651760916;9250.72247832566;9289.90710092904;9393.154616084585;9484.099010593982;3493.9301213975577;3554.431290993429;3609.3864043112735;3784;3809.542854857435;761.4479502360745;916.8074704312169;933.8785957825439;985.7979114281555;1066.5573786878358;19973;21046.47383815417;21140.577188456453;21146.471338216666;21171;1075.709558419238;1090.596479302663;1098.3794156301665;1104.6741211342674;1108.833674948748;11501.827472587918;11721.531138754524;11784;11823.113266504875;11908.702282443008;612332;616309;632628;641745;644757;72413.27586724065;112271;114954.42522787332;117412.41293793647;121756;1223.6512593910693;1245.3897590180745;1275.834141561591;1277.9680507987375;1289.2071376103816;18838.3406580769;19054.7141792876;19063.90468047651;19109.426717198505;19214.423567293004;47067.94058386194;50026.74933126556;51716;51871;52482;65846.34153658402;66420.33579664335;66595.66702166652;66676;66879;11944.343061131722;12090;12172.817407738714;12436.253824770543;12590.181638193488;13951.093178943336;14039.157650541;14338.56984290473;14379.836435984067;14632.853671463148;11556;11622.883771162518;11712.180147389601;11757.76484470323;11973.401329933642;10790.514426850654;10992.186289262183;11015.724606884893;11539.653810385702;11767.823482647938;12315.630531084082;12402.379881006094;12510.061745368952;12573.371331433575;12823.93588032054;12253.938730306649;12296.938515307365;12410.193337433038;12451.315177665316;12863.807042894177;11248;11444.713882153013;12355.011599071873;12505.43725532335;12849.293288868828 12:19:36 INFO - PROCESS | 7440 | |3;mootools.html;1509.2604623734367;1535.5546891401493;1538.115583539465;1538.12326973625;1567.051933580184;10848.945755271223;11434;11446.599369022086;11479.253848499862;11479.942600286988;10924.453777311122;11385.089192864572;11651.300921944689;11660.47527861246;11690.29858208508;2463.039414628292;2503.561876671575;2504.887280072392;2505.837120587165;2505.887235074417;2396.269137912936;2410.0480310277517;2473.2332976777166;2475.5420247254224;2475.653408522808;2401.4236583220077;2403.531311394279;2414.782669559739;2430.0765709030634;2433.6227884677814;2382.845115067515;2418.0811291709215;2421.0920904660666;2423.8182136339756;2453.03105273416;2315.872627005514;2331.4287999440076;2339.9181028663916;2345.5543446745232;2346.8122550196113;11341.659750207507;11520.884791152064;11527.077833773363;11542.018928391097;11552.133589980785;5618.606697531174;5769.278840144982;5771.942280577183;5780.3063632363965;5782.277215348082;3322.103032181322;3370.747193960463;3373.578302712161;3379.374815659111;3432.48512723091;1305.2951406240618;1356.1930651262483;1374.924379159146;1429.9714005719932;1430.6780974280807;14359.066660667024;14418.167830630642;15930.283137258852;17536.38622648214;17815.10924453772;4129.256733787887;4616.169089563844;4643.770989924855;4645.46577143626;4665.2535594304745;13259.071864969359;13267.800982985264;13276.668083297802;13289.933550332187;13306.600801975956;5857.179994800731;5865.882682346414;5867.559933005042;5871.207387002785;5875.764969401176;5090.821821236239;5439.755211015514;5453.127499600045;5454.372747134051;5455.509004189642;3919.9492677747294;3974.900627484278;4006.659433948089;4041.413994970713;4049.2104039712563;960.05914204079;960.519740129935;978.3689520259393;978.7993461340408;980.2550061952967;1065.2010991756183;1066.9679909602723;1068.3322923173016;1078.6224821312635;1079.7786453776957;7037.19072306681;7197.388222001188;7214.458915581353;7332.523385980001;7370.8894366584545;1018.1651046142092;1040.1262939131016;1048.449563978902;1057.0592172966067;1061.1987949098382;4425.889352766207;4443.577860103285;4456.710313829591;4457;4458.955410445854;14563.126212427076;14773.409063637335;14933.25333733309;15029.774553381709;15231;14590.540945905537;14888.181150036635;14985.850141498446;15014.024088434217;15014.324355404033;8997.235235005099;9197.54012299369;9238.676646317346;9432.103950124865;9439.480828554495;7276.4542659299705;7466.141393739789;7492.1384040836;7579.317861392501;7804.492707973964;13153.473861045451;13268.734625307632;13290;13411.463541458232;13540.59378218655;13569.864301357255;13605.251711155981;13709;13766.174029558259;13795.655108622364;9349.719508414759;9363.578638961399;9369.203617692572;9408.576614052246;9480.099390557885;10549.419781912069;11098.50056747432;11184.328940263611;11189.21675482741;11204.66386008421;9742.269329800434;9763.365381250196;9769.951150244204;10255.84616230772;10472.842907356548;9514.524273786144;9658.565364558472;9852.605895764376;9937.254705896941;10043.497825108569;8229.95885020571;8350.749477515685;8352.206540378655;8370.414071015213;8373.58132093405;6225.533085018551;6325.1144839721555;6359.523035772429;6367.108604795425;6407.455366293917;6457.0637257596445;6494.6103233806125;6519.7392104315295;6557.1147895035465;6667.366600172976 12:19:36 INFO - PROCESS | 7440 | |4;prototype.html;378.66866491819655;389.07982621101087;389.3322951138798;403.9777812220328;433.23101494846645;5166;5210.062188806013;5261.605379596536;5291.841244762659;5298.814541491049;5111.846644600659;5121.3342265505435;5121.641485096045;5151.072806894757;5191.325127733398;4288.463942007249;4362.847300344489;4370.956290437102;4388.473383194021;4426.62373698236;4394.396045443422;4422.447194100738;4424.026714122904;4438.334249862514;4449.42157519522;4316.668998740676;4421.977890110545;4424.690271680984;4430.224710675635;4432.379466874624;4182.205380977604;4259.9574004260185;4347.282698354799;4404.427424434819;4426.955730442703;4077.1641813428178;4109.301418758818;4128.1330920506725;4133.751974881487;4152.294110001307;5105.438401775802;5114.008305141497;5115.44227886057;5122.743862806882;5130.589552835764;4914.336564563774;5070.188769796815;5083.237514372837;5103.617228707862;5124.385073791132;5352.2506849041165;5366.195070739421;5407.783688652488;5423.105187644034;5487.259220005328;4513.413256276696;4521.932171017437;4532.977335113336;4548.363229147922;4560.960139046372;5416.9729151353995;5668.971655141698;5685.232493613351;5685.5167310778215;5698.956423565133;4641.930371044437;4674.275487299474;4676.456769266134;4690.413698287714;4705.411823522059;4765.880852978703;4785.712857228577;4812.5668689817385;4817.927731084036;4824.324594556765;4892.535209155196;4975.855553222779;4990.4011518618;5072.416672082684;5073.949260507347;5358.196270559447;5364.310242273749;5371.865703357369;5382.4886635769535;5402.162664786964;1632.3225861267679;1640.3274657390414;1650.8926919750177;1653.7023335799672;1667.2997341116761;1265.0764941592688;1275.2603489975793;1283.4288741509938;1290.9160904541175;1306.6406738146895;5193.636445448858;5194.532492075731;5261.210818377198;5263.3947096083675;5306.575473962073;5555.833325000256;5587.485631465856;5603.411641777636;5604.747786349624;5618.578606604439;5076.593872490192;5150.40770311419;5168.508991645787;5182.212136812794;5216.739163041909;2890;2903.535434330497;2916.8395738234176;2931.809432386888;2933.5453004784295;5441.918371224435;5697.601167918206;5741.88516229673;5767.307923049261;5783.363829978699;5423.620346575702;5640.971795140998;5662.490375866192;5665.433456654384;5709.229254050733;5571.721413929287;5611.410801865745;5661.46216109469;5714.542836572981;5735.340435849764;5534.169874518854;5540.722963851872;5561.944380556142;5570.161846590697;5581.720913954205;5264.710440925785;5442.265294185234;5478.01395748769;5511.889762204813;5520.834374968757;5726.685032323262;5746.8506298739585;5749.425057494217;5771.192033115283;5777.191193233035;4626.005408837116;4658.440987081436;4686.664300674292;4690.390249267573;4692.114349674994;5444.754986025559;5561.582881283839;5574.219609254626;5579.804706835242;5604.8038318658655;5368.355797304349;5399.757010934596;5416.593755468277;5417.699752059556;5432.619716619957;5280.920786188133;5379.73101344939;5420.322459692538;5431.755570999236;5436.646617969819;5419.674819510842;5419.810306639249;5421.566274698093;5432.066683990601;5434.701091440009;5390.380106287827;5420.376656684532;5452.482014208644;5515.227868098389;5538.473844984881;5074.847754567369;5307.734613269398;5307.7611507483025;5334.9454414645925;5341.919871201857 12:19:36 INFO - PROCESS | 7440 | |5;yui.html;840.1682334488858;846.1158089796162;898.9775255618608;904.1967817732964;933.9633007361831;17295.82704172958;18424.355147569837;18870.62258754824;19275.903620481913;19472.513187170345;15974.281157347918;16111.919440402811;16116.75824862628;16325.918370408132;17039.57401064974;852.9616167272487;867.6269204242174;870.6256309786773;887.4054383563029;890.4078787606234;885.7475619448459;886.685226744506;887.8801361816137;888.7555922121404;888.9911100889005;931.16660588773;931.4271722890408;932.2681694869528;932.7668082979255;934.1265916368218;932.1657116880422;932.202966463674;932.5057719408691;932.8647346134807;932.9766755831158;678.2437582095977;679.5990365684221;679.7213142611555;680.1566058087985;680.210955291864;17803;17957.820421795746;18029.18868650914;18827.341043063563;19063.428097157106;16141.192940353052;16319.18404079803;16432.507024789276;16450.177491125396;16593.419230327003;13416.731665366735;13578.1853088814;13597.116187447784;13854.722905541934;13905.443782248698;4024.5975402459812;4039.4950631171105;4072.1041370898356;4137.731047481904;4178.206140833262;85360;85476.57261713651;86855.56572217161;89146.55426722825;89255;12181.878181218075;12223.93888030554;12477.188982716094;12527.43626536808;12668.873311266954;23554;23638.881805591205;23760;23904;24314.392140196636;8621.69824056155;8848.690295839744;8860.955695221612;8973.641054357882;9004.414713043761;4997.325361076281;5034.622403319766;5058.974705126524;5064.392272927273;5200.2199670049795;3773.4528493368307;3872.7676339419727;3896.5324161100284;3922.3332033554366;3928.2143571285856;613.5613036678707;619.0343064818896;619.1950464396267;635.6789821140387;635.9618422894641;623.1929651101834;633.3033662970787;644.4103645164622;656.8193746719595;659.591053546804;10384.682073765882;10708.30396024256;10752.838707419394;10844.186685998582;11059.451676765259;811.9837603247902;815.0830315894618;817.8037271055023;838.1702114906199;845.8815765792793;5290.682559046392;5335.519803217729;5370;5395.406505284415;5428.782848686008;63125.684371577845;78625;79312;79378;81406.18593813985;57800;58770;70941.64529177321;71184.2881571188;71668.64165679243;60945.695271524244;62560;62708.37291627025;63384.68307658432;63453;10300.690979270634;10307.433091180057;10330.276880618285;10367.429791361548;10529;40502.392464113655;41845;42323;42709.78645106879;43037.139257215305;53021.469785301655;53443.73278133584;55905.64566125883;58021.12968305563;58539.414605853395;14158.362873670503;14166.645833854236;14255.714885702435;14334.641633959234;14376.424943002163;15300.464483743015;15387.461438849587;16265.186740663155;16424.83575164233;17556.91221543935;15602.219889005277;15841.68316633684;15903.52289431319;16700.916495417445;16704.74942875882;14347.784783228051;15001.924990375415;15605.453809116627;15626.609334766721;16166.919165404097;15826.446074387803;15982;16038;16860.66278674444;16867.325306987583;9003.774905627411;9034.277257819227;9237;9270.860937086083;9569.186619137174;11376.43117844088;11753.353565553976;11947.76104477923;12014.219075760047;12056.939715301367 12:19:36 INFO - PROCESS | 7440 | __end_tp_report 12:19:36 INFO - PROCESS | 7440 | __start_cc_report 12:19:36 INFO - PROCESS | 7440 | _x_x_mozilla_cycle_collect,1448 12:19:36 INFO - PROCESS | 7440 | __end_cc_report 12:19:36 INFO - PROCESS | 7440 | __startTimestamp1495826376935__endTimestamp 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | ------- Summary: start ------- 12:19:36 INFO - PROCESS | 7440 | Number of tests: 6 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | [#0] dojo.html Cycles:165 Average:5743.44 Median:5983.79 stddev:638.19 (10.7%) stddev-sans-first:613.69 12:19:36 INFO - PROCESS | 7440 | Values: 3425.1 3449.7 3452.6 3469.1 3493.4 5738.7 5792.9 5803.6 5809.9 5815.6 5765.4 5821.4 5827.8 5828.1 5828.3 5202.5 5263.0 5293.6 5296.3 5330.3 5044.5 5148.7 5342.1 5351.4 5353.5 5242.4 5252.6 5280.1 5298.9 5300.4 5317.6 5323.9 5332.1 5332.4 5339.6 5073.3 5205.5 5257.2 5313.3 5314.4 5631.5 5659.8 5717.5 5734.8 5735.7 6186.5 6214.9 6219.0 6227.5 6228.9 6195.4 6262.6 6266.0 6280.0 6280.3 5630.6 5649.2 5745.7 5782.3 5795.9 6313.3 6325.2 6327.4 6328.1 6330.7 5983.8 6049.9 6054.5 6098.6 6102.4 6002.2 6030.4 6043.3 6111.3 6215.8 6217.6 6237.8 6239.2 6239.4 6239.5 6049.6 6068.0 6101.2 6105.8 6115.2 4702.3 4712.1 4747.0 4765.2 4793.1 4186.1 4270.3 4292.0 4292.1 4326.3 5781.9 5864.4 5868.6 5909.8 5914.1 6161.8 6188.9 6197.0 6199.4 6202.2 5766.5 5766.7 5777.7 5824.8 5828.9 5126.4 5137.1 5204.2 5206.0 5208.3 6436.6 6457.7 6462.8 6473.8 6474.7 6156.5 6175.4 6220.9 6229.7 6233.8 6119.1 6226.7 6296.1 6357.9 6450.9 6152.7 6154.5 6162.6 6162.7 6166.3 6251.3 6257.7 6267.1 6269.5 6276.7 5777.6 5878.3 5973.5 6059.9 6071.7 6079.1 6086.6 6103.1 6103.6 6120.1 6002.5 6055.0 6055.9 6075.9 6080.6 5822.3 5849.2 5894.7 5897.9 6029.7 5986.1 6005.9 6007.7 6015.2 6015.9 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | [#1] ext.html Cycles:180 Average:48569.97 Median:32610.09 stddev:82219.87 (252.1%) stddev-sans-first:82372.11 12:19:36 INFO - PROCESS | 7440 | Values: 747.8 811.3 817.4 877.1 895.2 31908.2 42957.4 43079.0 43082.4 43158.8 40958.6 41010.2 41149.8 46807.3 46918.3 7743.6 9310.7 9458.3 9459.4 9463.9 7761.3 10758.4 11059.7 11074.3 11075.7 11194.8 11212.7 11222.3 11222.9 11222.9 11164.9 11185.8 11186.0 11198.8 11200.1 10718.6 10766.2 10795.7 10807.9 10813.9 44299.0 44652.1 44699.3 44778.0 44789.3 4371.8 4416.6 4429.7 4531.8 4551.6 31196.1 31903.5 31940.5 33053.7 34751.1 3801.1 3870.9 3902.6 3954.9 3964.5 253084.0 253797.7 254162.0 254609.0 254814.0 57468.0 57647.7 57665.0 57770.0 57787.4 59092.0 59120.0 59126.4 59416.4 59569.1 17344.5 17434.8 17486.0 17536.4 17553.1 13592.6 13674.2 15525.7 15554.8 15560.1 14685.7 14790.4 14808.0 14980.7 15340.8 6123.0 6187.3 6279.6 6342.3 6357.6 1005.7 1022.1 1022.2 1022.5 1034.2 28988.4 29241.4 29403.0 29688.6 29908.0 1097.3 1101.7 1105.3 1133.3 1183.7 15533.1 16746.7 16777.7 17632.9 17657.7 355678.0 358293.0 358324.0 358517.0 359008.0 61010.7 61231.4 61242.1 61270.0 61274.4 1574.6 1589.3 1625.1 1633.4 1635.4 31570.0 32758.1 32919.7 33102.7 33310.8 43630.1 44028.0 44034.0 44063.6 44075.8 319289.0 321470.4 321717.0 322326.0 323950.0 25756.5 32858.0 33084.5 33237.2 38278.4 41731.6 41964.0 42121.4 42168.4 42277.6 32144.4 32604.5 32615.7 32630.5 32733.7 32053.4 32056.0 32360.2 32408.4 32864.0 36634.0 37220.3 37702.4 37736.0 37778.8 30601.7 30610.0 31099.0 31135.0 31438.1 24402.3 25039.7 25276.1 25287.0 25827.5 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | [#2] jquery.html Cycles:180 Average:36857.88 Median:22210.89 stddev:103990.31 (468.2%) stddev-sans-first:104246.11 12:19:36 INFO - PROCESS | 7440 | Values: 456.1 468.2 472.2 478.8 490.9 20887.4 21911.1 22024.1 22256.8 22391.2 20565.8 20831.0 22165.0 22846.4 23352.2 5232.7 6769.6 6979.0 7003.3 7016.5 6905.4 6946.2 7011.6 7014.6 7018.8 6729.4 6861.2 6885.8 6886.6 6896.4 6629.7 6775.3 6827.8 6859.7 6877.8 6664.2 6682.1 6693.5 6695.5 6698.1 24263.6 24844.4 24937.9 26474.7 26604.7 3606.8 3663.4 3699.8 3715.5 3769.7 15997.7 16149.4 16236.0 16529.3 16836.1 2275.6 2326.8 2381.3 2441.5 2604.3 116895.0 120413.0 120980.4 124596.4 125183.0 18281.5 18302.5 18390.2 18478.7 18709.3 32010.8 32018.7 32110.4 32297.7 32438.7 10445.7 10554.7 10558.6 10845.2 10922.3 8937.6 8966.1 8984.8 9162.8 9208.4 8822.5 9250.7 9289.9 9393.2 9484.1 3493.9 3554.4 3609.4 3784.0 3809.5 761.4 916.8 933.9 985.8 1066.6 19973.0 21046.5 21140.6 21146.5 21171.0 1075.7 1090.6 1098.4 1104.7 1108.8 11501.8 11721.5 11784.0 11823.1 11908.7 612332.0 616309.0 632628.0 641745.0 644757.0 72413.3 112271.0 114954.4 117412.4 121756.0 1223.7 1245.4 1275.8 1278.0 1289.2 18838.3 19054.7 19063.9 19109.4 19214.4 47067.9 50026.7 51716.0 51871.0 52482.0 65846.3 66420.3 66595.7 66676.0 66879.0 11944.3 12090.0 12172.8 12436.3 12590.2 13951.1 14039.2 14338.6 14379.8 14632.9 11556.0 11622.9 11712.2 11757.8 11973.4 10790.5 10992.2 11015.7 11539.7 11767.8 12315.6 12402.4 12510.1 12573.4 12823.9 12253.9 12296.9 12410.2 12451.3 12863.8 11248.0 11444.7 12355.0 12505.4 12849.3 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | [#3] mootools.html Cycles:180 Average:7148.82 Median:2468.14 stddev:4581.59 (185.6%) stddev-sans-first:4574.84 12:19:36 INFO - PROCESS | 7440 | Values: 1509.3 1535.6 1538.1 1538.1 1567.1 10848.9 11434.0 11446.6 11479.3 11479.9 10924.5 11385.1 11651.3 11660.5 11690.3 2463.0 2503.6 2504.9 2505.8 2505.9 2396.3 2410.0 2473.2 2475.5 2475.7 2401.4 2403.5 2414.8 2430.1 2433.6 2382.8 2418.1 2421.1 2423.8 2453.0 2315.9 2331.4 2339.9 2345.6 2346.8 11341.7 11520.9 11527.1 11542.0 11552.1 5618.6 5769.3 5771.9 5780.3 5782.3 3322.1 3370.7 3373.6 3379.4 3432.5 1305.3 1356.2 1374.9 1430.0 1430.7 14359.1 14418.2 15930.3 17536.4 17815.1 4129.3 4616.2 4643.8 4645.5 4665.3 13259.1 13267.8 13276.7 13289.9 13306.6 5857.2 5865.9 5867.6 5871.2 5875.8 5090.8 5439.8 5453.1 5454.4 5455.5 3919.9 3974.9 4006.7 4041.4 4049.2 960.1 960.5 978.4 978.8 980.3 1065.2 1067.0 1068.3 1078.6 1079.8 7037.2 7197.4 7214.5 7332.5 7370.9 1018.2 1040.1 1048.4 1057.1 1061.2 4425.9 4443.6 4456.7 4457.0 4459.0 14563.1 14773.4 14933.3 15029.8 15231.0 14590.5 14888.2 14985.9 15014.0 15014.3 8997.2 9197.5 9238.7 9432.1 9439.5 7276.5 7466.1 7492.1 7579.3 7804.5 13153.5 13268.7 13290.0 13411.5 13540.6 13569.9 13605.3 13709.0 13766.2 13795.7 9349.7 9363.6 9369.2 9408.6 9480.1 10549.4 11098.5 11184.3 11189.2 11204.7 9742.3 9763.4 9770.0 10255.8 10472.8 9514.5 9658.6 9852.6 9937.3 10043.5 8230.0 8350.7 8352.2 8370.4 8373.6 6225.5 6325.1 6359.5 6367.1 6407.5 6457.1 6494.6 6519.7 6557.1 6667.4 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | [#4] prototype.html Cycles:180 Average:4743.96 Median:5192.48 stddev:1248.29 (24.0%) stddev-sans-first:1208.03 12:19:36 INFO - PROCESS | 7440 | Values: 378.7 389.1 389.3 404.0 433.2 5166.0 5210.1 5261.6 5291.8 5298.8 5111.8 5121.3 5121.6 5151.1 5191.3 4288.5 4362.8 4371.0 4388.5 4426.6 4394.4 4422.4 4424.0 4438.3 4449.4 4316.7 4422.0 4424.7 4430.2 4432.4 4182.2 4260.0 4347.3 4404.4 4427.0 4077.2 4109.3 4128.1 4133.8 4152.3 5105.4 5114.0 5115.4 5122.7 5130.6 4914.3 5070.2 5083.2 5103.6 5124.4 5352.3 5366.2 5407.8 5423.1 5487.3 4513.4 4521.9 4533.0 4548.4 4561.0 5417.0 5669.0 5685.2 5685.5 5699.0 4641.9 4674.3 4676.5 4690.4 4705.4 4765.9 4785.7 4812.6 4817.9 4824.3 4892.5 4975.9 4990.4 5072.4 5073.9 5358.2 5364.3 5371.9 5382.5 5402.2 1632.3 1640.3 1650.9 1653.7 1667.3 1265.1 1275.3 1283.4 1290.9 1306.6 5193.6 5194.5 5261.2 5263.4 5306.6 5555.8 5587.5 5603.4 5604.7 5618.6 5076.6 5150.4 5168.5 5182.2 5216.7 2890.0 2903.5 2916.8 2931.8 2933.5 5441.9 5697.6 5741.9 5767.3 5783.4 5423.6 5641.0 5662.5 5665.4 5709.2 5571.7 5611.4 5661.5 5714.5 5735.3 5534.2 5540.7 5561.9 5570.2 5581.7 5264.7 5442.3 5478.0 5511.9 5520.8 5726.7 5746.9 5749.4 5771.2 5777.2 4626.0 4658.4 4686.7 4690.4 4692.1 5444.8 5561.6 5574.2 5579.8 5604.8 5368.4 5399.8 5416.6 5417.7 5432.6 5280.9 5379.7 5420.3 5431.8 5436.6 5419.7 5419.8 5421.6 5432.1 5434.7 5390.4 5420.4 5452.5 5515.2 5538.5 5074.8 5307.7 5307.8 5334.9 5341.9 12:19:36 INFO - PROCESS | 7440 | 12:19:36 INFO - PROCESS | 7440 | [#5] yui.html Cycles:180 Average:18465.21 Median:5015.97 stddev:22674.88 (452.1%) stddev-sans-first:22699.87 12:19:36 INFO - PROCESS | 7440 | Values: 840.2 846.1 899.0 904.2 934.0 17295.8 18424.4 18870.6 19275.9 19472.5 15974.3 16111.9 16116.8 16325.9 17039.6 853.0 867.6 870.6 887.4 890.4 885.7 886.7 887.9 888.8 889.0 931.2 931.4 932.3 932.8 934.1 932.2 932.2 932.5 932.9 933.0 678.2 679.6 679.7 680.2 680.2 17803.0 17957.8 18029.2 18827.3 19063.4 16141.2 16319.2 16432.5 16450.2 16593.4 13416.7 13578.2 13597.1 13854.7 13905.4 4024.6 4039.5 4072.1 4137.7 4178.2 85360.0 85476.6 86855.6 89146.6 89255.0 12181.9 12223.9 12477.2 12527.4 12668.9 23554.0 23638.9 23760.0 23904.0 24314.4 8621.7 8848.7 8861.0 8973.6 9004.4 4997.3 5034.6 5059.0 5064.4 5200.2 3773.5 3872.8 3896.5 3922.3 3928.2 613.6 619.0 619.2 635.7 636.0 623.2 633.3 644.4 656.8 659.6 10384.7 10708.3 10752.8 10844.2 11059.5 812.0 815.1 817.8 838.2 845.9 5290.7 5335.5 5370.0 5395.4 5428.8 63125.7 78625.0 79312.0 79378.0 81406.2 57800.0 58770.0 70941.6 71184.3 71668.6 60945.7 62560.0 62708.4 63384.7 63453.0 10300.7 10307.4 10330.3 10367.4 10529.0 40502.4 41845.0 42323.0 42709.8 43037.1 53021.5 53443.7 55905.6 58021.1 58539.4 14158.4 14166.6 14255.7 14334.6 14376.4 15300.5 15387.5 16265.2 16424.8 17556.9 15602.2 15841.7 15903.5 16700.9 16704.7 14347.8 15001.9 15605.5 15626.6 16166.9 15826.4 15982.0 16038.0 16860.7 16867.3 9003.8 9034.3 9237.0 9270.9 9569.2 11376.4 11753.4 11947.8 12014.2 12056.9 12:19:36 INFO - PROCESS | 7440 | -------- Summary: end -------- 12:19:36 INFO - PROCESS | 7440 | 12:19:37 INFO - TEST-INFO | 7440: exit 0 12:19:37 INFO - TEST-OK | dromaeo_css | took 1116405ms 12:19:37 INFO - TEST-START | kraken 12:19:37 INFO - operating with platform_type : linux_ 12:19:37 INFO - Initialising browser for kraken test... 12:19:37 INFO - TEST-INFO | started process 7754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsK4weO/profile http://localhost:43133/getInfo.html) 12:19:41 INFO - PROCESS | 7754 | __metrics Screen width/height:1600/1200 12:19:41 INFO - PROCESS | 7754 | colorDepth:24 12:19:41 INFO - PROCESS | 7754 | Browser inner width/height: 1024/697 12:19:41 INFO - PROCESS | 7754 | __metrics 12:19:42 INFO - PROCESS | 7754 | 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:19:42 INFO - PROCESS | 7754 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:19:43 INFO - TEST-INFO | 7754: exit 0 12:19:43 INFO - Browser initialized. 12:19:43 INFO - Running cycle 1/1 for kraken test... 12:19:43 INFO - TEST-INFO | started process 7848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsK4weO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:19:55 INFO - PROCESS | 7848 | RSS: Main: 141692928 12:19:55 INFO - PROCESS | 7848 | 12:19:57 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43133/tests/kraken/driver.html?testName=audio-beat-detection) 12:19:58 INFO - PROCESS | 7848 | RSS: Main: 183898112 12:19:58 INFO - PROCESS | 7848 | 12:20:01 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43133/tests/kraken/driver.html?testName=audio-dft) 12:20:01 INFO - PROCESS | 7848 | RSS: Main: 173707264 12:20:01 INFO - PROCESS | 7848 | 12:20:04 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43133/tests/kraken/driver.html?testName=audio-fft) 12:20:05 INFO - PROCESS | 7848 | RSS: Main: 265670656 12:20:05 INFO - PROCESS | 7848 | 12:20:07 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43133/tests/kraken/driver.html?testName=audio-oscillator) 12:20:07 INFO - PROCESS | 7848 | RSS: Main: 171253760 12:20:07 INFO - PROCESS | 7848 | 12:20:09 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43133/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:20:10 INFO - PROCESS | 7848 | RSS: Main: 174256128 12:20:10 INFO - PROCESS | 7848 | 12:20:13 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43133/tests/kraken/driver.html?testName=imaging-darkroom) 12:20:13 INFO - PROCESS | 7848 | RSS: Main: 209629184 12:20:13 INFO - PROCESS | 7848 | 12:20:17 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43133/tests/kraken/driver.html?testName=imaging-desaturate) 12:20:18 INFO - PROCESS | 7848 | RSS: Main: 210911232 12:20:18 INFO - PROCESS | 7848 | 12:20:20 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43133/tests/kraken/driver.html?testName=json-parse-financial) 12:20:21 INFO - PROCESS | 7848 | RSS: Main: 211705856 12:20:21 INFO - PROCESS | 7848 | 12:20:22 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43133/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:20:23 INFO - PROCESS | 7848 | RSS: Main: 173588480 12:20:23 INFO - PROCESS | 7848 | 12:20:25 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:20:25 INFO - PROCESS | 7848 | RSS: Main: 180445184 12:20:25 INFO - PROCESS | 7848 | 12:20:27 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:20:28 INFO - PROCESS | 7848 | RSS: Main: 190554112 12:20:28 INFO - PROCESS | 7848 | 12:20:30 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:20:31 INFO - PROCESS | 7848 | RSS: Main: 200171520 12:20:31 INFO - PROCESS | 7848 | 12:20:34 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:20:34 INFO - PROCESS | 7848 | RSS: Main: 196063232 12:20:34 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | Cycle 1(1): loaded http://localhost:43133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43133/tests/kraken/driver.html?testName=ai-astar) 12:20:36 INFO - PROCESS | 7848 | RSS: Main: 246693888 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | __start_tp_report 12:20:36 INFO - PROCESS | 7848 | _x_x_mozilla_page_load 12:20:36 INFO - PROCESS | 7848 | _x_x_mozilla_page_load_details 12:20:36 INFO - PROCESS | 7848 | |i|pagename|runs| 12:20:36 INFO - PROCESS | 7848 | |0;ai-astar;103;113;113;113;112;111;112;112;111;112 12:20:36 INFO - PROCESS | 7848 | |1;audio-beat-detection;137;115;114;161;116;115;115;116;131;116 12:20:36 INFO - PROCESS | 7848 | |2;audio-dft;160;160;159;163;161;162;161;159;159;162 12:20:36 INFO - PROCESS | 7848 | |3;audio-fft;75;77;75;117;77;77;75;76;90;75 12:20:36 INFO - PROCESS | 7848 | |4;audio-oscillator;82;81;105;82;83;82;95;82;81;111 12:20:36 INFO - PROCESS | 7848 | |5;imaging-gaussian-blur;113;113;113;114;113;113;112;113;113;113 12:20:36 INFO - PROCESS | 7848 | |6;imaging-darkroom;146;146;146;146;147;146;147;146;146;145 12:20:36 INFO - PROCESS | 7848 | |7;imaging-desaturate;94;95;94;94;94;94;93;94;93;93 12:20:36 INFO - PROCESS | 7848 | |8;json-parse-financial;61;54;55;58;55;56;55;57;55;56 12:20:36 INFO - PROCESS | 7848 | |9;json-stringify-tinderbox;52;53;52;53;52;53;53;52;53;52 12:20:36 INFO - PROCESS | 7848 | |10;stanford-crypto-aes;75;84;78;75;84;83;75;78;75;78 12:20:36 INFO - PROCESS | 7848 | |11;stanford-crypto-ccm;122;120;121;135;120;126;118;119;157;128 12:20:36 INFO - PROCESS | 7848 | |12;stanford-crypto-pbkdf2;162;157;158;159;159;160;156;160;156;163 12:20:36 INFO - PROCESS | 7848 | |13;stanford-crypto-sha256-iterative;58;60;59;63;59;58;59;58;58;57 12:20:36 INFO - PROCESS | 7848 | __end_tp_report 12:20:36 INFO - PROCESS | 7848 | __start_cc_report 12:20:36 INFO - PROCESS | 7848 | _x_x_mozilla_cycle_collect,1692 12:20:36 INFO - PROCESS | 7848 | __end_cc_report 12:20:36 INFO - PROCESS | 7848 | __startTimestamp1495826436779__endTimestamp 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | ------- Summary: start ------- 12:20:36 INFO - PROCESS | 7848 | Number of tests: 14 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.97 (2.7%) stddev-sans-first:0.78 12:20:36 INFO - PROCESS | 7848 | Values: 103.0 113.0 113.0 113.0 112.0 111.0 112.0 112.0 111.0 112.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:116.00 stddev:15.35 (13.2%) stddev-sans-first:15.50 12:20:36 INFO - PROCESS | 7848 | Values: 137.0 115.0 114.0 161.0 116.0 115.0 115.0 116.0 131.0 116.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.43 (0.9%) stddev-sans-first:1.50 12:20:36 INFO - PROCESS | 7848 | Values: 160.0 160.0 159.0 163.0 161.0 162.0 161.0 159.0 159.0 162.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.50 stddev:13.30 (17.4%) stddev-sans-first:13.91 12:20:36 INFO - PROCESS | 7848 | Values: 75.0 77.0 75.0 117.0 77.0 77.0 75.0 76.0 90.0 75.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:82.00 stddev:11.22 (13.7%) stddev-sans-first:11.66 12:20:36 INFO - PROCESS | 7848 | Values: 82.0 81.0 105.0 82.0 83.0 82.0 95.0 82.0 81.0 111.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 12:20:36 INFO - PROCESS | 7848 | Values: 113.0 113.0 113.0 114.0 113.0 113.0 112.0 113.0 113.0 113.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 12:20:36 INFO - PROCESS | 7848 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 145.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 12:20:36 INFO - PROCESS | 7848 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 93.0 94.0 93.0 93.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:2.04 (3.6%) stddev-sans-first:1.22 12:20:36 INFO - PROCESS | 7848 | Values: 61.0 54.0 55.0 58.0 55.0 56.0 55.0 57.0 55.0 56.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#9] json-stringify-tinderbox Cycles:10 Average:52.50 Median:53.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 12:20:36 INFO - PROCESS | 7848 | Values: 52.0 53.0 52.0 53.0 52.0 53.0 53.0 52.0 53.0 52.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:78.00 stddev:3.81 (4.9%) stddev-sans-first:3.82 12:20:36 INFO - PROCESS | 7848 | Values: 75.0 84.0 78.0 75.0 84.0 83.0 75.0 78.0 75.0 78.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:124.00 stddev:11.87 (9.6%) stddev-sans-first:12.47 12:20:36 INFO - PROCESS | 7848 | Values: 122.0 120.0 121.0 135.0 120.0 126.0 118.0 119.0 157.0 128.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.00 Median:159.50 stddev:2.36 (1.5%) stddev-sans-first:2.24 12:20:36 INFO - PROCESS | 7848 | Values: 162.0 157.0 158.0 159.0 159.0 160.0 156.0 160.0 156.0 163.0 12:20:36 INFO - PROCESS | 7848 | 12:20:36 INFO - PROCESS | 7848 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.90 Median:59.00 stddev:1.66 (2.8%) stddev-sans-first:1.73 12:20:36 INFO - PROCESS | 7848 | Values: 58.0 60.0 59.0 63.0 59.0 58.0 59.0 58.0 58.0 57.0 12:20:36 INFO - PROCESS | 7848 | -------- Summary: end -------- 12:20:36 INFO - PROCESS | 7848 | 12:20:37 INFO - TEST-INFO | 7848: exit 0 12:20:37 INFO - TEST-OK | kraken | took 60094ms 12:20:37 INFO - SUITE-END | took 1176s 12:20:37 INFO - Completed test suite (00:19:36) 12:20:37 INFO - javascript benchmark 12:20:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7877.17363788362, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3425.1094715374, 3449.7412694047953, 3452.6029506606747, 3469.0980345110265, 3493.4410494320914, 5738.7417566209515, 5792.913106303404, 5803.593748437611, 5809.912851307228, 5815.5638327125425, 5765.423457654243, 5821.417858214187, 5827.796027139051, 5828.0675091985295, 5828.300603927534, 5202.4537423570455, 5263.0, 5293.6294459387855, 5296.285001524802, 5330.307060082184, 5044.470330615288, 5148.691078535281, 5342.091844386464, 5351.384590772072, 5353.491418315254, 5242.423333433319, 5252.579793616502, 5280.128778751501, 5298.92051619226, 5300.363956325246, 5317.574594032468, 5323.893522129555, 5332.14685650296, 5332.386775520826, 5339.599530035263, 5073.340465739467, 5205.531502164823, 5257.1588545832865, 5313.3092697949405, 5314.388845282765, 5631.465010823965, 5659.801906933238, 5717.456841600041, 5734.827955161351, 5735.684537350444, 6186.505079593667, 6214.875702485925, 6219.0, 6227.532935029845, 6228.87542249157, 6195.370953080206, 6262.561620686553, 6266.0, 6280.0, 6280.340564240734, 5630.634008789416, 5649.152627105926, 5745.7127143643065, 5782.277215348082, 5795.9420405796245, 6313.337099604567, 6325.240971083499, 6327.367263273728, 6328.145700330489, 6330.651814150174, 5983.790567330209, 6049.909251361233, 6054.485368743618, 6098.634081955097, 6102.420270074336, 6002.219711437485, 6030.366811484819, 6043.335233124353, 6111.297200821873, 6215.782447614311, 6217.564770466024, 6237.844053898689, 6239.220097487814, 6239.438450539473, 6239.532035097386, 6049.576529642883, 6068.0, 6101.237345331833, 6105.8168254952425, 6115.174451448997, 4702.3416721659, 4712.057588482317, 4746.95253047472, 4765.1660959331975, 4793.065352256276, 4186.079062606222, 4270.316749320094, 4291.957080429156, 4292.055747735493, 4326.307790753464, 5781.8554536136935, 5864.354920958691, 5868.618539794899, 5909.8227053188475, 5914.142449344907, 6161.771459377412, 6188.938110618927, 6197.0, 6199.442050215503, 6202.193714817045, 5766.452187042224, 5766.711664416763, 5777.740001699934, 5824.767009319665, 5828.94171058284, 5126.436092029874, 5137.101007323703, 5204.193350030751, 5206.0368831766245, 5208.322918020633, 6436.645984470899, 6457.7094030767785, 6462.838429039218, 6473.773417930444, 6474.6762661866715, 6156.476699480594, 6175.3515880831865, 6220.875582488235, 6229.657368844757, 6233.812985610439, 6119.136988619874, 6226.688665566794, 6296.0555916612875, 6357.936420635734, 6450.870982580416, 6152.692365381802, 6154.507639388748, 6162.568620196544, 6162.722677479434, 6166.260048794174, 6251.312355640966, 6257.655828929453, 6267.059941008885, 6269.529785266214, 6276.6547839869245, 5777.62445441045, 5878.3239927407185, 5973.522118230444, 6059.878802423838, 6071.666058366655, 6079.148919151234, 6086.6348019119, 6103.05402662588, 6103.572749907641, 6120.143179954721, 6002.549808764273, 6055.0, 6055.909161362495, 6075.908861367171, 6080.604760690541, 5822.3304320003745, 5849.210356601804, 5894.734736936763, 5897.852553686193, 6029.668368239789, 5986.07215881539, 6005.879882402415, 6007.669578173242, 6015.157877897011, 6015.939840601713], "value": 5702.012752361358, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.7794050755031, 811.3265989228938, 817.3747083481136, 877.1184959116088, 895.1987970766161, 31908.20229494264, 42957.35563966539, 43079.0, 43082.35376469355, 43158.78420607896, 40958.59041409585, 41010.17979640405, 41149.7942510287, 46807.29789053167, 46918.296225556645, 7743.6128193590375, 9310.67412640556, 9458.33791634586, 9459.432434053944, 9463.905360946406, 7761.3402860756905, 10758.354498730063, 11059.72350691231, 11074.335539867634, 11075.72310692231, 11194.832077518844, 11212.719682007973, 11222.326660400362, 11222.887771122267, 11222.943885280603, 11164.888351116506, 11185.776284474307, 11186.049185819214, 11198.832017519744, 11200.103991680646, 10718.624848130277, 10766.24636275461, 10795.730106747395, 10807.945960270226, 10813.891861081367, 44299.0, 44652.10695786098, 44699.329510057374, 44778.0, 44789.328160077624, 4371.781410929472, 4416.624586910117, 4429.734215947054, 4531.796069176895, 4551.635869130461, 31196.064118076494, 31903.52144717831, 31940.520892186636, 33053.6694633053, 34751.131221719406, 3801.1067399161175, 3870.9032274193087, 3902.609739026103, 3954.8813535593977, 3964.5044369453817, 253084.0, 253797.7310113456, 254162.0, 254609.0, 254814.0, 57468.0, 57647.711761441766, 57665.0, 57770.0, 57787.422125778205, 59092.0, 59120.0, 59126.40873591295, 59416.40583594195, 59569.106463403084, 17344.47966561005, 17434.825651743322, 17486.038267895132, 17536.386226482013, 17553.122343882755, 13592.592222233348, 13674.247916364695, 15525.689486210213, 15554.844451555566, 15560.144192069545, 14685.706285874223, 14790.408383664748, 14808.0, 14980.700385992437, 15340.769888451681, 6122.969385153135, 6187.257529096447, 6279.623222606659, 6342.270638876496, 6357.554971151975, 1005.7284533176002, 1022.1005515146624, 1022.1567207054061, 1022.4938655365493, 1034.1623285139062, 28988.42023159525, 29241.415171696448, 29403.0, 29688.554671679944, 29908.0, 1097.3361116524547, 1101.6694991502518, 1105.2760441910561, 1133.280366966982, 1183.7099910521977, 15533.145676987875, 16746.665066698843, 16777.664446711242, 17632.911835441, 17657.735133973, 355678.0, 358293.0, 358324.0, 358517.0, 359008.0, 61010.694946524985, 61231.38768612346, 61242.0813687795, 61270.0, 61274.38725612776, 1574.5984773882587, 1589.30865073691, 1625.1224338856885, 1633.4119716901905, 1635.3703824027598, 31570.0, 32758.07844566389, 32919.67080329262, 33102.66897331092, 33310.83344583359, 43630.12739745251, 44028.0, 44034.0, 44063.55936440723, 44075.77962110169, 319289.0, 321470.39264803525, 321717.0, 322326.0, 323950.0, 25756.522897965817, 32858.0, 33084.503732444515, 33237.16907077342, 38278.42582361322, 41731.58268417398, 41964.0, 42121.36817947795, 42168.36747448852, 42277.57722422737, 32144.357112857146, 32604.510932336507, 32615.673843261262, 32630.51054234141, 32733.67266327306, 32053.35893282168, 32056.0, 32360.190995225308, 32408.35183296368, 32864.0, 36634.0, 37220.25559488849, 37702.43446348252, 37736.0, 37778.811105944296, 30601.693983060773, 30610.0, 31099.0, 31135.0, 31438.056858294287, 24402.29183957106, 25039.749602503744, 25276.115335963153, 25287.0, 25827.483450331263], "value": 19688.27821402802, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.1287940034535, 468.173673466332, 472.2420515073307, 478.760619690155, 490.94354149272834, 20887.373378798642, 21911.12355505778, 22024.119035238593, 22256.777432225692, 22391.216307429244, 20565.794342056575, 20831.0, 22165.0, 22846.428839279026, 23352.18267360643, 5232.73836308183, 6769.5599786014, 6979.0, 7003.3346832050875, 7016.473764467685, 6905.447564194852, 6946.2011868635, 7011.579305241702, 7014.579125252476, 7018.82452938678, 6729.427998620124, 6861.1766588009505, 6885.758998435056, 6886.552374095692, 6896.448284137257, 6629.701663425158, 6775.254721980578, 6827.829304267433, 6859.691313890862, 6877.793666189998, 6664.233613134503, 6682.131322928037, 6693.46452283821, 6695.531312808105, 6698.062271282024, 24263.636045459156, 24844.3788905277, 24937.875310623513, 26474.73525264742, 26604.733952660612, 3606.783592984429, 3663.377225871608, 3699.7595156314756, 3715.492042871051, 3769.736118471708, 15997.680046399008, 16149.434769783118, 16235.993824126666, 16529.33882644693, 16836.07401592912, 2275.5676421479866, 2326.7789559991775, 2381.321323422816, 2441.4628781668002, 2604.296839853229, 116895.0, 120413.0, 120980.39509802483, 124596.37701811433, 125183.0, 18281.542961425806, 18302.450926472226, 18390.172442239862, 18478.67863970557, 18709.251629934923, 32010.839945800122, 32018.67981320157, 32110.357792844014, 32297.677023229466, 32438.67561324404, 10445.738856528496, 10554.683359499228, 10558.630447934285, 10845.186611004206, 10922.29005114665, 8937.553122343857, 8966.058563850833, 8984.775380615407, 9162.770930726652, 9208.447493150432, 8822.470651760916, 9250.72247832566, 9289.90710092904, 9393.154616084585, 9484.099010593982, 3493.9301213975577, 3554.431290993429, 3609.3864043112735, 3784.0, 3809.542854857435, 761.4479502360745, 916.8074704312169, 933.8785957825439, 985.7979114281555, 1066.5573786878358, 19973.0, 21046.47383815417, 21140.577188456453, 21146.471338216666, 21171.0, 1075.709558419238, 1090.596479302663, 1098.3794156301665, 1104.6741211342674, 1108.833674948748, 11501.827472587918, 11721.531138754524, 11784.0, 11823.113266504875, 11908.702282443008, 612332.0, 616309.0, 632628.0, 641745.0, 644757.0, 72413.27586724065, 112271.0, 114954.42522787332, 117412.41293793647, 121756.0, 1223.6512593910693, 1245.3897590180745, 1275.834141561591, 1277.9680507987375, 1289.2071376103816, 18838.3406580769, 19054.7141792876, 19063.90468047651, 19109.426717198505, 19214.423567293004, 47067.94058386194, 50026.74933126556, 51716.0, 51871.0, 52482.0, 65846.34153658402, 66420.33579664335, 66595.66702166652, 66676.0, 66879.0, 11944.343061131722, 12090.0, 12172.817407738714, 12436.253824770543, 12590.181638193488, 13951.093178943336, 14039.157650541, 14338.56984290473, 14379.836435984067, 14632.853671463148, 11556.0, 11622.883771162518, 11712.180147389601, 11757.76484470323, 11973.401329933642, 10790.514426850654, 10992.186289262183, 11015.724606884893, 11539.653810385702, 11767.823482647938, 12315.630531084082, 12402.379881006094, 12510.061745368952, 12573.371331433575, 12823.93588032054, 12253.938730306649, 12296.938515307365, 12410.193337433038, 12451.315177665316, 12863.807042894177, 11248.0, 11444.713882153013, 12355.011599071873, 12505.43725532335, 12849.293288868828], "value": 11533.075404672238, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1509.2604623734367, 1535.5546891401493, 1538.115583539465, 1538.12326973625, 1567.051933580184, 10848.945755271223, 11434.0, 11446.599369022086, 11479.253848499862, 11479.942600286988, 10924.453777311122, 11385.089192864572, 11651.300921944689, 11660.47527861246, 11690.29858208508, 2463.039414628292, 2503.561876671575, 2504.887280072392, 2505.837120587165, 2505.887235074417, 2396.269137912936, 2410.0480310277517, 2473.2332976777166, 2475.5420247254224, 2475.653408522808, 2401.4236583220077, 2403.531311394279, 2414.782669559739, 2430.0765709030634, 2433.6227884677814, 2382.845115067515, 2418.0811291709215, 2421.0920904660666, 2423.8182136339756, 2453.03105273416, 2315.872627005514, 2331.4287999440076, 2339.9181028663916, 2345.5543446745232, 2346.8122550196113, 11341.659750207507, 11520.884791152064, 11527.077833773363, 11542.018928391097, 11552.133589980785, 5618.606697531174, 5769.278840144982, 5771.942280577183, 5780.3063632363965, 5782.277215348082, 3322.103032181322, 3370.747193960463, 3373.578302712161, 3379.374815659111, 3432.48512723091, 1305.2951406240618, 1356.1930651262483, 1374.924379159146, 1429.9714005719932, 1430.6780974280807, 14359.066660667024, 14418.167830630642, 15930.283137258852, 17536.38622648214, 17815.10924453772, 4129.256733787887, 4616.169089563844, 4643.770989924855, 4645.46577143626, 4665.2535594304745, 13259.071864969359, 13267.800982985264, 13276.668083297802, 13289.933550332187, 13306.600801975956, 5857.179994800731, 5865.882682346414, 5867.559933005042, 5871.207387002785, 5875.764969401176, 5090.821821236239, 5439.755211015514, 5453.127499600045, 5454.372747134051, 5455.509004189642, 3919.9492677747294, 3974.900627484278, 4006.659433948089, 4041.413994970713, 4049.2104039712563, 960.05914204079, 960.519740129935, 978.3689520259393, 978.7993461340408, 980.2550061952967, 1065.2010991756183, 1066.9679909602723, 1068.3322923173016, 1078.6224821312635, 1079.7786453776957, 7037.19072306681, 7197.388222001188, 7214.458915581353, 7332.523385980001, 7370.8894366584545, 1018.1651046142092, 1040.1262939131016, 1048.449563978902, 1057.0592172966067, 1061.1987949098382, 4425.889352766207, 4443.577860103285, 4456.710313829591, 4457.0, 4458.955410445854, 14563.126212427076, 14773.409063637335, 14933.25333733309, 15029.774553381709, 15231.0, 14590.540945905537, 14888.181150036635, 14985.850141498446, 15014.024088434217, 15014.324355404033, 8997.235235005099, 9197.54012299369, 9238.676646317346, 9432.103950124865, 9439.480828554495, 7276.4542659299705, 7466.141393739789, 7492.1384040836, 7579.317861392501, 7804.492707973964, 13153.473861045451, 13268.734625307632, 13290.0, 13411.463541458232, 13540.59378218655, 13569.864301357255, 13605.251711155981, 13709.0, 13766.174029558259, 13795.655108622364, 9349.719508414759, 9363.578638961399, 9369.203617692572, 9408.576614052246, 9480.099390557885, 10549.419781912069, 11098.50056747432, 11184.328940263611, 11189.21675482741, 11204.66386008421, 9742.269329800434, 9763.365381250196, 9769.951150244204, 10255.84616230772, 10472.842907356548, 9514.524273786144, 9658.565364558472, 9852.605895764376, 9937.254705896941, 10043.497825108569, 8229.95885020571, 8350.749477515685, 8352.206540378655, 8370.414071015213, 8373.58132093405, 6225.533085018551, 6325.1144839721555, 6359.523035772429, 6367.108604795425, 6407.455366293917, 6457.0637257596445, 6494.6103233806125, 6519.7392104315295, 6557.1147895035465, 6667.366600172976], "value": 5390.12115991086, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.66866491819655, 389.07982621101087, 389.3322951138798, 403.9777812220328, 433.23101494846645, 5166.0, 5210.062188806013, 5261.605379596536, 5291.841244762659, 5298.814541491049, 5111.846644600659, 5121.3342265505435, 5121.641485096045, 5151.072806894757, 5191.325127733398, 4288.463942007249, 4362.847300344489, 4370.956290437102, 4388.473383194021, 4426.62373698236, 4394.396045443422, 4422.447194100738, 4424.026714122904, 4438.334249862514, 4449.42157519522, 4316.668998740676, 4421.977890110545, 4424.690271680984, 4430.224710675635, 4432.379466874624, 4182.205380977604, 4259.9574004260185, 4347.282698354799, 4404.427424434819, 4426.955730442703, 4077.1641813428178, 4109.301418758818, 4128.1330920506725, 4133.751974881487, 4152.294110001307, 5105.438401775802, 5114.008305141497, 5115.44227886057, 5122.743862806882, 5130.589552835764, 4914.336564563774, 5070.188769796815, 5083.237514372837, 5103.617228707862, 5124.385073791132, 5352.2506849041165, 5366.195070739421, 5407.783688652488, 5423.105187644034, 5487.259220005328, 4513.413256276696, 4521.932171017437, 4532.977335113336, 4548.363229147922, 4560.960139046372, 5416.9729151353995, 5668.971655141698, 5685.232493613351, 5685.5167310778215, 5698.956423565133, 4641.930371044437, 4674.275487299474, 4676.456769266134, 4690.413698287714, 4705.411823522059, 4765.880852978703, 4785.712857228577, 4812.5668689817385, 4817.927731084036, 4824.324594556765, 4892.535209155196, 4975.855553222779, 4990.4011518618, 5072.416672082684, 5073.949260507347, 5358.196270559447, 5364.310242273749, 5371.865703357369, 5382.4886635769535, 5402.162664786964, 1632.3225861267679, 1640.3274657390414, 1650.8926919750177, 1653.7023335799672, 1667.2997341116761, 1265.0764941592688, 1275.2603489975793, 1283.4288741509938, 1290.9160904541175, 1306.6406738146895, 5193.636445448858, 5194.532492075731, 5261.210818377198, 5263.3947096083675, 5306.575473962073, 5555.833325000256, 5587.485631465856, 5603.411641777636, 5604.747786349624, 5618.578606604439, 5076.593872490192, 5150.40770311419, 5168.508991645787, 5182.212136812794, 5216.739163041909, 2890.0, 2903.535434330497, 2916.8395738234176, 2931.809432386888, 2933.5453004784295, 5441.918371224435, 5697.601167918206, 5741.88516229673, 5767.307923049261, 5783.363829978699, 5423.620346575702, 5640.971795140998, 5662.490375866192, 5665.433456654384, 5709.229254050733, 5571.721413929287, 5611.410801865745, 5661.46216109469, 5714.542836572981, 5735.340435849764, 5534.169874518854, 5540.722963851872, 5561.944380556142, 5570.161846590697, 5581.720913954205, 5264.710440925785, 5442.265294185234, 5478.01395748769, 5511.889762204813, 5520.834374968757, 5726.685032323262, 5746.8506298739585, 5749.425057494217, 5771.192033115283, 5777.191193233035, 4626.005408837116, 4658.440987081436, 4686.664300674292, 4690.390249267573, 4692.114349674994, 5444.754986025559, 5561.582881283839, 5574.219609254626, 5579.804706835242, 5604.8038318658655, 5368.355797304349, 5399.757010934596, 5416.593755468277, 5417.699752059556, 5432.619716619957, 5280.920786188133, 5379.73101344939, 5420.322459692538, 5431.755570999236, 5436.646617969819, 5419.674819510842, 5419.810306639249, 5421.566274698093, 5432.066683990601, 5434.701091440009, 5390.380106287827, 5420.376656684532, 5452.482014208644, 5515.227868098389, 5538.473844984881, 5074.847754567369, 5307.734613269398, 5307.7611507483025, 5334.9454414645925, 5341.919871201857], "value": 4378.794698992369, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [840.1682334488858, 846.1158089796162, 898.9775255618608, 904.1967817732964, 933.9633007361831, 17295.82704172958, 18424.355147569837, 18870.62258754824, 19275.903620481913, 19472.513187170345, 15974.281157347918, 16111.919440402811, 16116.75824862628, 16325.918370408132, 17039.57401064974, 852.9616167272487, 867.6269204242174, 870.6256309786773, 887.4054383563029, 890.4078787606234, 885.7475619448459, 886.685226744506, 887.8801361816137, 888.7555922121404, 888.9911100889005, 931.16660588773, 931.4271722890408, 932.2681694869528, 932.7668082979255, 934.1265916368218, 932.1657116880422, 932.202966463674, 932.5057719408691, 932.8647346134807, 932.9766755831158, 678.2437582095977, 679.5990365684221, 679.7213142611555, 680.1566058087985, 680.210955291864, 17803.0, 17957.820421795746, 18029.18868650914, 18827.341043063563, 19063.428097157106, 16141.192940353052, 16319.18404079803, 16432.507024789276, 16450.177491125396, 16593.419230327003, 13416.731665366735, 13578.1853088814, 13597.116187447784, 13854.722905541934, 13905.443782248698, 4024.5975402459812, 4039.4950631171105, 4072.1041370898356, 4137.731047481904, 4178.206140833262, 85360.0, 85476.57261713651, 86855.56572217161, 89146.55426722825, 89255.0, 12181.878181218075, 12223.93888030554, 12477.188982716094, 12527.43626536808, 12668.873311266954, 23554.0, 23638.881805591205, 23760.0, 23904.0, 24314.392140196636, 8621.69824056155, 8848.690295839744, 8860.955695221612, 8973.641054357882, 9004.414713043761, 4997.325361076281, 5034.622403319766, 5058.974705126524, 5064.392272927273, 5200.2199670049795, 3773.4528493368307, 3872.7676339419727, 3896.5324161100284, 3922.3332033554366, 3928.2143571285856, 613.5613036678707, 619.0343064818896, 619.1950464396267, 635.6789821140387, 635.9618422894641, 623.1929651101834, 633.3033662970787, 644.4103645164622, 656.8193746719595, 659.591053546804, 10384.682073765882, 10708.30396024256, 10752.838707419394, 10844.186685998582, 11059.451676765259, 811.9837603247902, 815.0830315894618, 817.8037271055023, 838.1702114906199, 845.8815765792793, 5290.682559046392, 5335.519803217729, 5370.0, 5395.406505284415, 5428.782848686008, 63125.684371577845, 78625.0, 79312.0, 79378.0, 81406.18593813985, 57800.0, 58770.0, 70941.64529177321, 71184.2881571188, 71668.64165679243, 60945.695271524244, 62560.0, 62708.37291627025, 63384.68307658432, 63453.0, 10300.690979270634, 10307.433091180057, 10330.276880618285, 10367.429791361548, 10529.0, 40502.392464113655, 41845.0, 42323.0, 42709.78645106879, 43037.139257215305, 53021.469785301655, 53443.73278133584, 55905.64566125883, 58021.12968305563, 58539.414605853395, 14158.362873670503, 14166.645833854236, 14255.714885702435, 14334.641633959234, 14376.424943002163, 15300.464483743015, 15387.461438849587, 16265.186740663155, 16424.83575164233, 17556.91221543935, 15602.219889005277, 15841.68316633684, 15903.52289431319, 16700.916495417445, 16704.74942875882, 14347.784783228051, 15001.924990375415, 15605.453809116627, 15626.609334766721, 16166.919165404097, 15826.446074387803, 15982.0, 16038.0, 16860.66278674444, 16867.325306987583, 9003.774905627411, 9034.277257819227, 9237.0, 9270.860937086083, 9569.186619137174, 11376.43117844088, 11753.353565553976, 11947.76104477923, 12014.219075760047, 12056.939715301367], "value": 7817.141401493885, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 112.0, 111.0, 112.0, 112.0, 111.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 115.0, 114.0, 161.0, 116.0, 115.0, 115.0, 116.0, 131.0, 116.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 163.0, 161.0, 162.0, 161.0, 159.0, 159.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 117.0, 77.0, 77.0, 75.0, 76.0, 90.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 105.0, 82.0, 83.0, 82.0, 95.0, 82.0, 81.0, 111.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 54.0, 55.0, 58.0, 55.0, 56.0, 55.0, 57.0, 55.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 53.0, 52.0, 53.0, 53.0, 52.0, 53.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 78.0, 75.0, 84.0, 83.0, 75.0, 78.0, 75.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 121.0, 135.0, 120.0, 126.0, 118.0, 119.0, 157.0, 128.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 157.0, 158.0, 159.0, 159.0, 160.0, 156.0, 160.0, 156.0, 163.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 63.0, 59.0, 58.0, 59.0, 58.0, 58.0, 57.0], "value": 58.9, "unit": "score"}]}]} 12:20:38 INFO - Return code: 0 12:20:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:20:38 INFO - # TBPL SUCCESS # 12:20:38 INFO - Running post-action listener: _resource_record_post_action 12:20:38 INFO - [mozharness: 2017-05-26 19:20:38.083281Z] Finished run-tests step (success) 12:20:38 INFO - Running post-run listener: _resource_record_post_run 12:20:38 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 798720; Write bytes: 338038784; Read time: 76; Write time: 264376 12:20:38 INFO - TinderboxPrint: CPU usage
13.1% 12:20:38 INFO - TinderboxPrint: I/O read bytes / time
798,720 / 76 12:20:38 INFO - TinderboxPrint: I/O write bytes / time
338,038,784 / 264,376 12:20:38 INFO - TinderboxPrint: CPU idle
8,263.5 (86.9%) 12:20:38 INFO - TinderboxPrint: CPU user
1,197.3 (12.6%) 12:20:38 INFO - TinderboxPrint: Swap in / out
0 / 0 12:20:38 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 102793216; Read time: 0; Write time: 101976 12:20:38 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 794624; Write bytes: 235245568; Read time: 52; Write time: 162400 12:20:38 INFO - Running post-run listener: _upload_blobber_files 12:20:38 INFO - Blob upload gear active. 12:20:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:20:38 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 12:20:38 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'] 12:20:38 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 12:20:39 INFO - (blobuploader) - INFO - Open directory for files ... 12:20:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:20:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:20:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:39 INFO - (blobuploader) - INFO - Done attempting. 12:20:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:20:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:43 INFO - (blobuploader) - INFO - Done attempting. 12:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:43 INFO - (blobuploader) - INFO - Done attempting. 12:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:44 INFO - (blobuploader) - INFO - Done attempting. 12:20:44 INFO - (blobuploader) - INFO - Iteration through files over. 12:20:44 INFO - Return code: 0 12:20:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:20:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:20:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/473099630b00344e8b75cd8582d9e934bb8871bc1d5b672b8df527369ab2f989d5ee79487053f22b9c64392ab4b038b83939cd3866254e65e0c061d2ca080656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7fe0708a9f54a1d0463f2591c46f3f98a5c649a63ff34a53aa7627725b4b83272d328746d4201c221dd882909a9f6cd34f8a0cb0d0cddc166fa18545d0921cd9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15a1eb9179f305171a82220989f561af53de60e1084a25e18323cd8524562ce4e8009fa1d85fdeb935fb76f2d2a0537496028458271c420f224f3d978dd697e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c1a2212cd8304cf1792ce42cb19ba598a561301e9387e326859d2075a61a3800806b222b8d79cb8328c5605baa0559143ba814833ddba1d60b40558a18c9406"} 12:20:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:20:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:20:44 INFO - Contents: 12:20:44 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/473099630b00344e8b75cd8582d9e934bb8871bc1d5b672b8df527369ab2f989d5ee79487053f22b9c64392ab4b038b83939cd3866254e65e0c061d2ca080656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7fe0708a9f54a1d0463f2591c46f3f98a5c649a63ff34a53aa7627725b4b83272d328746d4201c221dd882909a9f6cd34f8a0cb0d0cddc166fa18545d0921cd9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15a1eb9179f305171a82220989f561af53de60e1084a25e18323cd8524562ce4e8009fa1d85fdeb935fb76f2d2a0537496028458271c420f224f3d978dd697e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c1a2212cd8304cf1792ce42cb19ba598a561301e9387e326859d2075a61a3800806b222b8d79cb8328c5605baa0559143ba814833ddba1d60b40558a18c9406"} 12:20:44 INFO - Running post-run listener: copy_logs_to_upload_dir 12:20:44 INFO - Copying logs to upload dir... 12:20:44 INFO - mkdir: /builds/slave/test/build/upload/logs 12:20:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.727940 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-26 12:20:44.077162) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 12:20:44.087722) ========= 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=5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/473099630b00344e8b75cd8582d9e934bb8871bc1d5b672b8df527369ab2f989d5ee79487053f22b9c64392ab4b038b83939cd3866254e65e0c061d2ca080656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7fe0708a9f54a1d0463f2591c46f3f98a5c649a63ff34a53aa7627725b4b83272d328746d4201c221dd882909a9f6cd34f8a0cb0d0cddc166fa18545d0921cd9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15a1eb9179f305171a82220989f561af53de60e1084a25e18323cd8524562ce4e8009fa1d85fdeb935fb76f2d2a0537496028458271c420f224f3d978dd697e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c1a2212cd8304cf1792ce42cb19ba598a561301e9387e326859d2075a61a3800806b222b8d79cb8328c5605baa0559143ba814833ddba1d60b40558a18c9406"} build_url:https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/caZ_lRPARI-V1pPJoNd-Sg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/473099630b00344e8b75cd8582d9e934bb8871bc1d5b672b8df527369ab2f989d5ee79487053f22b9c64392ab4b038b83939cd3866254e65e0c061d2ca080656", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7fe0708a9f54a1d0463f2591c46f3f98a5c649a63ff34a53aa7627725b4b83272d328746d4201c221dd882909a9f6cd34f8a0cb0d0cddc166fa18545d0921cd9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/15a1eb9179f305171a82220989f561af53de60e1084a25e18323cd8524562ce4e8009fa1d85fdeb935fb76f2d2a0537496028458271c420f224f3d978dd697e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c1a2212cd8304cf1792ce42cb19ba598a561301e9387e326859d2075a61a3800806b222b8d79cb8328c5605baa0559143ba814833ddba1d60b40558a18c9406"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 12:20:44.117525) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 12:20:44.117915) ========= 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=5c37b704b588aaa989324530000001d0-1495823178.622158-1787860276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 12:20:44.202433) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 12:20:44.202793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 12:20:44.203142) ========= ========= Total master_lag: 0.29 =========