builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-063 starttime: 1495097721.5 results: success (0) revision: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.501846) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.502293) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.502584) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.595227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.595617) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-18 01:55:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 209M=0s 2017-05-18 01:55:21 (209 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090322 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.743887) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.744208) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018541 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:21.776974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 01:55:21.777306) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 --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 a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 --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=769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-18 01:55:21,957 truncating revision to first 12 chars 2017-05-18 01:55:21,957 Setting DEBUG logging. 2017-05-18 01:55:21,957 attempt 1/10 2017-05-18 01:55:21,957 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a3bc13b60d41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 01:55:23,279 unpacking tar archive at: mozilla-beta-a3bc13b60d41/testing/mozharness/ program finished with exit code 0 elapsedTime=1.605230 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 01:55:23.401255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:23.401595) ========= script_repo_revision: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:23.401987) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:23.402249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 01:55:23.408840) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-05-18 01:55:23.409136) ========= /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=769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:55:23 INFO - MultiFileLogger online at 20170518 01:55:23 in /builds/slave/test 01:55:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 01:55:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:55:23 INFO - {'append_to_log': False, 01:55:23 INFO - 'base_work_dir': '/builds/slave/test', 01:55:23 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 01:55:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:55:23 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 01:55:23 INFO - 'buildbot_json_path': 'buildprops.json', 01:55:23 INFO - 'config_files': ('talos/linux_config.py',), 01:55:23 INFO - 'default_actions': ('clobber', 01:55:23 INFO - 'read-buildbot-config', 01:55:23 INFO - 'download-and-extract', 01:55:23 INFO - 'populate-webroot', 01:55:23 INFO - 'create-virtualenv', 01:55:23 INFO - 'install', 01:55:23 INFO - 'run-tests'), 01:55:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:55:23 INFO - 'download_minidump_stackwalk': True, 01:55:23 INFO - 'download_symbols': 'ondemand', 01:55:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:55:23 INFO - 'tooltool.py': '/tools/tooltool.py', 01:55:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:55:23 INFO - '/tools/misc-python/virtualenv.py')}, 01:55:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:55:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:55:23 INFO - 'gecko_profile': False, 01:55:23 INFO - 'gecko_profile_interval': 0, 01:55:23 INFO - 'installer_path': 'installer.exe', 01:55:23 INFO - 'log_level': 'info', 01:55:23 INFO - 'log_name': 'talos', 01:55:23 INFO - 'log_to_console': True, 01:55:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:55:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:55:23 INFO - 'opt_config_files': (), 01:55:23 INFO - 'pip_index': False, 01:55:23 INFO - 'suite': 'dromaeojs', 01:55:23 INFO - 'system_bits': '32', 01:55:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:55:23 INFO - 'title': 'talos-linux64-ix-063', 01:55:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:55:23 INFO - 'use_talos_json': True, 01:55:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:55:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:55:23 INFO - 'work_dir': 'build'} 01:55:23 INFO - [mozharness: 2017-05-18 08:55:23.682598Z] Running clobber step. 01:55:23 INFO - Running pre-action listener: _resource_record_pre_action 01:55:23 INFO - Running main action method: clobber 01:55:23 INFO - rmtree: /builds/slave/test/build 01:55:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:55:24 INFO - Running post-action listener: _resource_record_post_action 01:55:24 INFO - [mozharness: 2017-05-18 08:55:24.678933Z] Finished clobber step (success) 01:55:24 INFO - [mozharness: 2017-05-18 08:55:24.679032Z] Running read-buildbot-config step. 01:55:24 INFO - Running pre-action listener: _resource_record_pre_action 01:55:24 INFO - Running main action method: read_buildbot_config 01:55:24 INFO - Using buildbot properties: 01:55:24 INFO - { 01:55:24 INFO - "project": "", 01:55:24 INFO - "product": "firefox", 01:55:24 INFO - "who": "mak77@bonardo.net", 01:55:24 INFO - "installer_path": "public/build/target.tar.bz2", 01:55:24 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:55:24 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 01:55:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 01:55:24 INFO - "stage_platform": "linux64", 01:55:24 INFO - "basedir": "/builds/slave/test", 01:55:24 INFO - "buildnumber": 49, 01:55:24 INFO - "platform": "ubuntu64_hw", 01:55:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:55:24 INFO - "slavebuilddir": "test", 01:55:24 INFO - "taskId": "chKrDTUFSTe9zHPvdyxJVA", 01:55:24 INFO - "branch": "mozilla-beta", 01:55:24 INFO - "script_repo_revision": "production", 01:55:24 INFO - "revision": "a3bc13b60d416ad4a0dd42820ede5c0d69c288a0", 01:55:24 INFO - "slavename": "talos-linux64-ix-063", 01:55:24 INFO - "repo_path": "releases/mozilla-beta" 01:55:24 INFO - } 01:55:24 INFO - Finding installer, test and symbols from parent task. 01:55:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chKrDTUFSTe9zHPvdyxJVA'}, attempt #1 01:55:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chKrDTUFSTe9zHPvdyxJVA'}, attempt #1 01:55:25 INFO - Task dependencies: BCtiJzQjTTGMNAqN4HNAMw 01:55:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw'}, attempt #1 01:55:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2 01:55:26 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/test_packages.json 01:55:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.crashreporter-symbols.zip 01:55:26 INFO - Running post-action listener: _resource_record_post_action 01:55:26 INFO - [mozharness: 2017-05-18 08:55:26.040267Z] Finished read-buildbot-config step (success) 01:55:26 INFO - [mozharness: 2017-05-18 08:55:26.040484Z] Running download-and-extract step. 01:55:26 INFO - Running pre-action listener: _resource_record_pre_action 01:55:26 INFO - Running main action method: download_and_extract 01:55:26 INFO - mkdir: /builds/slave/test/build/tests 01:55: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')]} 01:55:26 INFO - trying https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.test_packages.json 01:55:26 INFO - Downloading https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:55:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:55:26 INFO - Downloaded 986 bytes. 01:55:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:55:26 INFO - Using the following test package requirements: 01:55:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 01:55:26 INFO - u'common': [u'target.common.tests.zip'], 01:55:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:55:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:55:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:55:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:55:26 INFO - u'mozbase': [u'target.common.tests.zip'], 01:55:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:55:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:55:26 INFO - u'web-platform': [u'target.common.tests.zip', 01:55:26 INFO - u'target.web-platform.tests.zip'], 01:55:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:55:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:55:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.common.tests.zip 01:55:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:55:26 INFO - Fetch https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.common.tests.zip into memory 01:55:27 INFO - Expected file size: 59542546 01:55:27 INFO - Obtained file size: 59542546 01:55:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:55:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.common.tests.zip 01:55:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:55:37 INFO - Fetch https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.common.tests.zip into memory 01:55:38 INFO - Expected file size: 59542546 01:55:38 INFO - Obtained file size: 59542546 01:55:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.talos.tests.zip 01:55:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:55:49 INFO - Fetch https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.talos.tests.zip into memory 01:55:50 INFO - Expected file size: 14404449 01:55:50 INFO - Obtained file size: 14404449 01:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:55:50 INFO - trying https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2 01:55:50 INFO - Downloading https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:55:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:55:51 INFO - Downloaded 59741261 bytes. 01:55:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2 01:55:51 INFO - mkdir: /builds/slave/test/properties 01:55:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:55:51 INFO - Writing to file /builds/slave/test/properties/build_url 01:55:51 INFO - Contents: 01:55:51 INFO - build_url:https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2 01:55:51 INFO - Running post-action listener: _resource_record_post_action 01:55:51 INFO - Running post-action listener: set_extra_try_arguments 01:55:51 INFO - [mozharness: 2017-05-18 08:55:51.961151Z] Finished download-and-extract step (success) 01:55:51 INFO - [mozharness: 2017-05-18 08:55:51.961248Z] Running populate-webroot step. 01:55:51 INFO - Running pre-action listener: _resource_record_pre_action 01:55:51 INFO - Running main action method: populate_webroot 01:55:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:55:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:55:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:55:51 INFO - '--tppagecycles', 01:55:51 INFO - '7'], 01:55:51 INFO - 'tests': ['tsvgm']}}, 01:55:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:55:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:55:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:55:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:55:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:55:51 INFO - 'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:55:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:55:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:55:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:55:51 INFO - 'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['damp', 'tps']}, 01:55:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:55:51 INFO - 'tests': ['damp', 'tps']}, 01:55:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['dromaeo_dom']}, 01:55:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:55:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['basic_compositor_video']}, 01:55:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:55:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['a11yr', 01:55:51 INFO - 'ts_paint', 01:55:51 INFO - 'tpaint', 01:55:51 INFO - 'sessionrestore', 01:55:51 INFO - 'sessionrestore_no_auto_restore', 01:55:51 INFO - 'tabpaint']}, 01:55:51 INFO - 'other-e10s': {'tests': ['a11yr', 01:55:51 INFO - 'ts_paint', 01:55:51 INFO - 'tpaint', 01:55:51 INFO - 'sessionrestore', 01:55:51 INFO - 'sessionrestore_no_auto_restore', 01:55:51 INFO - 'tabpaint']}, 01:55:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:55:51 INFO - 'ts_paint', 01:55:51 INFO - 'tpaint', 01:55:51 INFO - 'sessionrestore', 01:55:51 INFO - 'sessionrestore_no_auto_restore', 01:55:51 INFO - 'tabpaint']}, 01:55:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:55:51 INFO - 'ts_paint', 01:55:51 INFO - 'tpaint', 01:55:51 INFO - 'sessionrestore', 01:55:51 INFO - 'sessionrestore_no_auto_restore', 01:55:51 INFO - 'tabpaint']}, 01:55:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['a11yr', 01:55:51 INFO - 'ts_paint', 01:55:51 INFO - 'tpaint', 01:55:51 INFO - 'sessionrestore', 01:55:51 INFO - 'sessionrestore_no_auto_restore', 01:55:51 INFO - 'tabpaint']}, 01:55:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['a11yr', 01:55:51 INFO - 'ts_paint', 01:55:51 INFO - 'tpaint', 01:55:51 INFO - 'sessionrestore', 01:55:51 INFO - 'sessionrestore_no_auto_restore', 01:55:51 INFO - 'tabpaint']}, 01:55:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['tsvgx', 01:55:51 INFO - 'tsvgr_opacity', 01:55:51 INFO - 'tart', 01:55:51 INFO - 'tscrollx', 01:55:51 INFO - 'cart', 01:55:51 INFO - 'tsvg_static']}, 01:55:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:55:51 INFO - 'tsvgr_opacity', 01:55:51 INFO - 'tart', 01:55:51 INFO - 'tscrollx', 01:55:51 INFO - 'cart', 01:55:51 INFO - 'tsvg_static']}, 01:55:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:55:51 INFO - 'talos_options': ['--disable-e10s'], 01:55:51 INFO - 'tests': ['tp5o']}, 01:55:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:55:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:55:51 INFO - 'talos_options': ['--disable-e10s', 01:55:51 INFO - '--xperf_path', 01:55:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:55:51 INFO - 'tests': ['tp5n']}, 01:55:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:55:51 INFO - 'talos_options': ['--xperf_path', 01:55:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:55:51 INFO - 'tests': ['tp5n']}}, 01:55:51 INFO - 'talos.zip': {'path': '', 01:55:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:55:51 INFO - Running post-action listener: _resource_record_post_action 01:55:51 INFO - [mozharness: 2017-05-18 08:55:51.979184Z] Finished populate-webroot step (success) 01:55:51 INFO - [mozharness: 2017-05-18 08:55:51.979274Z] Running create-virtualenv step. 01:55:51 INFO - Running pre-action listener: _resource_record_pre_action 01:55:51 INFO - Running main action method: create_virtualenv 01:55:51 INFO - Creating virtualenv /builds/slave/test/build/venv 01:55:51 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 01:55:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:55:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:55:52 INFO - Using real prefix '/usr' 01:55:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:55:52 INFO - Installing distribute.............................................................................................................................................................................................done. 01:55:54 INFO - Installing pip.................done. 01:55:54 INFO - Return code: 0 01:55:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:55:54 INFO - Reading from file tmpfile_stdout 01:55:54 INFO - Output received: 01:55:54 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) 01:55:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:55:54 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')]} 01:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:55:54 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')]} 01:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:55:54 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:55:54 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 01:55:54 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 01:55:54 INFO - Using env: {'DISPLAY': ':0', 01:55:54 INFO - 'HOME': '/home/cltbld', 01:55:54 INFO - 'LANG': 'en_US.UTF-8', 01:55:54 INFO - 'LANGUAGE': 'en_US:en', 01:55:54 INFO - 'LOGNAME': 'cltbld', 01:55:54 INFO - 'MAIL': '/var/mail/cltbld', 01:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:55:54 INFO - 'MOZ_NO_REMOTE': '1', 01:55:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:55:54 INFO - 'NO_EM_RESTART': '1', 01:55:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:55:54 INFO - 'PWD': '/builds/slave/test', 01:55:54 INFO - 'SHELL': '/bin/bash', 01:55:54 INFO - 'SHLVL': '1', 01:55:54 INFO - 'TERM': 'linux', 01:55:54 INFO - 'TMOUT': '86400', 01:55:54 INFO - 'USER': 'cltbld', 01:55:54 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:55:54 INFO - '_': '/tools/buildbot/bin/python'} 01:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:55:54 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 01:55:54 INFO - Cleaning up... 01:55:54 INFO - Return code: 0 01:55:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:55:54 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')]} 01:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:55:54 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')]} 01:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:55:54 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:55:54 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 01:55:54 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 01:55:54 INFO - Using env: {'DISPLAY': ':0', 01:55:54 INFO - 'HOME': '/home/cltbld', 01:55:54 INFO - 'LANG': 'en_US.UTF-8', 01:55:54 INFO - 'LANGUAGE': 'en_US:en', 01:55:54 INFO - 'LOGNAME': 'cltbld', 01:55:54 INFO - 'MAIL': '/var/mail/cltbld', 01:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:55:54 INFO - 'MOZ_NO_REMOTE': '1', 01:55:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:55:54 INFO - 'NO_EM_RESTART': '1', 01:55:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:55:54 INFO - 'PWD': '/builds/slave/test', 01:55:54 INFO - 'SHELL': '/bin/bash', 01:55:54 INFO - 'SHLVL': '1', 01:55:54 INFO - 'TERM': 'linux', 01:55:54 INFO - 'TMOUT': '86400', 01:55:54 INFO - 'USER': 'cltbld', 01:55:54 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:55:54 INFO - '_': '/tools/buildbot/bin/python'} 01:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:55:55 INFO - Downloading/unpacking psutil>=3.1.1 01:55:55 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 01:55:55 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 01:55:55 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 01:55:55 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 01:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:55:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:55:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:55:56 INFO - Installing collected packages: psutil 01:55:56 INFO - Running setup.py install for psutil 01:55:56 INFO - building 'psutil._psutil_linux' extension 01:55:56 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 01:55:56 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 01:55:56 INFO - building 'psutil._psutil_posix' extension 01:55:56 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 01:55:56 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 01:55:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:55:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:55:56 INFO - Successfully installed psutil 01:55:56 INFO - Cleaning up... 01:55:56 INFO - Return code: 0 01:55:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:55:56 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')]} 01:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:55:56 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')]} 01:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:55:56 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:55:56 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 01:55:56 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 01:55:56 INFO - Using env: {'DISPLAY': ':0', 01:55:56 INFO - 'HOME': '/home/cltbld', 01:55:56 INFO - 'LANG': 'en_US.UTF-8', 01:55:56 INFO - 'LANGUAGE': 'en_US:en', 01:55:56 INFO - 'LOGNAME': 'cltbld', 01:55:56 INFO - 'MAIL': '/var/mail/cltbld', 01:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:55:56 INFO - 'MOZ_NO_REMOTE': '1', 01:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:55:56 INFO - 'NO_EM_RESTART': '1', 01:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:55:56 INFO - 'PWD': '/builds/slave/test', 01:55:56 INFO - 'SHELL': '/bin/bash', 01:55:56 INFO - 'SHLVL': '1', 01:55:56 INFO - 'TERM': 'linux', 01:55:56 INFO - 'TMOUT': '86400', 01:55:56 INFO - 'USER': 'cltbld', 01:55:56 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:55:56 INFO - '_': '/tools/buildbot/bin/python'} 01:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:55:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:55:57 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 01:55:57 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 01:55:57 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 01:55:57 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 01:55:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:55:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:55:58 INFO - Installing collected packages: mozsystemmonitor 01:55:58 INFO - Running setup.py install for mozsystemmonitor 01:55:58 INFO - Successfully installed mozsystemmonitor 01:55:58 INFO - Cleaning up... 01:55:58 INFO - Return code: 0 01:55:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:55:58 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:55:58 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 01:55:58 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 01:55:58 INFO - Using env: {'DISPLAY': ':0', 01:55:58 INFO - 'HOME': '/home/cltbld', 01:55:58 INFO - 'LANG': 'en_US.UTF-8', 01:55:58 INFO - 'LANGUAGE': 'en_US:en', 01:55:58 INFO - 'LOGNAME': 'cltbld', 01:55:58 INFO - 'MAIL': '/var/mail/cltbld', 01:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:55:58 INFO - 'MOZ_NO_REMOTE': '1', 01:55:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:55:58 INFO - 'NO_EM_RESTART': '1', 01:55:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:55:58 INFO - 'PWD': '/builds/slave/test', 01:55:58 INFO - 'SHELL': '/bin/bash', 01:55:58 INFO - 'SHLVL': '1', 01:55:58 INFO - 'TERM': 'linux', 01:55:58 INFO - 'TMOUT': '86400', 01:55:58 INFO - 'USER': 'cltbld', 01:55:58 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:55:58 INFO - '_': '/tools/buildbot/bin/python'} 01:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:55:58 INFO - Downloading/unpacking jsonschema==2.5.1 01:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:55:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:55:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:55:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:55:59 INFO - Downloading functools32-3.2.3-2.tar.gz 01:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:55:59 INFO - warning: no files found matching '*.txt' 01:55:59 INFO - no previously-included directories found matching 'build' 01:55:59 INFO - no previously-included directories found matching 'dist' 01:55:59 INFO - no previously-included directories found matching '.git*' 01:55:59 INFO - Installing collected packages: jsonschema, functools32 01:55:59 INFO - Running setup.py install for functools32 01:56:00 INFO - warning: no files found matching '*.txt' 01:56:00 INFO - no previously-included directories found matching 'build' 01:56:00 INFO - no previously-included directories found matching 'dist' 01:56:00 INFO - no previously-included directories found matching '.git*' 01:56:00 INFO - Successfully installed jsonschema functools32 01:56:00 INFO - Cleaning up... 01:56:00 INFO - Return code: 0 01:56:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:56: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')]} 01:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:56: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')]} 01:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:56:00 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:56:00 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 01:56:00 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 01:56:00 INFO - Using env: {'DISPLAY': ':0', 01:56:00 INFO - 'HOME': '/home/cltbld', 01:56:00 INFO - 'LANG': 'en_US.UTF-8', 01:56:00 INFO - 'LANGUAGE': 'en_US:en', 01:56:00 INFO - 'LOGNAME': 'cltbld', 01:56:00 INFO - 'MAIL': '/var/mail/cltbld', 01:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:56:00 INFO - 'MOZ_NO_REMOTE': '1', 01:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:56:00 INFO - 'NO_EM_RESTART': '1', 01:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:56:00 INFO - 'PWD': '/builds/slave/test', 01:56:00 INFO - 'SHELL': '/bin/bash', 01:56:00 INFO - 'SHLVL': '1', 01:56:00 INFO - 'TERM': 'linux', 01:56:00 INFO - 'TMOUT': '86400', 01:56:00 INFO - 'USER': 'cltbld', 01:56:00 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:56:00 INFO - '_': '/tools/buildbot/bin/python'} 01:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:56:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:56:00 INFO - Cleaning up... 01:56:00 INFO - Return code: 0 01:56:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:56: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')]} 01:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:56: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')]} 01:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:56:00 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:56:00 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 01:56:00 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 01:56:00 INFO - Using env: {'DISPLAY': ':0', 01:56:00 INFO - 'HOME': '/home/cltbld', 01:56:00 INFO - 'LANG': 'en_US.UTF-8', 01:56:00 INFO - 'LANGUAGE': 'en_US:en', 01:56:00 INFO - 'LOGNAME': 'cltbld', 01:56:00 INFO - 'MAIL': '/var/mail/cltbld', 01:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:56:00 INFO - 'MOZ_NO_REMOTE': '1', 01:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:56:00 INFO - 'NO_EM_RESTART': '1', 01:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:56:00 INFO - 'PWD': '/builds/slave/test', 01:56:00 INFO - 'SHELL': '/bin/bash', 01:56:00 INFO - 'SHLVL': '1', 01:56:00 INFO - 'TERM': 'linux', 01:56:00 INFO - 'TMOUT': '86400', 01:56:00 INFO - 'USER': 'cltbld', 01:56:00 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:56:00 INFO - '_': '/tools/buildbot/bin/python'} 01:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:56:00 INFO - Downloading/unpacking blobuploader==1.2.4 01:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:56:01 INFO - Downloading blobuploader-1.2.4.tar.gz 01:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:56:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:56:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:56:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:56:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:56:02 INFO - Downloading docopt-0.6.1.tar.gz 01:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:56:02 INFO - Installing collected packages: blobuploader, requests, docopt 01:56:02 INFO - Running setup.py install for blobuploader 01:56:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:56:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:56:02 INFO - Running setup.py install for requests 01:56:02 INFO - Running setup.py install for docopt 01:56:02 INFO - Successfully installed blobuploader requests docopt 01:56:02 INFO - Cleaning up... 01:56:02 INFO - Return code: 0 01:56:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:56:02 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')]} 01:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:56:02 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')]} 01:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:56:02 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:56:02 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 01:56:02 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 01:56:02 INFO - Using env: {'DISPLAY': ':0', 01:56:02 INFO - 'HOME': '/home/cltbld', 01:56:02 INFO - 'LANG': 'en_US.UTF-8', 01:56:02 INFO - 'LANGUAGE': 'en_US:en', 01:56:02 INFO - 'LOGNAME': 'cltbld', 01:56:02 INFO - 'MAIL': '/var/mail/cltbld', 01:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:56:02 INFO - 'MOZ_NO_REMOTE': '1', 01:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:56:02 INFO - 'NO_EM_RESTART': '1', 01:56:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:56:02 INFO - 'PWD': '/builds/slave/test', 01:56:02 INFO - 'SHELL': '/bin/bash', 01:56:02 INFO - 'SHLVL': '1', 01:56:02 INFO - 'TERM': 'linux', 01:56:02 INFO - 'TMOUT': '86400', 01:56:02 INFO - 'USER': 'cltbld', 01:56:02 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:56:02 INFO - '_': '/tools/buildbot/bin/python'} 01:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:56:03 INFO - Running setup.py (path:/tmp/pip-gP1LLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:56:03 INFO - Running setup.py (path:/tmp/pip-l7v0_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:56:03 INFO - Running setup.py (path:/tmp/pip-WcW46E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:56:03 INFO - Running setup.py (path:/tmp/pip-WrYbln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:56:03 INFO - Running setup.py (path:/tmp/pip-fa3oJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:56:03 INFO - Running setup.py (path:/tmp/pip-aGNKcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:56:03 INFO - Running setup.py (path:/tmp/pip-W7NBYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:56:03 INFO - Running setup.py (path:/tmp/pip-1JXggV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:56:03 INFO - Running setup.py (path:/tmp/pip-TzLy4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:56:03 INFO - Running setup.py (path:/tmp/pip-rXsKr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:56:03 INFO - Running setup.py (path:/tmp/pip-lSmo1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:56:03 INFO - Running setup.py (path:/tmp/pip-SWMHjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:56:03 INFO - Running setup.py (path:/tmp/pip-BIy27_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:56:04 INFO - Running setup.py (path:/tmp/pip-R8Fb3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:56:04 INFO - Running setup.py (path:/tmp/pip-4Bj9zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:56:04 INFO - Running setup.py (path:/tmp/pip-hYZYDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:56:04 INFO - Running setup.py (path:/tmp/pip-LcVLqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:56:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:56:04 INFO - Running setup.py install for manifestparser 01:56:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:56:04 INFO - Running setup.py install for mozcrash 01:56:04 INFO - Running setup.py install for mozdebug 01:56:04 INFO - Running setup.py install for mozdevice 01:56:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:56:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:56:04 INFO - Running setup.py install for mozfile 01:56:04 INFO - Running setup.py install for mozhttpd 01:56:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:56:04 INFO - Running setup.py install for mozinfo 01:56:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:56:04 INFO - Running setup.py install for mozInstall 01:56:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Running setup.py install for mozleak 01:56:05 INFO - Running setup.py install for mozlog 01:56:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Running setup.py install for moznetwork 01:56:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Running setup.py install for mozprocess 01:56:05 INFO - Running setup.py install for mozprofile 01:56:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Running setup.py install for mozrunner 01:56:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:56:05 INFO - Running setup.py install for mozscreenshot 01:56:05 INFO - Running setup.py install for moztest 01:56:05 INFO - Running setup.py install for mozversion 01:56:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:56:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:56:06 INFO - Cleaning up... 01:56:06 INFO - Return code: 0 01:56:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:56:06 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')]} 01:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:56:06 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')]} 01:56:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:56:06 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:56:06 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 01:56:06 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 01:56:06 INFO - Using env: {'DISPLAY': ':0', 01:56:06 INFO - 'HOME': '/home/cltbld', 01:56:06 INFO - 'LANG': 'en_US.UTF-8', 01:56:06 INFO - 'LANGUAGE': 'en_US:en', 01:56:06 INFO - 'LOGNAME': 'cltbld', 01:56:06 INFO - 'MAIL': '/var/mail/cltbld', 01:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:56:06 INFO - 'MOZ_NO_REMOTE': '1', 01:56:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:56:06 INFO - 'NO_EM_RESTART': '1', 01:56:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:56:06 INFO - 'PWD': '/builds/slave/test', 01:56:06 INFO - 'SHELL': '/bin/bash', 01:56:06 INFO - 'SHLVL': '1', 01:56:06 INFO - 'TERM': 'linux', 01:56:06 INFO - 'TMOUT': '86400', 01:56:06 INFO - 'USER': 'cltbld', 01:56:06 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:56:06 INFO - '_': '/tools/buildbot/bin/python'} 01:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:56:06 INFO - Running setup.py (path:/tmp/pip-Ozj3kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:56:06 INFO - Running setup.py (path:/tmp/pip-Ul31lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:56:06 INFO - Running setup.py (path:/tmp/pip-Q7bvp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:56:06 INFO - Running setup.py (path:/tmp/pip-YGtntE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:56:06 INFO - Running setup.py (path:/tmp/pip-7lCuOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:56:06 INFO - Running setup.py (path:/tmp/pip-IjHgcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:56:06 INFO - Running setup.py (path:/tmp/pip-o3Zfiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:56:06 INFO - Running setup.py (path:/tmp/pip-xysojD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:56:06 INFO - Running setup.py (path:/tmp/pip-Qi14bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:56:06 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)) 01:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:56:06 INFO - Running setup.py (path:/tmp/pip-3555jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:56:07 INFO - Running setup.py (path:/tmp/pip-QuvDXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:56:07 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)) 01:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:56:07 INFO - Running setup.py (path:/tmp/pip-b1ZddQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:56:07 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)) 01:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:56:07 INFO - Running setup.py (path:/tmp/pip-f1WPSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:56:07 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)) 01:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:56:07 INFO - Running setup.py (path:/tmp/pip-hSCgOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:56:07 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)) 01:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:56:07 INFO - Running setup.py (path:/tmp/pip-C7VsOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:56:07 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)) 01:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:56:07 INFO - Running setup.py (path:/tmp/pip-zTvk86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:56:07 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)) 01:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:56:07 INFO - Running setup.py (path:/tmp/pip-k6ncvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:56:07 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)) 01:56:07 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)) 01:56:07 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)) 01:56:07 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)) 01:56:07 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)) 01:56:07 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)) 01:56:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:56:07 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 01:56:07 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 01:56:07 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 01:56:07 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 01:56:08 INFO - Downloading blessings-1.6.tar.gz 01:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:56:08 INFO - Installing collected packages: blessings 01:56:08 INFO - Running setup.py install for blessings 01:56:08 INFO - Successfully installed blessings 01:56:08 INFO - Cleaning up... 01:56:08 INFO - Return code: 0 01:56:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:56:09 INFO - Reading from file tmpfile_stdout 01:56:09 INFO - Current package versions: 01:56:09 INFO - argparse == 1.2.1 01:56:09 INFO - blessings == 1.6 01:56:09 INFO - blobuploader == 1.2.4 01:56:09 INFO - docopt == 0.6.1 01:56:09 INFO - functools32 == 3.2.3-2 01:56:09 INFO - jsonschema == 2.5.1 01:56:09 INFO - manifestparser == 1.1 01:56:09 INFO - mozInstall == 1.12 01:56:09 INFO - mozcrash == 1.0 01:56:09 INFO - mozdebug == 0.1 01:56:09 INFO - mozdevice == 0.49 01:56:09 INFO - mozfile == 1.2 01:56:09 INFO - mozhttpd == 0.7 01:56:09 INFO - mozinfo == 0.9 01:56:09 INFO - mozleak == 0.1 01:56:09 INFO - mozlog == 3.4 01:56:09 INFO - moznetwork == 0.27 01:56:09 INFO - mozprocess == 0.25 01:56:09 INFO - mozprofile == 0.28 01:56:09 INFO - mozrunner == 6.13 01:56:09 INFO - mozscreenshot == 0.1 01:56:09 INFO - mozsystemmonitor == 0.3 01:56:09 INFO - moztest == 0.8 01:56:09 INFO - mozversion == 1.4 01:56:09 INFO - psutil == 3.1.1 01:56:09 INFO - requests == 1.2.3 01:56:09 INFO - wsgiref == 0.1.2 01:56:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:56:09 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 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:56:09 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 01:56:09 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 01:56:09 INFO - Using env: {'DISPLAY': ':0', 01:56:09 INFO - 'HOME': '/home/cltbld', 01:56:09 INFO - 'LANG': 'en_US.UTF-8', 01:56:09 INFO - 'LANGUAGE': 'en_US:en', 01:56:09 INFO - 'LOGNAME': 'cltbld', 01:56:09 INFO - 'MAIL': '/var/mail/cltbld', 01:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:56:09 INFO - 'MOZ_NO_REMOTE': '1', 01:56:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:56:09 INFO - 'NO_EM_RESTART': '1', 01:56:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:56:09 INFO - 'PWD': '/builds/slave/test', 01:56:09 INFO - 'SHELL': '/bin/bash', 01:56:09 INFO - 'SHLVL': '1', 01:56:09 INFO - 'TERM': 'linux', 01:56:09 INFO - 'TMOUT': '86400', 01:56:09 INFO - 'USER': 'cltbld', 01:56:09 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:56:09 INFO - '_': '/tools/buildbot/bin/python'} 01:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 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)) 01:56:09 INFO - Cleaning up... 01:56:09 INFO - Return code: 0 01:56:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:56:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe1b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', '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 01:56:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:56:09 INFO - Using env: {'DISPLAY': ':0', 01:56:09 INFO - 'HOME': '/home/cltbld', 01:56:09 INFO - 'LANG': 'en_US.UTF-8', 01:56:09 INFO - 'LANGUAGE': 'en_US:en', 01:56:09 INFO - 'LOGNAME': 'cltbld', 01:56:09 INFO - 'MAIL': '/var/mail/cltbld', 01:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:56:09 INFO - 'MOZ_NO_REMOTE': '1', 01:56:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:56:09 INFO - 'NO_EM_RESTART': '1', 01:56:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:56:09 INFO - 'PWD': '/builds/slave/test', 01:56:09 INFO - 'SHELL': '/bin/bash', 01:56:09 INFO - 'SHLVL': '1', 01:56:09 INFO - 'TERM': 'linux', 01:56:09 INFO - 'TMOUT': '86400', 01:56:09 INFO - 'USER': 'cltbld', 01:56:09 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:56:09 INFO - '_': '/tools/buildbot/bin/python'} 01:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:56:09 INFO - Cleaning up... 01:56:09 INFO - Return code: 0 01:56:09 INFO - Running post-action listener: _resource_record_post_action 01:56:09 INFO - Running post-action listener: _start_resource_monitoring 01:56:09 INFO - Starting resource monitoring. 01:56:09 INFO - [mozharness: 2017-05-18 08:56:09.589375Z] Finished create-virtualenv step (success) 01:56:09 INFO - [mozharness: 2017-05-18 08:56:09.589844Z] Running install step. 01:56:09 INFO - Running pre-action listener: _resource_record_pre_action 01:56:09 INFO - Running main action method: install 01:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:56:09 INFO - Reading from file tmpfile_stdout 01:56:09 INFO - Detecting whether we're running mozinstall >=1.0... 01:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:56:09 INFO - Reading from file tmpfile_stdout 01:56:09 INFO - Output received: 01:56:09 INFO - Usage: mozinstall [options] installer 01:56:09 INFO - Options: 01:56:09 INFO - -h, --help show this help message and exit 01:56:09 INFO - -d DEST, --destination=DEST 01:56:09 INFO - Directory to install application into. [default: 01:56:09 INFO - "/builds/slave/test"] 01:56:09 INFO - --app=APP Application being installed. [default: firefox] 01:56:09 INFO - mkdir: /builds/slave/test/build/application 01:56:09 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'] 01:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:56:23 INFO - Reading from file tmpfile_stdout 01:56:23 INFO - Output received: 01:56:23 INFO - /builds/slave/test/build/application/firefox/firefox 01:56:23 INFO - Running post-action listener: _resource_record_post_action 01:56:23 INFO - [mozharness: 2017-05-18 08:56:23.935898Z] Finished install step (success) 01:56:23 INFO - [mozharness: 2017-05-18 08:56:23.936139Z] Running run-tests step. 01:56:23 INFO - Running pre-action listener: _resource_record_pre_action 01:56:23 INFO - Running main action method: run_tests 01:56:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:56:23 INFO - Python 2.7.3 01:56:23 INFO - Return code: 0 01:56:23 INFO - grabbing minidump binary from tooltool 01:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:56:23 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 0x10b6400>, '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 0x10b73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:56:23 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 01:56:23 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 01:56:23 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 01:56:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:56:24 INFO - Return code: 0 01:56:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:56:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:56:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:56:24 INFO - ENV: RUST_BACKTRACE is now 1 01:56:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:56:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:56:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 01:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 01:56:24 INFO - Using env: {'DISPLAY': ':0', 01:56:24 INFO - 'HOME': '/home/cltbld', 01:56:24 INFO - 'LANG': 'en_US.UTF-8', 01:56:24 INFO - 'LANGUAGE': 'en_US:en', 01:56:24 INFO - 'LOGNAME': 'cltbld', 01:56:24 INFO - 'MAIL': '/var/mail/cltbld', 01:56:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:56:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:56:24 INFO - 'MOZ_NO_REMOTE': '1', 01:56:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:56:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:56:24 INFO - 'NO_EM_RESTART': '1', 01:56:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:56:24 INFO - 'PWD': '/builds/slave/test', 01:56:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:56:24 INFO - 'RUST_BACKTRACE': '1', 01:56:24 INFO - 'SHELL': '/bin/bash', 01:56:24 INFO - 'SHLVL': '1', 01:56:24 INFO - 'TERM': 'linux', 01:56:24 INFO - 'TMOUT': '86400', 01:56:24 INFO - 'USER': 'cltbld', 01:56:24 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501', 01:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:56:24 INFO - '_': '/tools/buildbot/bin/python'} 01:56:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 01:56:24 INFO - mozversion application_buildid: 20170518080610 01:56:24 INFO - mozversion application_changeset: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 01:56:24 INFO - mozversion application_display_name: Firefox 01:56:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:56:24 INFO - mozversion application_name: Firefox 01:56:24 INFO - mozversion application_remotingname: firefox 01:56:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:56:24 INFO - mozversion application_vendor: Mozilla 01:56:24 INFO - mozversion application_version: 54.0 01:56:24 INFO - mozversion platform_buildid: 20170518080610 01:56:24 INFO - mozversion platform_changeset: a3bc13b60d416ad4a0dd42820ede5c0d69c288a0 01:56:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:56:24 INFO - mozversion platform_version: 54.0 01:56:24 INFO - using testdate: 1495097784 01:56:24 INFO - actual date: 1495097784 01:56:24 INFO - starting webserver on 'localhost:34505' 01:56:24 INFO - SUITE-START | Running 2 tests 01:56:24 INFO - TEST-START | dromaeo_css 01:56:24 INFO - operating with platform_type : linux_ 01:56:24 INFO - Initialising browser for dromaeo_css test... 01:56:24 INFO - TEST-INFO | started process 4116 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJp4SPo/profile http://localhost:34505/getInfo.html) 01:56:28 INFO - PROCESS | 4116 | __metrics Screen width/height:1600/1200 01:56:28 INFO - PROCESS | 4116 | colorDepth:24 01:56:28 INFO - PROCESS | 4116 | Browser inner width/height: 1024/697 01:56:28 INFO - PROCESS | 4116 | __metrics 01:56:29 INFO - PROCESS | 4116 | 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" 01:56:29 INFO - PROCESS | 4116 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:56:30 INFO - TEST-INFO | 4116: exit 0 01:56:30 INFO - Browser initialized. 01:56:30 INFO - Running cycle 1/1 for dromaeo_css test... 01:56:30 INFO - TEST-INFO | started process 4206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJp4SPo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:56:42 INFO - PROCESS | 4206 | RSS: Main: 141180928 01:56:42 INFO - PROCESS | 4206 | 01:59:43 INFO - PROCESS | 4206 | Cycle 1(1): loaded http://localhost:34505/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34505/tests/dromaeo/cssquery-ext.html) 01:59:44 INFO - PROCESS | 4206 | RSS: Main: 276496384 01:59:44 INFO - PROCESS | 4206 | 02:02:47 INFO - PROCESS | 4206 | Cycle 1(1): loaded http://localhost:34505/tests/dromaeo/cssquery-ext.html (next: http://localhost:34505/tests/dromaeo/cssquery-jquery.html) 02:02:47 INFO - PROCESS | 4206 | RSS: Main: 228691968 02:02:47 INFO - PROCESS | 4206 | 02:05:49 INFO - PROCESS | 4206 | Cycle 1(1): loaded http://localhost:34505/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34505/tests/dromaeo/cssquery-mootools.html) 02:05:50 INFO - PROCESS | 4206 | RSS: Main: 198668288 02:05:50 INFO - PROCESS | 4206 | 02:08:51 INFO - PROCESS | 4206 | Cycle 1(1): loaded http://localhost:34505/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34505/tests/dromaeo/cssquery-prototype.html) 02:08:52 INFO - PROCESS | 4206 | RSS: Main: 173780992 02:08:52 INFO - PROCESS | 4206 | 02:11:55 INFO - PROCESS | 4206 | Cycle 1(1): loaded http://localhost:34505/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34505/tests/dromaeo/cssquery-yui.html) 02:11:55 INFO - PROCESS | 4206 | RSS: Main: 174022656 02:11:55 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | Cycle 1(1): loaded http://localhost:34505/tests/dromaeo/cssquery-yui.html (next: http://localhost:34505/tests/dromaeo/cssquery-dojo.html) 02:14:58 INFO - PROCESS | 4206 | RSS: Main: 261541888 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | __start_tp_report 02:14:58 INFO - PROCESS | 4206 | _x_x_mozilla_page_load 02:14:58 INFO - PROCESS | 4206 | _x_x_mozilla_page_load_details 02:14:58 INFO - PROCESS | 4206 | |i|pagename|runs| 02:14:58 INFO - PROCESS | 4206 | |0;dojo.html;3273.49260864566;3376.0884561168486;3380.307037057403;3390.0507857799826;3412.061683037166;5658.349289831671;5778.855528611787;5781.335146458158;5792.884142317156;5797.565182611305;5693.089105743082;5779;5787.363390027093;5787.710614469281;5799.507041901433;5127.282180494733;5131.974340128294;5163.81926632568;5176.482351764812;5216;5032.270320803481;5204.713740744258;5234.057869583474;5236.607254455912;5261.76322065508;5063.84808455745;5095.3376061112;5109.642325037249;5217.765200565984;5228.529432351088;5202.06362854686;5231.215317702337;5236.21456781482;5236.293100431431;5240.973795131038;4998.950010499885;5088.109580823379;5131.7690703918415;5198.714070726109;5208.895822083575;5636.4927156556105;5659.60382773206;5683.062294721366;5696.287964004499;5704.11586204139;6136.7238474268315;6174.475169610589;6184.07238914162;6188.752449901998;6213.37866213375;6234.0337247726675;6255.499560035186;6260.060990851363;6267.811965641038;6273.529485288622;5635.943640563624;5678.403767604384;5743.253377060955;5773.566982476331;5777.335606405233;6302.243730752248;6307.96846015767;6320.367963203643;6325.240971083499;6329.9239129348125;6036.728347224385;6071.51427885768;6090.025595904634;6090.512758979359;6103.664298463538;5937.10943358491;6002.0996850472775;6088.512918966472;6113.083037544404;6193.4425901669065;6134.865219626364;6158.168647232566;6165.383461653799;6174.567780255339;6175.62946223228;5915.911261331084;5948.10778383246;5951.077582974559;6166.599171053867;6172.8765424691255;4614.100250451197;4637.188492013884;4665.533446655308;4697.412823397075;4709.411323584552;4133.04939863833;4204.831806727697;4216.177845320133;4225.3873188387515;4227.915441691149;5901.527877769769;5916.822495325147;5925.762969481259;6029.125776762433;6042.093685947143;6219.40915613016;6220.409061139185;6240.063990401385;6255.46828519581;6262.279837818709;5984.042553191555;5997.640141591519;5999;6005.579609427386;6039.758409663652;5107.846764597068;5152.098382782998;5202.97398513005;5262.473752624784;5315.946840531546;6497.902531462032;6507.707153178024;6507.934920650733;6511.479081673455;6516.348365163446;6169.660668663267;6184.6598437086395;6241.594296370721;6247.781327653511;6252.124702541557;6226.097215903578;6266.530010249158;6343.143675603735;6362.6818659067785;6487.870242595216;6110.4980065817745;6113.388661133851;6116.66358350295;6117.143599895929;6144.2319710036245;6186.1648677427975;6217.937820621736;6232.25212974446;6235.532335074797;6235.844103897439;5798.246227990334;5895.14520394549;5962.403759624003;6055.576109672281;6070.3929607038945;5961.642301461926;5961.791337303173;6053.334133245427;6060.575759696779;6085.2393450818645;5913.970430147821;6033.12519684652;6034.577579569388;6036.154938308729;6049.66726830011;5857.9121313179485;5874.177615133799;5898.233229680286;5920.023196172583;6070.423309785563;5830.329512105993;5848.94151058501;5849.590528662953;5861.472467477947;5876.118582212703 02:14:58 INFO - PROCESS | 4206 | |1;ext.html;769.8729709597917;849.7790574450643;865.8874346334976;919.5540163020934;928.0209379105041;31808;44577.33134002992;44826.32760508595;44877.32684009738;44883.10233795322;41776;42054.78972605133;42115;45759.084818303614;45789.54210457894;7752.883706744404;9150.81698366034;9265.814683706321;9267.397619154734;9279.95360023199;7739.961300193491;10463.110635595984;10672.573097076147;10685.358878467277;10686.893131068668;10770.515326810313;10771.084457821094;10804;10824.67525974222;10836;10762.730931726692;10769.084627806646;10790.51442685081;10791.40647264408;10800.027997480225;10547.78904421915;10669.573217071307;10678.092362149227;10686.679399618024;10692.518836652369;43924.12151756979;43976.120477590586;44109;44132.11735765299;44229.115417691464;4338.132373525276;4369.344598310247;4382.233109205877;4518.796654150538;4528.592426681582;30670.386592268253;31029.379412411625;31251.843740781376;31278.687213128032;32475.83762081198;4377.255866502702;4451.554844515555;4466.8436604719;4478.520262585533;4602.033572949685;238188;238454;238708;239841;239964;45575;45628.08743825105;45806.31290530645;45882.770586147526;45966.770166148955;48059.27911081337;48273;48365.274520882216;48380;48382.790430239525;15651.530454086396;16001.199940003185;16019.279132438834;16026.11856347912;16035.919820401057;12080.275183489019;12253.509859605516;13847.653808654662;13865.514706985408;13873.791893121612;13058;13117.868821311855;13240.867591324157;13275.535356262484;13399.397027133802;5496.4503549645515;5551.333839939152;5568.638038527482;5572.136318870582;5572.498475137257;1022.4172221833484;1029.9433531155858;1032.8140934631838;1033.5968972100886;1037.5590374090982;29363.119106426842;29375.85312073426;29459.705402946125;29713.851430742707;29998.700013000027;1100.4937728644913;1104.8342748587777;1123.43828085957;1127.5940661361903;1134.8581427321585;15199.772003419735;15905.602359941095;15906.284217210254;15969.840301596836;16169.757453638205;156489;156644;157442;157731;158209.20895395678;58900;58999.705001474715;59202.40797592055;59247.703761480916;59354.70322648445;1482.1181397068726;1498.4680438444414;1523.8933274670887;1531.5328824708347;1533.9233038348261;28086.157415277576;28940.27649308784;29023.709762902945;29245.122646320644;29637.110886673436;41925;42035.369469458594;42277.36583951182;42320.1535969285;42348.78825605852;337236;337944;338451;338494;338582;31223.843880780452;31347.216319592193;31679;31814.840925795223;32129;40460;40745.79627101846;40799;41309.173816524104;41610.79194604007;31739.682603174595;31907.68092319047;31930.20174495656;32029.839850800596;32686.50970235496;31974.200644984063;32105.839470802497;32106;32192.517112243804;34354;36378;36678;37141.81429092837;37298.25403491969;37459.43810842894;31030.53454198234;31262.06213813498;31535.684643153276;31576;31965.561549730784;24738.134165304127;25820.870895645403;25910.87044564765;26068.478630427664;26245.868770656787 02:14:58 INFO - PROCESS | 4206 | |2;jquery.html;441.76365644380263;463.28885161277424;467.07424100435827;468.5033864104049;484.02227500449095;20216.595668086647;20539.897300513512;21906.342809715694;22108.447288817788;22234.221802236923;21473;21816.127354905824;21836.89081554594;21943.67084493734;23086.769132308673;6736.225334086569;6772.661366931658;6911.48163887708;6997.475189360793;7007.369336759691;6776.932230677704;6869.450443964496;6871.690773915186;6877.587344759306;6878.621675807829;6866.382025617693;7308.707651693952;7549.811254718648;7566.697332106709;7581.658825352844;7477.626118694098;7670.041244844395;7686.461947663665;7701.22987701231;7724.536527808322;7291.562506249589;7468.551886886804;7470.215627359104;7475.813104672372;7476.252374762535;25801.22596322113;25914.87042564794;26179.476410471685;26374.73625263742;26474.47051058968;3240.4977228529615;3264.5592844965863;3271.247613049012;3277.4428347180797;3359.7480188985924;15442.845571544252;16268.51194464168;16341.673166536722;16883.493495195165;16966.151692415453;2235.8434909556336;2267.841251112423;2336.123953517431;2372.454335502827;2375.2161786610377;116558;118774;118964;119663;122068;17408.129593520272;18235.817641823676;18896.52758681044;18983.62032759337;20039.298624548363;32375.838120809716;32787.83606081954;32817.3436531268;32829.507557386656;32940;10592.735181620521;10717.732056698645;10730.336797796353;10777.838332424863;11056.944715276533;9134;9134.680286190085;9145.314101442418;9371.297152713574;9372.640967059739;9114.40756350835;9175.541222938826;9261.629534818667;9477.715668529956;9496.572654230577;3752.4746535485056;3842.2507611015585;3889.5138107736534;3935.5080614923136;4105.486814148231;989.6931951095039;995.9053889880449;1005.2762011351815;1005.9748506287254;1019.1591936652144;20633.587328253652;21608.35174944754;21779.128834846746;21809.454763630718;22275.665865012037;984.1388784813288;991.6430085169334;1005.6631028605499;1012.8480727890875;1016.8322226832638;11448.3703396314;11989.340586267665;12066.818997715041;12080.335581543099;12180.75638487243;647994;652489;677541;693740;696063;117954.4102279483;118524.40737796428;119199;120524;121443;1301.9609411717663;1302.9348532573333;1315.145155648836;1330.4146175682863;1333.3933060457468;18064.548386290448;18484.72272915934;18639.90680046591;18705.625887482445;18821.6235675283;50636.74681626568;51653.48346516487;51679;52280.73859630677;53276.46723532715;64609.35390646033;70313.58571492729;72318;76265;76839.61580192063;12550.937245313715;13135.868641313464;13137.474501020235;13223.471061157446;13263.86736132665;14340.713185736435;14458;14499.202543860189;14521.419143234152;14629.707405852036;12968.481260749464;13261.336933153496;13304.800427993781;13312.933435332761;13378.464861405435;11942.641720748392;12227;12321.07591930591;12547.121701480808;12672.429740661508;13235.735285294433;13301.068925175145;13316.667083322995;13481.865181348454;13831.861681383058;13045.478180872658;13066.869331306565;13129.60611181666;13183.472661093449;13245.271510066654;12973.610791675887;12993.155444896052;13054.21674699483;13122.60632181036;13392.531261405804 02:14:58 INFO - PROCESS | 4206 | |3;mootools.html;1421.7014426970336;1560.23548461254;1613;1625.1143126995782;1632.9265183066768;9362.391444556099;11290.09679225662;11562.710932226699;11576.826347604772;11577.131715121368;11646.592369267077;11709.882901170986;11730.648080557576;11733.765324693522;11736.53053877846;2038.2152871144642;2081.6149012432675;2350.8707021113833;2390.713114426271;2393.9880300598475;2416.3113512648897;2458.754124587536;2470.8641024743633;2477.8513289202706;2478.3556275368355;2458.3485376375274;2463.4334103156284;2470.888810003545;2478.8388754730977;2484.1305543059966;2441.170002199243;2483.279848843842;2487.8009759219217;2490.4272017436;2490.962635560477;2368.740553532053;2383.784270022301;2387.3434805428474;2391.4380120671626;2392.509535545209;11648.359340236284;11852.762944741144;11869;11879.584214552535;11881.34652594107;5922.940770592282;6067.484263837579;6071.696415179224;6081.148639190517;6085.878282434326;3512.5609298837644;3595.1551385424405;3601.711863050976;3642.2351306225723;3651.3062518121474;1389.2984043058293;1401.7126489069717;1404.7190561887664;1453.2588379926208;1461.7807328900644;16457.341706331732;17035.744463833053;17409.56476088108;17416.564585885328;17488.12559372039;4623.514530974267;4685.086408150446;4806.182948898696;4809.302651115569;4844.927326090112;12926.547570834975;12991.155574887602;13006.934965325112;13016.28410437435;13017.21896686202;6053.182820319289;6060.818175454743;6067.878642427215;6068.089786531968;6078.54410919174;5471.863203419946;5484.2047903053835;5486.588505861996;5556.277683901067;5636.126400407943;4095.692823038284;4103.97948010258;4165.791710414467;4179.644730197906;4208.116295577963;1082.9620963266404;1089.2974031749477;1103.6082190822142;1119.5130118398522;1121.091915548392;1086.9184811139196;1095.6055819904827;1107.9279846809932;1108.1633366808007;1117.1118960426481;7345.88981165283;7378.852422951618;7426.405887528985;7565.886511702329;7596.316331530189;1015.1675625986768;1068.1348108032519;1072.3726619927274;1073.1790180511914;1093.9398333091756;4793.664443488992;4823.5658790709;4856.150173719656;4885.022995400934;5009.924851127236;13626.250556219504;13980.930095349458;14153.716925661634;14732.337044832793;14740.484083057041;14422.711545769025;14464.059836110619;14466.566003019927;14474.927625361806;14480;10442.947785261025;10456.843147352796;10634.41510716918;10690.572377104983;10738;7084;7281.1262648482525;7342.192358840416;7483.775486735407;7613.352865006315;12776.10567260283;13160.47358105665;13271.336433178496;13431;13482.93258533701;13160.40778165004;13398.637561533256;13412.865871341552;13633.659158521117;13768.24274664903;9628.9037109628;9706.223502119954;9841.901580984098;10059.597616095567;10093.041161089684;11223;11267.098632109575;11321.264117832314;11336.263142895685;11436.656900293005;9333.346665733605;9414.15272625467;9494.762630934281;9572.521373931137;9759.804803904024;8892.822143557221;8955.104489551253;8972.057933916845;9099;9346.11211934865;8694.130586941255;8709.303255739653;8724.738257852274;8767.079456657215;8790.120987901415;6363.87272254543;6450.387213214737;6489.253735820441;6495.772647957488;6533.281339052605;6261.655608941552;6320.336364681642;6372.107904893412;6428.4857211423905;6562.475001999924 02:14:58 INFO - PROCESS | 4206 | |4;prototype.html;400.80360623294587;405.728162131372;412.7275997841425;417.94995546842745;429.30638630602266;5266.894662106755;5322.733863306834;5322.946770532293;5350.3515759699;5361.946380536194;4838.34682317887;5248.186531087687;5282.973585132069;5289.471052894709;5327.547158491533;4475.104979004196;4479.216137175998;4504;4512.819487220508;4531.252343363341;4480.708753930984;4492.415985921825;4493.932591011137;4512.5261847506035;4566.40636717228;4153.210889930903;4322.1787860306595;4379.167958087969;4401.195509840966;4536.183486972343;4363.563643635611;4381.255186618282;4388.71473354231;4393.209222339978;4393.384926110363;4148.253314403406;4148.91702165955;4162.250794856925;4165.291900376943;4178.844972046116;4923.630727695438;5127.692338459704;5170.1986192140275;5192.428832828424;5248.501392367757;5066.569341605931;5067.493250674903;5069.569086627641;5085;5092.567131793802;5464.617476776588;5527.53015993641;5543.501084902378;5573.637713548647;5583.231318859794;4331.956680433187;4537.977310113428;4572.154151481986;4598.494165641777;4697.154512187805;5340.49265319794;5504.7522861470925;5508.43857606211;5738.8278351649515;5770.711464426762;4488.5287044859815;4515.006698526385;4522.638188944877;4525.547445255448;4535.387722657399;4727.527247275245;4790.928136077961;4792.723456358202;4798.49615790344;4825.95174048255;5045.318881950963;5057.721825299644;5077.746112694351;5122.615803814729;5126.974365128151;5417.756200970965;5419.810306639249;5439.592030597721;5464.398916119224;5511.53151982078;1642.82750311218;1655.5033489952966;1655.991720041392;1668.6579251253463;1675.1373042882924;1241.2089237114305;1272.867148238069;1304.1979182802509;1306.882380585752;1320.4388135042761;5262.605304602093;5456.345238571317;5482.369527504308;5482.821193443349;5509.090999985037;5622.313306008181;5683.886322273532;5700.201971723962;5757.971210144006;5768.682722450306;5459.69971651555;5467.332463598561;5487.4238204987905;5564.527015203672;5583.469570390806;2923.8391888445913;2983.8358890261243;3003.2130882125102;3016.5324374721954;3021.957424688479;5732.742026608813;5824.3302020267365;5868.713520428573;5908.586398952118;5938.257717785277;5435.374931882884;5753.482186603226;5797.47822695951;5805.680687562224;5842.685395785999;5844.649321040752;5849.853753656193;5855.677937713382;5871.735771890189;5884.205632239678;5595.384507704231;5634.943650563606;5651.463111004448;5702.45826646468;5707.771689132388;5216.895662086661;5564.721763911869;5582.8325150245555;5583.74873130702;5618.775248989995;5610.625396777652;5708.743106560131;5752.223449834219;5827.970860145672;5874.971879920911;4989.376327959005;5050.267711181931;5069.493050694901;5088.236764485357;5113.727214582745;5432.728363581884;5641.5204707600315;5663.377028526941;5699.207388379747;5734.971325143514;5490.560755139659;5498.3127109111365;5503.559715222693;5511;5517.36550296705;5506.5870059744875;5511.283533140666;5539.556835453234;5550.944490555043;5565.16522521625;5268.894622107459;5471.9726401369335;5484.91772623403;5492.395836458067;5521.2270282159725;5532.557395408277;5534.584906132137;5535.612507124623;5558.471945165203;5570.3315602128005;5127.897561999563;5406.648567843077;5432.592555558428;5455.809046683347;5467.781288748405 02:14:58 INFO - PROCESS | 4206 | |5;yui.html;901.9007909129995;933.7292185266273;940.135075730328;944.7071407863558;972.8297547929113;16866.91566542167;18104.09479526025;18132.18405171767;18166.545836354097;18201.453956381312;17519;17712.822871771277;17748;17748.822511774877;17751.20119594618;699.8740226759181;722.5303552690767;892.3708785306344;903.5211337990859;925.745420009496;901.7384958362068;907.4872696926249;908.9819402269468;909.0863681999581;911.293747345809;971.4996776660025;971.5385192033763;972.9902700972971;973.0755782007086;973.4208146153063;960.9951950240275;962.7159987803615;962.9133377995979;962.9518524073768;965.6427121964883;711.5943911970179;711.6299524247362;712.0245263988369;712.0351923144169;712.2450202785064;18516.722249166272;18737.81262187388;18766.812331876645;18942.526436839053;18979;16276.186190690418;16392.67214655712;16438.342466301332;16500.587485312964;16939.745903811454;13466.394012269471;13573.049886507948;13875.167489950636;13980.091294065955;14002.85997140036;4087.223427548786;4130.359794231899;4151.647109995654;4204.66362690984;4211.97894010531;79981.60009199916;81728;81810.59094704548;81972.18027819645;82537.58731206306;12304.692382690504;12484.625461236177;12508.249505029728;12605.621831345074;12618.432170552347;20766.792332076788;20857.791422085586;20971.685424718642;21056.894715526632;21154.894225528773;8981.550922453851;9162.86255706165;9216.262698984065;9328.673496427593;9334.813303733887;5283.656562323436;5292.629515933848;5318.414974352818;5375.381831089397;5463.043967305785;3713.294474049922;3756.117312431631;3767.171222331083;3791.696664266852;3791.696664266852;646.2374398210063;647.0423772816258;650.9121268628769;655.3151956205777;657.375493281375;850.8936382952131;862.5514732339274;874.9956250218836;897.9955100224588;899.9685011024583;11874.703132421586;12077.879221207675;12210.26738395699;12221.75556488883;12586.748265034648;859.2824991132475;862.1938487514194;880.000398190227;891.9598618062073;899.9190072893471;5432.864178395493;5527.944720552823;5573.721313934287;5660.26416565852;5724.112762521816;79053.60473197597;79584.6020769904;80500;81704.59147704224;85098.72351914726;69865;70116.6494167526;71003.64498177476;71478.64260678663;73544;67225.6638716813;67663.3233667667;67760.66119669468;68299.65850170718;68533;11678.24091434054;12354.567590134302;12936.870631293943;12956.740865182832;13145.408456619667;41562.58437415708;42769.78615106905;43267.35098973455;43603.781981089895;44902;55676.44323556712;58441;58863.41136588579;59152.40847591469;59406;13565.186088834702;13866.611013386859;13886.236257005557;13893.374798133906;14181.858181418467;15692.686146276783;15746.897717159689;15766.9211653941;15975.28111234974;16135.193240337701;16089.67820643604;16222;16355.2640131192;16673.583160421087;17034;15571.844281557493;15728.92135539315;15921;16117.677646446771;16384.75422868634;15970.520884373487;16036.278367473726;16046.919765401099;16169.919150404172;16390.344386224893;10407.375557466576;10618.8407173894;10747.623833165802;11090.889091108986;11464.885351146715;12572.182808117443;12605.180663256859;13880.167189968633;13895;13925.79111313311 02:14:58 INFO - PROCESS | 4206 | __end_tp_report 02:14:58 INFO - PROCESS | 4206 | __start_cc_report 02:14:58 INFO - PROCESS | 4206 | _x_x_mozilla_cycle_collect,1321 02:14:58 INFO - PROCESS | 4206 | __end_cc_report 02:14:58 INFO - PROCESS | 4206 | __startTimestamp1495098898385__endTimestamp 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | ------- Summary: start ------- 02:14:58 INFO - PROCESS | 4206 | Number of tests: 6 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | [#0] dojo.html Cycles:165 Average:5718.28 Median:5948.11 stddev:667.23 (11.2%) stddev-sans-first:641.11 02:14:58 INFO - PROCESS | 4206 | Values: 3273.5 3376.1 3380.3 3390.1 3412.1 5658.3 5778.9 5781.3 5792.9 5797.6 5693.1 5779.0 5787.4 5787.7 5799.5 5127.3 5132.0 5163.8 5176.5 5216.0 5032.3 5204.7 5234.1 5236.6 5261.8 5063.8 5095.3 5109.6 5217.8 5228.5 5202.1 5231.2 5236.2 5236.3 5241.0 4999.0 5088.1 5131.8 5198.7 5208.9 5636.5 5659.6 5683.1 5696.3 5704.1 6136.7 6174.5 6184.1 6188.8 6213.4 6234.0 6255.5 6260.1 6267.8 6273.5 5635.9 5678.4 5743.3 5773.6 5777.3 6302.2 6308.0 6320.4 6325.2 6329.9 6036.7 6071.5 6090.0 6090.5 6103.7 5937.1 6002.1 6088.5 6113.1 6193.4 6134.9 6158.2 6165.4 6174.6 6175.6 5915.9 5948.1 5951.1 6166.6 6172.9 4614.1 4637.2 4665.5 4697.4 4709.4 4133.0 4204.8 4216.2 4225.4 4227.9 5901.5 5916.8 5925.8 6029.1 6042.1 6219.4 6220.4 6240.1 6255.5 6262.3 5984.0 5997.6 5999.0 6005.6 6039.8 5107.8 5152.1 5203.0 5262.5 5315.9 6497.9 6507.7 6507.9 6511.5 6516.3 6169.7 6184.7 6241.6 6247.8 6252.1 6226.1 6266.5 6343.1 6362.7 6487.9 6110.5 6113.4 6116.7 6117.1 6144.2 6186.2 6217.9 6232.3 6235.5 6235.8 5798.2 5895.1 5962.4 6055.6 6070.4 5961.6 5961.8 6053.3 6060.6 6085.2 5914.0 6033.1 6034.6 6036.2 6049.7 5857.9 5874.2 5898.2 5920.0 6070.4 5830.3 5848.9 5849.6 5861.5 5876.1 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | [#1] ext.html Cycles:180 Average:41927.40 Median:31773.84 stddev:66901.79 (210.6%) stddev-sans-first:67018.10 02:14:58 INFO - PROCESS | 4206 | Values: 769.9 849.8 865.9 919.6 928.0 31808.0 44577.3 44826.3 44877.3 44883.1 41776.0 42054.8 42115.0 45759.1 45789.5 7752.9 9150.8 9265.8 9267.4 9280.0 7740.0 10463.1 10672.6 10685.4 10686.9 10770.5 10771.1 10804.0 10824.7 10836.0 10762.7 10769.1 10790.5 10791.4 10800.0 10547.8 10669.6 10678.1 10686.7 10692.5 43924.1 43976.1 44109.0 44132.1 44229.1 4338.1 4369.3 4382.2 4518.8 4528.6 30670.4 31029.4 31251.8 31278.7 32475.8 4377.3 4451.6 4466.8 4478.5 4602.0 238188.0 238454.0 238708.0 239841.0 239964.0 45575.0 45628.1 45806.3 45882.8 45966.8 48059.3 48273.0 48365.3 48380.0 48382.8 15651.5 16001.2 16019.3 16026.1 16035.9 12080.3 12253.5 13847.7 13865.5 13873.8 13058.0 13117.9 13240.9 13275.5 13399.4 5496.5 5551.3 5568.6 5572.1 5572.5 1022.4 1029.9 1032.8 1033.6 1037.6 29363.1 29375.9 29459.7 29713.9 29998.7 1100.5 1104.8 1123.4 1127.6 1134.9 15199.8 15905.6 15906.3 15969.8 16169.8 156489.0 156644.0 157442.0 157731.0 158209.2 58900.0 58999.7 59202.4 59247.7 59354.7 1482.1 1498.5 1523.9 1531.5 1533.9 28086.2 28940.3 29023.7 29245.1 29637.1 41925.0 42035.4 42277.4 42320.2 42348.8 337236.0 337944.0 338451.0 338494.0 338582.0 31223.8 31347.2 31679.0 31814.8 32129.0 40460.0 40745.8 40799.0 41309.2 41610.8 31739.7 31907.7 31930.2 32029.8 32686.5 31974.2 32105.8 32106.0 32192.5 34354.0 36378.0 36678.0 37141.8 37298.3 37459.4 31030.5 31262.1 31535.7 31576.0 31965.6 24738.1 25820.9 25910.9 26068.5 26245.9 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | [#2] jquery.html Cycles:180 Average:38817.72 Median:21925.01 stddev:111158.88 (507.0%) stddev-sans-first:111433.36 02:14:58 INFO - PROCESS | 4206 | Values: 441.8 463.3 467.1 468.5 484.0 20216.6 20539.9 21906.3 22108.4 22234.2 21473.0 21816.1 21836.9 21943.7 23086.8 6736.2 6772.7 6911.5 6997.5 7007.4 6776.9 6869.5 6871.7 6877.6 6878.6 6866.4 7308.7 7549.8 7566.7 7581.7 7477.6 7670.0 7686.5 7701.2 7724.5 7291.6 7468.6 7470.2 7475.8 7476.3 25801.2 25914.9 26179.5 26374.7 26474.5 3240.5 3264.6 3271.2 3277.4 3359.7 15442.8 16268.5 16341.7 16883.5 16966.2 2235.8 2267.8 2336.1 2372.5 2375.2 116558.0 118774.0 118964.0 119663.0 122068.0 17408.1 18235.8 18896.5 18983.6 20039.3 32375.8 32787.8 32817.3 32829.5 32940.0 10592.7 10717.7 10730.3 10777.8 11056.9 9134.0 9134.7 9145.3 9371.3 9372.6 9114.4 9175.5 9261.6 9477.7 9496.6 3752.5 3842.3 3889.5 3935.5 4105.5 989.7 995.9 1005.3 1006.0 1019.2 20633.6 21608.4 21779.1 21809.5 22275.7 984.1 991.6 1005.7 1012.8 1016.8 11448.4 11989.3 12066.8 12080.3 12180.8 647994.0 652489.0 677541.0 693740.0 696063.0 117954.4 118524.4 119199.0 120524.0 121443.0 1302.0 1302.9 1315.1 1330.4 1333.4 18064.5 18484.7 18639.9 18705.6 18821.6 50636.7 51653.5 51679.0 52280.7 53276.5 64609.4 70313.6 72318.0 76265.0 76839.6 12550.9 13135.9 13137.5 13223.5 13263.9 14340.7 14458.0 14499.2 14521.4 14629.7 12968.5 13261.3 13304.8 13312.9 13378.5 11942.6 12227.0 12321.1 12547.1 12672.4 13235.7 13301.1 13316.7 13481.9 13831.9 13045.5 13066.9 13129.6 13183.5 13245.3 12973.6 12993.2 13054.2 13122.6 13392.5 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | [#3] mootools.html Cycles:180 Average:7229.41 Median:2405.15 stddev:4570.70 (190.0%) stddev-sans-first:4562.69 02:14:58 INFO - PROCESS | 4206 | Values: 1421.7 1560.2 1613.0 1625.1 1632.9 9362.4 11290.1 11562.7 11576.8 11577.1 11646.6 11709.9 11730.6 11733.8 11736.5 2038.2 2081.6 2350.9 2390.7 2394.0 2416.3 2458.8 2470.9 2477.9 2478.4 2458.3 2463.4 2470.9 2478.8 2484.1 2441.2 2483.3 2487.8 2490.4 2491.0 2368.7 2383.8 2387.3 2391.4 2392.5 11648.4 11852.8 11869.0 11879.6 11881.3 5922.9 6067.5 6071.7 6081.1 6085.9 3512.6 3595.2 3601.7 3642.2 3651.3 1389.3 1401.7 1404.7 1453.3 1461.8 16457.3 17035.7 17409.6 17416.6 17488.1 4623.5 4685.1 4806.2 4809.3 4844.9 12926.5 12991.2 13006.9 13016.3 13017.2 6053.2 6060.8 6067.9 6068.1 6078.5 5471.9 5484.2 5486.6 5556.3 5636.1 4095.7 4104.0 4165.8 4179.6 4208.1 1083.0 1089.3 1103.6 1119.5 1121.1 1086.9 1095.6 1107.9 1108.2 1117.1 7345.9 7378.9 7426.4 7565.9 7596.3 1015.2 1068.1 1072.4 1073.2 1093.9 4793.7 4823.6 4856.2 4885.0 5009.9 13626.3 13980.9 14153.7 14732.3 14740.5 14422.7 14464.1 14466.6 14474.9 14480.0 10442.9 10456.8 10634.4 10690.6 10738.0 7084.0 7281.1 7342.2 7483.8 7613.4 12776.1 13160.5 13271.3 13431.0 13482.9 13160.4 13398.6 13412.9 13633.7 13768.2 9628.9 9706.2 9841.9 10059.6 10093.0 11223.0 11267.1 11321.3 11336.3 11436.7 9333.3 9414.2 9494.8 9572.5 9759.8 8892.8 8955.1 8972.1 9099.0 9346.1 8694.1 8709.3 8724.7 8767.1 8790.1 6363.9 6450.4 6489.3 6495.8 6533.3 6261.7 6320.3 6372.1 6428.5 6562.5 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | [#4] prototype.html Cycles:180 Average:4823.07 Median:5325.25 stddev:1276.98 (24.0%) stddev-sans-first:1236.68 02:14:58 INFO - PROCESS | 4206 | Values: 400.8 405.7 412.7 417.9 429.3 5266.9 5322.7 5322.9 5350.4 5361.9 4838.3 5248.2 5283.0 5289.5 5327.5 4475.1 4479.2 4504.0 4512.8 4531.3 4480.7 4492.4 4493.9 4512.5 4566.4 4153.2 4322.2 4379.2 4401.2 4536.2 4363.6 4381.3 4388.7 4393.2 4393.4 4148.3 4148.9 4162.3 4165.3 4178.8 4923.6 5127.7 5170.2 5192.4 5248.5 5066.6 5067.5 5069.6 5085.0 5092.6 5464.6 5527.5 5543.5 5573.6 5583.2 4332.0 4538.0 4572.2 4598.5 4697.2 5340.5 5504.8 5508.4 5738.8 5770.7 4488.5 4515.0 4522.6 4525.5 4535.4 4727.5 4790.9 4792.7 4798.5 4826.0 5045.3 5057.7 5077.7 5122.6 5127.0 5417.8 5419.8 5439.6 5464.4 5511.5 1642.8 1655.5 1656.0 1668.7 1675.1 1241.2 1272.9 1304.2 1306.9 1320.4 5262.6 5456.3 5482.4 5482.8 5509.1 5622.3 5683.9 5700.2 5758.0 5768.7 5459.7 5467.3 5487.4 5564.5 5583.5 2923.8 2983.8 3003.2 3016.5 3022.0 5732.7 5824.3 5868.7 5908.6 5938.3 5435.4 5753.5 5797.5 5805.7 5842.7 5844.6 5849.9 5855.7 5871.7 5884.2 5595.4 5634.9 5651.5 5702.5 5707.8 5216.9 5564.7 5582.8 5583.7 5618.8 5610.6 5708.7 5752.2 5828.0 5875.0 4989.4 5050.3 5069.5 5088.2 5113.7 5432.7 5641.5 5663.4 5699.2 5735.0 5490.6 5498.3 5503.6 5511.0 5517.4 5506.6 5511.3 5539.6 5550.9 5565.2 5268.9 5472.0 5484.9 5492.4 5521.2 5532.6 5534.6 5535.6 5558.5 5570.3 5127.9 5406.6 5432.6 5455.8 5467.8 02:14:58 INFO - PROCESS | 4206 | 02:14:58 INFO - PROCESS | 4206 | [#5] yui.html Cycles:180 Average:19018.78 Median:23490.88 stddev:23188.99 (98.7%) stddev-sans-first:23214.14 02:14:58 INFO - PROCESS | 4206 | Values: 901.9 933.7 940.1 944.7 972.8 16866.9 18104.1 18132.2 18166.5 18201.5 17519.0 17712.8 17748.0 17748.8 17751.2 699.9 722.5 892.4 903.5 925.7 901.7 907.5 909.0 909.1 911.3 971.5 971.5 973.0 973.1 973.4 961.0 962.7 962.9 963.0 965.6 711.6 711.6 712.0 712.0 712.2 18516.7 18737.8 18766.8 18942.5 18979.0 16276.2 16392.7 16438.3 16500.6 16939.7 13466.4 13573.0 13875.2 13980.1 14002.9 4087.2 4130.4 4151.6 4204.7 4212.0 79981.6 81728.0 81810.6 81972.2 82537.6 12304.7 12484.6 12508.2 12605.6 12618.4 20766.8 20857.8 20971.7 21056.9 21154.9 8981.6 9162.9 9216.3 9328.7 9334.8 5283.7 5292.6 5318.4 5375.4 5463.0 3713.3 3756.1 3767.2 3791.7 3791.7 646.2 647.0 650.9 655.3 657.4 850.9 862.6 875.0 898.0 900.0 11874.7 12077.9 12210.3 12221.8 12586.7 859.3 862.2 880.0 892.0 899.9 5432.9 5527.9 5573.7 5660.3 5724.1 79053.6 79584.6 80500.0 81704.6 85098.7 69865.0 70116.6 71003.6 71478.6 73544.0 67225.7 67663.3 67760.7 68299.7 68533.0 11678.2 12354.6 12936.9 12956.7 13145.4 41562.6 42769.8 43267.4 43603.8 44902.0 55676.4 58441.0 58863.4 59152.4 59406.0 13565.2 13866.6 13886.2 13893.4 14181.9 15692.7 15746.9 15766.9 15975.3 16135.2 16089.7 16222.0 16355.3 16673.6 17034.0 15571.8 15728.9 15921.0 16117.7 16384.8 15970.5 16036.3 16046.9 16169.9 16390.3 10407.4 10618.8 10747.6 11090.9 11464.9 12572.2 12605.2 13880.2 13895.0 13925.8 02:14:58 INFO - PROCESS | 4206 | -------- Summary: end -------- 02:14:58 INFO - PROCESS | 4206 | 02:14:59 INFO - TEST-INFO | 4206: exit 0 02:14:59 INFO - TEST-OK | dromaeo_css | took 1114910ms 02:14:59 INFO - TEST-START | kraken 02:14:59 INFO - operating with platform_type : linux_ 02:14:59 INFO - Initialising browser for kraken test... 02:14:59 INFO - TEST-INFO | started process 4516 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYgazBk/profile http://localhost:34505/getInfo.html) 02:15:03 INFO - PROCESS | 4516 | __metrics Screen width/height:1600/1200 02:15:03 INFO - PROCESS | 4516 | colorDepth:24 02:15:03 INFO - PROCESS | 4516 | Browser inner width/height: 1024/697 02:15:03 INFO - PROCESS | 4516 | __metrics 02:15:04 INFO - PROCESS | 4516 | 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" 02:15:04 INFO - PROCESS | 4516 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:15:05 INFO - TEST-INFO | 4516: exit 0 02:15:05 INFO - Browser initialized. 02:15:05 INFO - Running cycle 1/1 for kraken test... 02:15:05 INFO - TEST-INFO | started process 4609 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYgazBk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:15:16 INFO - PROCESS | 4609 | RSS: Main: 141627392 02:15:16 INFO - PROCESS | 4609 | 02:15:19 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34505/tests/kraken/driver.html?testName=audio-beat-detection) 02:15:20 INFO - PROCESS | 4609 | RSS: Main: 183857152 02:15:20 INFO - PROCESS | 4609 | 02:15:22 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34505/tests/kraken/driver.html?testName=audio-dft) 02:15:23 INFO - PROCESS | 4609 | RSS: Main: 173391872 02:15:23 INFO - PROCESS | 4609 | 02:15:26 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34505/tests/kraken/driver.html?testName=audio-fft) 02:15:26 INFO - PROCESS | 4609 | RSS: Main: 265371648 02:15:26 INFO - PROCESS | 4609 | 02:15:28 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34505/tests/kraken/driver.html?testName=audio-oscillator) 02:15:29 INFO - PROCESS | 4609 | RSS: Main: 171040768 02:15:29 INFO - PROCESS | 4609 | 02:15:31 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34505/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:15:31 INFO - PROCESS | 4609 | RSS: Main: 173797376 02:15:31 INFO - PROCESS | 4609 | 02:15:34 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34505/tests/kraken/driver.html?testName=imaging-darkroom) 02:15:35 INFO - PROCESS | 4609 | RSS: Main: 212336640 02:15:35 INFO - PROCESS | 4609 | 02:15:38 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34505/tests/kraken/driver.html?testName=imaging-desaturate) 02:15:39 INFO - PROCESS | 4609 | RSS: Main: 212500480 02:15:39 INFO - PROCESS | 4609 | 02:15:42 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34505/tests/kraken/driver.html?testName=json-parse-financial) 02:15:42 INFO - PROCESS | 4609 | RSS: Main: 211701760 02:15:42 INFO - PROCESS | 4609 | 02:15:44 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34505/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:15:45 INFO - PROCESS | 4609 | RSS: Main: 173518848 02:15:45 INFO - PROCESS | 4609 | 02:15:46 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:15:47 INFO - PROCESS | 4609 | RSS: Main: 180477952 02:15:47 INFO - PROCESS | 4609 | 02:15:49 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:15:49 INFO - PROCESS | 4609 | RSS: Main: 190255104 02:15:49 INFO - PROCESS | 4609 | 02:15:52 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:15:53 INFO - PROCESS | 4609 | RSS: Main: 199360512 02:15:53 INFO - PROCESS | 4609 | 02:15:55 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:15:56 INFO - PROCESS | 4609 | RSS: Main: 195555328 02:15:56 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | Cycle 1(1): loaded http://localhost:34505/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34505/tests/kraken/driver.html?testName=ai-astar) 02:15:58 INFO - PROCESS | 4609 | RSS: Main: 246255616 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | __start_tp_report 02:15:58 INFO - PROCESS | 4609 | _x_x_mozilla_page_load 02:15:58 INFO - PROCESS | 4609 | _x_x_mozilla_page_load_details 02:15:58 INFO - PROCESS | 4609 | |i|pagename|runs| 02:15:58 INFO - PROCESS | 4609 | |0;ai-astar;103;113;112;112;112;113;111;112;113;111 02:15:58 INFO - PROCESS | 4609 | |1;audio-beat-detection;143;115;115;149;118;117;116;121;140;115 02:15:58 INFO - PROCESS | 4609 | |2;audio-dft;161;161;162;159;160;160;161;160;160;160 02:15:58 INFO - PROCESS | 4609 | |3;audio-fft;84;76;75;110;76;77;77;75;95;75 02:15:58 INFO - PROCESS | 4609 | |4;audio-oscillator;82;82;107;81;83;82;99;82;82;103 02:15:58 INFO - PROCESS | 4609 | |5;imaging-gaussian-blur;113;113;112;114;113;113;114;113;113;113 02:15:58 INFO - PROCESS | 4609 | |6;imaging-darkroom;147;146;146;146;147;146;147;146;147;145 02:15:58 INFO - PROCESS | 4609 | |7;imaging-desaturate;93;94;93;93;95;95;94;94;93;93 02:15:58 INFO - PROCESS | 4609 | |8;json-parse-financial;52;53;53;53;53;54;55;54;53;54 02:15:58 INFO - PROCESS | 4609 | |9;json-stringify-tinderbox;52;52;53;52;53;52;51;51;52;52 02:15:58 INFO - PROCESS | 4609 | |10;stanford-crypto-aes;80;76;84;76;81;79;81;80;75;80 02:15:58 INFO - PROCESS | 4609 | |11;stanford-crypto-ccm;122;121;129;120;125;118;118;131;166;128 02:15:58 INFO - PROCESS | 4609 | |12;stanford-crypto-pbkdf2;159;160;161;161;161;154;156;160;161;159 02:15:58 INFO - PROCESS | 4609 | |13;stanford-crypto-sha256-iterative;59;59;59;60;59;61;59;58;58;57 02:15:58 INFO - PROCESS | 4609 | __end_tp_report 02:15:58 INFO - PROCESS | 4609 | __start_cc_report 02:15:58 INFO - PROCESS | 4609 | _x_x_mozilla_cycle_collect,1635 02:15:58 INFO - PROCESS | 4609 | __end_cc_report 02:15:58 INFO - PROCESS | 4609 | __startTimestamp1495098958251__endTimestamp 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | ------- Summary: start ------- 02:15:58 INFO - PROCESS | 4609 | Number of tests: 14 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.97 (2.7%) stddev-sans-first:0.78 02:15:58 INFO - PROCESS | 4609 | Values: 103.0 113.0 112.0 112.0 112.0 113.0 111.0 112.0 113.0 111.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:119.50 stddev:13.48 (11.3%) stddev-sans-first:12.60 02:15:58 INFO - PROCESS | 4609 | Values: 143.0 115.0 115.0 149.0 118.0 117.0 116.0 121.0 140.0 115.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 02:15:58 INFO - PROCESS | 4609 | Values: 161.0 161.0 162.0 159.0 160.0 160.0 161.0 160.0 160.0 160.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.50 stddev:11.67 (15.3%) stddev-sans-first:12.36 02:15:58 INFO - PROCESS | 4609 | Values: 84.0 76.0 75.0 110.0 76.0 77.0 77.0 75.0 95.0 75.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:82.00 stddev:10.33 (12.6%) stddev-sans-first:10.70 02:15:58 INFO - PROCESS | 4609 | Values: 82.0 82.0 107.0 81.0 83.0 82.0 99.0 82.0 82.0 103.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 02:15:58 INFO - PROCESS | 4609 | Values: 113.0 113.0 112.0 114.0 113.0 113.0 114.0 113.0 113.0 113.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 02:15:58 INFO - PROCESS | 4609 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 147.0 145.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 02:15:58 INFO - PROCESS | 4609 | Values: 93.0 94.0 93.0 93.0 95.0 95.0 94.0 94.0 93.0 93.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.50 stddev:0.84 (1.6%) stddev-sans-first:0.73 02:15:58 INFO - PROCESS | 4609 | Values: 52.0 53.0 53.0 53.0 53.0 54.0 55.0 54.0 53.0 54.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#9] json-stringify-tinderbox Cycles:10 Average:52.00 Median:52.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 02:15:58 INFO - PROCESS | 4609 | Values: 52.0 52.0 53.0 52.0 53.0 52.0 51.0 51.0 52.0 52.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:80.00 stddev:2.78 (3.5%) stddev-sans-first:2.93 02:15:58 INFO - PROCESS | 4609 | Values: 80.0 76.0 84.0 76.0 81.0 79.0 81.0 80.0 75.0 80.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:126.50 stddev:14.19 (11.2%) stddev-sans-first:14.89 02:15:58 INFO - PROCESS | 4609 | Values: 122.0 121.0 129.0 120.0 125.0 118.0 118.0 131.0 166.0 128.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.20 Median:160.50 stddev:2.39 (1.5%) stddev-sans-first:2.54 02:15:58 INFO - PROCESS | 4609 | Values: 159.0 160.0 161.0 161.0 161.0 154.0 156.0 160.0 161.0 159.0 02:15:58 INFO - PROCESS | 4609 | 02:15:58 INFO - PROCESS | 4609 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.90 Median:59.00 stddev:1.10 (1.9%) stddev-sans-first:1.17 02:15:58 INFO - PROCESS | 4609 | Values: 59.0 59.0 59.0 60.0 59.0 61.0 59.0 58.0 58.0 57.0 02:15:58 INFO - PROCESS | 4609 | -------- Summary: end -------- 02:15:58 INFO - PROCESS | 4609 | 02:15:59 INFO - TEST-INFO | 4609: exit 0 02:15:59 INFO - TEST-OK | kraken | took 60132ms 02:15:59 INFO - SUITE-END | took 1175s 02:15:59 INFO - Completed test suite (00:19:35) 02:15:59 INFO - javascript benchmark 02:15:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7935.2646013998665, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3273.49260864566, 3376.0884561168486, 3380.307037057403, 3390.0507857799826, 3412.061683037166, 5658.349289831671, 5778.855528611787, 5781.335146458158, 5792.884142317156, 5797.565182611305, 5693.089105743082, 5779.0, 5787.363390027093, 5787.710614469281, 5799.507041901433, 5127.282180494733, 5131.974340128294, 5163.81926632568, 5176.482351764812, 5216.0, 5032.270320803481, 5204.713740744258, 5234.057869583474, 5236.607254455912, 5261.76322065508, 5063.84808455745, 5095.3376061112, 5109.642325037249, 5217.765200565984, 5228.529432351088, 5202.06362854686, 5231.215317702337, 5236.21456781482, 5236.293100431431, 5240.973795131038, 4998.950010499885, 5088.109580823379, 5131.7690703918415, 5198.714070726109, 5208.895822083575, 5636.4927156556105, 5659.60382773206, 5683.062294721366, 5696.287964004499, 5704.11586204139, 6136.7238474268315, 6174.475169610589, 6184.07238914162, 6188.752449901998, 6213.37866213375, 6234.0337247726675, 6255.499560035186, 6260.060990851363, 6267.811965641038, 6273.529485288622, 5635.943640563624, 5678.403767604384, 5743.253377060955, 5773.566982476331, 5777.335606405233, 6302.243730752248, 6307.96846015767, 6320.367963203643, 6325.240971083499, 6329.9239129348125, 6036.728347224385, 6071.51427885768, 6090.025595904634, 6090.512758979359, 6103.664298463538, 5937.10943358491, 6002.0996850472775, 6088.512918966472, 6113.083037544404, 6193.4425901669065, 6134.865219626364, 6158.168647232566, 6165.383461653799, 6174.567780255339, 6175.62946223228, 5915.911261331084, 5948.10778383246, 5951.077582974559, 6166.599171053867, 6172.8765424691255, 4614.100250451197, 4637.188492013884, 4665.533446655308, 4697.412823397075, 4709.411323584552, 4133.04939863833, 4204.831806727697, 4216.177845320133, 4225.3873188387515, 4227.915441691149, 5901.527877769769, 5916.822495325147, 5925.762969481259, 6029.125776762433, 6042.093685947143, 6219.40915613016, 6220.409061139185, 6240.063990401385, 6255.46828519581, 6262.279837818709, 5984.042553191555, 5997.640141591519, 5999.0, 6005.579609427386, 6039.758409663652, 5107.846764597068, 5152.098382782998, 5202.97398513005, 5262.473752624784, 5315.946840531546, 6497.902531462032, 6507.707153178024, 6507.934920650733, 6511.479081673455, 6516.348365163446, 6169.660668663267, 6184.6598437086395, 6241.594296370721, 6247.781327653511, 6252.124702541557, 6226.097215903578, 6266.530010249158, 6343.143675603735, 6362.6818659067785, 6487.870242595216, 6110.4980065817745, 6113.388661133851, 6116.66358350295, 6117.143599895929, 6144.2319710036245, 6186.1648677427975, 6217.937820621736, 6232.25212974446, 6235.532335074797, 6235.844103897439, 5798.246227990334, 5895.14520394549, 5962.403759624003, 6055.576109672281, 6070.3929607038945, 5961.642301461926, 5961.791337303173, 6053.334133245427, 6060.575759696779, 6085.2393450818645, 5913.970430147821, 6033.12519684652, 6034.577579569388, 6036.154938308729, 6049.66726830011, 5857.9121313179485, 5874.177615133799, 5898.233229680286, 5920.023196172583, 6070.423309785563, 5830.329512105993, 5848.94151058501, 5849.590528662953, 5861.472467477947, 5876.118582212703], "value": 5672.632161466943, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.8729709597917, 849.7790574450643, 865.8874346334976, 919.5540163020934, 928.0209379105041, 31808.0, 44577.33134002992, 44826.32760508595, 44877.32684009738, 44883.10233795322, 41776.0, 42054.78972605133, 42115.0, 45759.084818303614, 45789.54210457894, 7752.883706744404, 9150.81698366034, 9265.814683706321, 9267.397619154734, 9279.95360023199, 7739.961300193491, 10463.110635595984, 10672.573097076147, 10685.358878467277, 10686.893131068668, 10770.515326810313, 10771.084457821094, 10804.0, 10824.67525974222, 10836.0, 10762.730931726692, 10769.084627806646, 10790.51442685081, 10791.40647264408, 10800.027997480225, 10547.78904421915, 10669.573217071307, 10678.092362149227, 10686.679399618024, 10692.518836652369, 43924.12151756979, 43976.120477590586, 44109.0, 44132.11735765299, 44229.115417691464, 4338.132373525276, 4369.344598310247, 4382.233109205877, 4518.796654150538, 4528.592426681582, 30670.386592268253, 31029.379412411625, 31251.843740781376, 31278.687213128032, 32475.83762081198, 4377.255866502702, 4451.554844515555, 4466.8436604719, 4478.520262585533, 4602.033572949685, 238188.0, 238454.0, 238708.0, 239841.0, 239964.0, 45575.0, 45628.08743825105, 45806.31290530645, 45882.770586147526, 45966.770166148955, 48059.27911081337, 48273.0, 48365.274520882216, 48380.0, 48382.790430239525, 15651.530454086396, 16001.199940003185, 16019.279132438834, 16026.11856347912, 16035.919820401057, 12080.275183489019, 12253.509859605516, 13847.653808654662, 13865.514706985408, 13873.791893121612, 13058.0, 13117.868821311855, 13240.867591324157, 13275.535356262484, 13399.397027133802, 5496.4503549645515, 5551.333839939152, 5568.638038527482, 5572.136318870582, 5572.498475137257, 1022.4172221833484, 1029.9433531155858, 1032.8140934631838, 1033.5968972100886, 1037.5590374090982, 29363.119106426842, 29375.85312073426, 29459.705402946125, 29713.851430742707, 29998.700013000027, 1100.4937728644913, 1104.8342748587777, 1123.43828085957, 1127.5940661361903, 1134.8581427321585, 15199.772003419735, 15905.602359941095, 15906.284217210254, 15969.840301596836, 16169.757453638205, 156489.0, 156644.0, 157442.0, 157731.0, 158209.20895395678, 58900.0, 58999.705001474715, 59202.40797592055, 59247.703761480916, 59354.70322648445, 1482.1181397068726, 1498.4680438444414, 1523.8933274670887, 1531.5328824708347, 1533.9233038348261, 28086.157415277576, 28940.27649308784, 29023.709762902945, 29245.122646320644, 29637.110886673436, 41925.0, 42035.369469458594, 42277.36583951182, 42320.1535969285, 42348.78825605852, 337236.0, 337944.0, 338451.0, 338494.0, 338582.0, 31223.843880780452, 31347.216319592193, 31679.0, 31814.840925795223, 32129.0, 40460.0, 40745.79627101846, 40799.0, 41309.173816524104, 41610.79194604007, 31739.682603174595, 31907.68092319047, 31930.20174495656, 32029.839850800596, 32686.50970235496, 31974.200644984063, 32105.839470802497, 32106.0, 32192.517112243804, 34354.0, 36378.0, 36678.0, 37141.81429092837, 37298.25403491969, 37459.43810842894, 31030.53454198234, 31262.06213813498, 31535.684643153276, 31576.0, 31965.561549730784, 24738.134165304127, 25820.870895645403, 25910.87044564765, 26068.478630427664, 26245.868770656787], "value": 18602.928095665808, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.76365644380263, 463.28885161277424, 467.07424100435827, 468.5033864104049, 484.02227500449095, 20216.595668086647, 20539.897300513512, 21906.342809715694, 22108.447288817788, 22234.221802236923, 21473.0, 21816.127354905824, 21836.89081554594, 21943.67084493734, 23086.769132308673, 6736.225334086569, 6772.661366931658, 6911.48163887708, 6997.475189360793, 7007.369336759691, 6776.932230677704, 6869.450443964496, 6871.690773915186, 6877.587344759306, 6878.621675807829, 6866.382025617693, 7308.707651693952, 7549.811254718648, 7566.697332106709, 7581.658825352844, 7477.626118694098, 7670.041244844395, 7686.461947663665, 7701.22987701231, 7724.536527808322, 7291.562506249589, 7468.551886886804, 7470.215627359104, 7475.813104672372, 7476.252374762535, 25801.22596322113, 25914.87042564794, 26179.476410471685, 26374.73625263742, 26474.47051058968, 3240.4977228529615, 3264.5592844965863, 3271.247613049012, 3277.4428347180797, 3359.7480188985924, 15442.845571544252, 16268.51194464168, 16341.673166536722, 16883.493495195165, 16966.151692415453, 2235.8434909556336, 2267.841251112423, 2336.123953517431, 2372.454335502827, 2375.2161786610377, 116558.0, 118774.0, 118964.0, 119663.0, 122068.0, 17408.129593520272, 18235.817641823676, 18896.52758681044, 18983.62032759337, 20039.298624548363, 32375.838120809716, 32787.83606081954, 32817.3436531268, 32829.507557386656, 32940.0, 10592.735181620521, 10717.732056698645, 10730.336797796353, 10777.838332424863, 11056.944715276533, 9134.0, 9134.680286190085, 9145.314101442418, 9371.297152713574, 9372.640967059739, 9114.40756350835, 9175.541222938826, 9261.629534818667, 9477.715668529956, 9496.572654230577, 3752.4746535485056, 3842.2507611015585, 3889.5138107736534, 3935.5080614923136, 4105.486814148231, 989.6931951095039, 995.9053889880449, 1005.2762011351815, 1005.9748506287254, 1019.1591936652144, 20633.587328253652, 21608.35174944754, 21779.128834846746, 21809.454763630718, 22275.665865012037, 984.1388784813288, 991.6430085169334, 1005.6631028605499, 1012.8480727890875, 1016.8322226832638, 11448.3703396314, 11989.340586267665, 12066.818997715041, 12080.335581543099, 12180.75638487243, 647994.0, 652489.0, 677541.0, 693740.0, 696063.0, 117954.4102279483, 118524.40737796428, 119199.0, 120524.0, 121443.0, 1301.9609411717663, 1302.9348532573333, 1315.145155648836, 1330.4146175682863, 1333.3933060457468, 18064.548386290448, 18484.72272915934, 18639.90680046591, 18705.625887482445, 18821.6235675283, 50636.74681626568, 51653.48346516487, 51679.0, 52280.73859630677, 53276.46723532715, 64609.35390646033, 70313.58571492729, 72318.0, 76265.0, 76839.61580192063, 12550.937245313715, 13135.868641313464, 13137.474501020235, 13223.471061157446, 13263.86736132665, 14340.713185736435, 14458.0, 14499.202543860189, 14521.419143234152, 14629.707405852036, 12968.481260749464, 13261.336933153496, 13304.800427993781, 13312.933435332761, 13378.464861405435, 11942.641720748392, 12227.0, 12321.07591930591, 12547.121701480808, 12672.429740661508, 13235.735285294433, 13301.068925175145, 13316.667083322995, 13481.865181348454, 13831.861681383058, 13045.478180872658, 13066.869331306565, 13129.60611181666, 13183.472661093449, 13245.271510066654, 12973.610791675887, 12993.155444896052, 13054.21674699483, 13122.60632181036, 13392.531261405804], "value": 11892.902702377361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.7014426970336, 1560.23548461254, 1613.0, 1625.1143126995782, 1632.9265183066768, 9362.391444556099, 11290.09679225662, 11562.710932226699, 11576.826347604772, 11577.131715121368, 11646.592369267077, 11709.882901170986, 11730.648080557576, 11733.765324693522, 11736.53053877846, 2038.2152871144642, 2081.6149012432675, 2350.8707021113833, 2390.713114426271, 2393.9880300598475, 2416.3113512648897, 2458.754124587536, 2470.8641024743633, 2477.8513289202706, 2478.3556275368355, 2458.3485376375274, 2463.4334103156284, 2470.888810003545, 2478.8388754730977, 2484.1305543059966, 2441.170002199243, 2483.279848843842, 2487.8009759219217, 2490.4272017436, 2490.962635560477, 2368.740553532053, 2383.784270022301, 2387.3434805428474, 2391.4380120671626, 2392.509535545209, 11648.359340236284, 11852.762944741144, 11869.0, 11879.584214552535, 11881.34652594107, 5922.940770592282, 6067.484263837579, 6071.696415179224, 6081.148639190517, 6085.878282434326, 3512.5609298837644, 3595.1551385424405, 3601.711863050976, 3642.2351306225723, 3651.3062518121474, 1389.2984043058293, 1401.7126489069717, 1404.7190561887664, 1453.2588379926208, 1461.7807328900644, 16457.341706331732, 17035.744463833053, 17409.56476088108, 17416.564585885328, 17488.12559372039, 4623.514530974267, 4685.086408150446, 4806.182948898696, 4809.302651115569, 4844.927326090112, 12926.547570834975, 12991.155574887602, 13006.934965325112, 13016.28410437435, 13017.21896686202, 6053.182820319289, 6060.818175454743, 6067.878642427215, 6068.089786531968, 6078.54410919174, 5471.863203419946, 5484.2047903053835, 5486.588505861996, 5556.277683901067, 5636.126400407943, 4095.692823038284, 4103.97948010258, 4165.791710414467, 4179.644730197906, 4208.116295577963, 1082.9620963266404, 1089.2974031749477, 1103.6082190822142, 1119.5130118398522, 1121.091915548392, 1086.9184811139196, 1095.6055819904827, 1107.9279846809932, 1108.1633366808007, 1117.1118960426481, 7345.88981165283, 7378.852422951618, 7426.405887528985, 7565.886511702329, 7596.316331530189, 1015.1675625986768, 1068.1348108032519, 1072.3726619927274, 1073.1790180511914, 1093.9398333091756, 4793.664443488992, 4823.5658790709, 4856.150173719656, 4885.022995400934, 5009.924851127236, 13626.250556219504, 13980.930095349458, 14153.716925661634, 14732.337044832793, 14740.484083057041, 14422.711545769025, 14464.059836110619, 14466.566003019927, 14474.927625361806, 14480.0, 10442.947785261025, 10456.843147352796, 10634.41510716918, 10690.572377104983, 10738.0, 7084.0, 7281.1262648482525, 7342.192358840416, 7483.775486735407, 7613.352865006315, 12776.10567260283, 13160.47358105665, 13271.336433178496, 13431.0, 13482.93258533701, 13160.40778165004, 13398.637561533256, 13412.865871341552, 13633.659158521117, 13768.24274664903, 9628.9037109628, 9706.223502119954, 9841.901580984098, 10059.597616095567, 10093.041161089684, 11223.0, 11267.098632109575, 11321.264117832314, 11336.263142895685, 11436.656900293005, 9333.346665733605, 9414.15272625467, 9494.762630934281, 9572.521373931137, 9759.804803904024, 8892.822143557221, 8955.104489551253, 8972.057933916845, 9099.0, 9346.11211934865, 8694.130586941255, 8709.303255739653, 8724.738257852274, 8767.079456657215, 8790.120987901415, 6363.87272254543, 6450.387213214737, 6489.253735820441, 6495.772647957488, 6533.281339052605, 6261.655608941552, 6320.336364681642, 6372.107904893412, 6428.4857211423905, 6562.475001999924], "value": 5488.778456283896, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.80360623294587, 405.728162131372, 412.7275997841425, 417.94995546842745, 429.30638630602266, 5266.894662106755, 5322.733863306834, 5322.946770532293, 5350.3515759699, 5361.946380536194, 4838.34682317887, 5248.186531087687, 5282.973585132069, 5289.471052894709, 5327.547158491533, 4475.104979004196, 4479.216137175998, 4504.0, 4512.819487220508, 4531.252343363341, 4480.708753930984, 4492.415985921825, 4493.932591011137, 4512.5261847506035, 4566.40636717228, 4153.210889930903, 4322.1787860306595, 4379.167958087969, 4401.195509840966, 4536.183486972343, 4363.563643635611, 4381.255186618282, 4388.71473354231, 4393.209222339978, 4393.384926110363, 4148.253314403406, 4148.91702165955, 4162.250794856925, 4165.291900376943, 4178.844972046116, 4923.630727695438, 5127.692338459704, 5170.1986192140275, 5192.428832828424, 5248.501392367757, 5066.569341605931, 5067.493250674903, 5069.569086627641, 5085.0, 5092.567131793802, 5464.617476776588, 5527.53015993641, 5543.501084902378, 5573.637713548647, 5583.231318859794, 4331.956680433187, 4537.977310113428, 4572.154151481986, 4598.494165641777, 4697.154512187805, 5340.49265319794, 5504.7522861470925, 5508.43857606211, 5738.8278351649515, 5770.711464426762, 4488.5287044859815, 4515.006698526385, 4522.638188944877, 4525.547445255448, 4535.387722657399, 4727.527247275245, 4790.928136077961, 4792.723456358202, 4798.49615790344, 4825.95174048255, 5045.318881950963, 5057.721825299644, 5077.746112694351, 5122.615803814729, 5126.974365128151, 5417.756200970965, 5419.810306639249, 5439.592030597721, 5464.398916119224, 5511.53151982078, 1642.82750311218, 1655.5033489952966, 1655.991720041392, 1668.6579251253463, 1675.1373042882924, 1241.2089237114305, 1272.867148238069, 1304.1979182802509, 1306.882380585752, 1320.4388135042761, 5262.605304602093, 5456.345238571317, 5482.369527504308, 5482.821193443349, 5509.090999985037, 5622.313306008181, 5683.886322273532, 5700.201971723962, 5757.971210144006, 5768.682722450306, 5459.69971651555, 5467.332463598561, 5487.4238204987905, 5564.527015203672, 5583.469570390806, 2923.8391888445913, 2983.8358890261243, 3003.2130882125102, 3016.5324374721954, 3021.957424688479, 5732.742026608813, 5824.3302020267365, 5868.713520428573, 5908.586398952118, 5938.257717785277, 5435.374931882884, 5753.482186603226, 5797.47822695951, 5805.680687562224, 5842.685395785999, 5844.649321040752, 5849.853753656193, 5855.677937713382, 5871.735771890189, 5884.205632239678, 5595.384507704231, 5634.943650563606, 5651.463111004448, 5702.45826646468, 5707.771689132388, 5216.895662086661, 5564.721763911869, 5582.8325150245555, 5583.74873130702, 5618.775248989995, 5610.625396777652, 5708.743106560131, 5752.223449834219, 5827.970860145672, 5874.971879920911, 4989.376327959005, 5050.267711181931, 5069.493050694901, 5088.236764485357, 5113.727214582745, 5432.728363581884, 5641.5204707600315, 5663.377028526941, 5699.207388379747, 5734.971325143514, 5490.560755139659, 5498.3127109111365, 5503.559715222693, 5511.0, 5517.36550296705, 5506.5870059744875, 5511.283533140666, 5539.556835453234, 5550.944490555043, 5565.16522521625, 5268.894622107459, 5471.9726401369335, 5484.91772623403, 5492.395836458067, 5521.2270282159725, 5532.557395408277, 5534.584906132137, 5535.612507124623, 5558.471945165203, 5570.3315602128005, 5127.897561999563, 5406.648567843077, 5432.592555558428, 5455.809046683347, 5467.781288748405], "value": 4451.01081801527, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.9007909129995, 933.7292185266273, 940.135075730328, 944.7071407863558, 972.8297547929113, 16866.91566542167, 18104.09479526025, 18132.18405171767, 18166.545836354097, 18201.453956381312, 17519.0, 17712.822871771277, 17748.0, 17748.822511774877, 17751.20119594618, 699.8740226759181, 722.5303552690767, 892.3708785306344, 903.5211337990859, 925.745420009496, 901.7384958362068, 907.4872696926249, 908.9819402269468, 909.0863681999581, 911.293747345809, 971.4996776660025, 971.5385192033763, 972.9902700972971, 973.0755782007086, 973.4208146153063, 960.9951950240275, 962.7159987803615, 962.9133377995979, 962.9518524073768, 965.6427121964883, 711.5943911970179, 711.6299524247362, 712.0245263988369, 712.0351923144169, 712.2450202785064, 18516.722249166272, 18737.81262187388, 18766.812331876645, 18942.526436839053, 18979.0, 16276.186190690418, 16392.67214655712, 16438.342466301332, 16500.587485312964, 16939.745903811454, 13466.394012269471, 13573.049886507948, 13875.167489950636, 13980.091294065955, 14002.85997140036, 4087.223427548786, 4130.359794231899, 4151.647109995654, 4204.66362690984, 4211.97894010531, 79981.60009199916, 81728.0, 81810.59094704548, 81972.18027819645, 82537.58731206306, 12304.692382690504, 12484.625461236177, 12508.249505029728, 12605.621831345074, 12618.432170552347, 20766.792332076788, 20857.791422085586, 20971.685424718642, 21056.894715526632, 21154.894225528773, 8981.550922453851, 9162.86255706165, 9216.262698984065, 9328.673496427593, 9334.813303733887, 5283.656562323436, 5292.629515933848, 5318.414974352818, 5375.381831089397, 5463.043967305785, 3713.294474049922, 3756.117312431631, 3767.171222331083, 3791.696664266852, 3791.696664266852, 646.2374398210063, 647.0423772816258, 650.9121268628769, 655.3151956205777, 657.375493281375, 850.8936382952131, 862.5514732339274, 874.9956250218836, 897.9955100224588, 899.9685011024583, 11874.703132421586, 12077.879221207675, 12210.26738395699, 12221.75556488883, 12586.748265034648, 859.2824991132475, 862.1938487514194, 880.000398190227, 891.9598618062073, 899.9190072893471, 5432.864178395493, 5527.944720552823, 5573.721313934287, 5660.26416565852, 5724.112762521816, 79053.60473197597, 79584.6020769904, 80500.0, 81704.59147704224, 85098.72351914726, 69865.0, 70116.6494167526, 71003.64498177476, 71478.64260678663, 73544.0, 67225.6638716813, 67663.3233667667, 67760.66119669468, 68299.65850170718, 68533.0, 11678.24091434054, 12354.567590134302, 12936.870631293943, 12956.740865182832, 13145.408456619667, 41562.58437415708, 42769.78615106905, 43267.35098973455, 43603.781981089895, 44902.0, 55676.44323556712, 58441.0, 58863.41136588579, 59152.40847591469, 59406.0, 13565.186088834702, 13866.611013386859, 13886.236257005557, 13893.374798133906, 14181.858181418467, 15692.686146276783, 15746.897717159689, 15766.9211653941, 15975.28111234974, 16135.193240337701, 16089.67820643604, 16222.0, 16355.2640131192, 16673.583160421087, 17034.0, 15571.844281557493, 15728.92135539315, 15921.0, 16117.677646446771, 16384.75422868634, 15970.520884373487, 16036.278367473726, 16046.919765401099, 16169.919150404172, 16390.344386224893, 10407.375557466576, 10618.8407173894, 10747.623833165802, 11090.889091108986, 11464.885351146715, 12572.182808117443, 12605.180663256859, 13880.167189968633, 13895.0, 13925.79111313311], "value": 8142.209268244742, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1450.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 112.0, 112.0, 113.0, 111.0, 112.0, 113.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 115.0, 149.0, 118.0, 117.0, 116.0, 121.0, 140.0, 115.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 159.0, 160.0, 160.0, 161.0, 160.0, 160.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 76.0, 75.0, 110.0, 76.0, 77.0, 77.0, 75.0, 95.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 107.0, 81.0, 83.0, 82.0, 99.0, 82.0, 82.0, 103.0], "value": 88.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 145.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 93.0, 95.0, 95.0, 94.0, 94.0, 93.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 53.0, 53.0, 54.0, 55.0, 54.0, 53.0, 54.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 52.0, 53.0, 52.0, 51.0, 51.0, 52.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 84.0, 76.0, 81.0, 79.0, 81.0, 80.0, 75.0, 80.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 129.0, 120.0, 125.0, 118.0, 118.0, 131.0, 166.0, 128.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 161.0, 161.0, 161.0, 154.0, 156.0, 160.0, 161.0, 159.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 59.0, 61.0, 59.0, 58.0, 58.0, 57.0], "value": 58.9, "unit": "score"}]}]} 02:15:59 INFO - Return code: 0 02:15:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:15:59 INFO - # TBPL SUCCESS # 02:15:59 INFO - Running post-action listener: _resource_record_post_action 02:15:59 INFO - [mozharness: 2017-05-18 09:15:59.593512Z] Finished run-tests step (success) 02:15:59 INFO - Running post-run listener: _resource_record_post_run 02:15:59 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 729088; Write bytes: 334839808; Read time: 64; Write time: 190132 02:15:59 INFO - TinderboxPrint: CPU usage
13.1% 02:15:59 INFO - TinderboxPrint: I/O read bytes / time
729,088 / 64 02:15:59 INFO - TinderboxPrint: I/O write bytes / time
334,839,808 / 190,132 02:15:59 INFO - TinderboxPrint: CPU idle
8,247.3 (86.9%) 02:15:59 INFO - TinderboxPrint: CPU user
1,198.2 (12.6%) 02:15:59 INFO - TinderboxPrint: Swap in / out
0 / 0 02:15:59 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 108699648; Read time: 0; Write time: 75004 02:15:59 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 724992; Write bytes: 226140160; Read time: 52; Write time: 115128 02:16:00 INFO - Running post-run listener: _upload_blobber_files 02:16:00 INFO - Blob upload gear active. 02:16:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:16:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:16:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:16:00 INFO - (blobuploader) - INFO - Open directory for files ... 02:16:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:16:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:16:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:16:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:16:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:16:01 INFO - (blobuploader) - INFO - Done attempting. 02:16:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:16:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:16:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:16:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:16:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:16:02 INFO - (blobuploader) - INFO - Done attempting. 02:16:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:16:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:16:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:16:03 INFO - (blobuploader) - INFO - Done attempting. 02:16:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:16:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:16:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:16:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:16:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:16:06 INFO - (blobuploader) - INFO - Done attempting. 02:16:06 INFO - (blobuploader) - INFO - Iteration through files over. 02:16:06 INFO - Return code: 0 02:16:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:16:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:16:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b0d3e6bf5ff1a633eb764b9c75cad15373026ca7bc6f9b47d15b08ba11fe6d11e3c45335f85667f3768356d86e42d4ea8e6a8dd21aa96b5c4b5787ba2e62052b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c7f6fea00e7cd91348970057f2d46a62a7845b3377a5e77803e58122da3e03fa303650cf3b0f2d30fb6e48b7f928838e0a83c6135892978635c9dee02582086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a5b07a5d921044f6e08ff6171825ccfc234d4fc02fec3f307eed180cfc3bbeeefcc474ebe3d918db046c4c77cab025d0d8cece936516073c36efe79c9b30a78e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/612411410d11fb1b65c5fcaa926466dfca9104ffd9f9c03715ee1d6f576fc493ba645c0499981803159cc9875688a3256a8ee45b560251946c865cbf945125f3"} 02:16:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:16:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:16:06 INFO - Contents: 02:16:06 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b0d3e6bf5ff1a633eb764b9c75cad15373026ca7bc6f9b47d15b08ba11fe6d11e3c45335f85667f3768356d86e42d4ea8e6a8dd21aa96b5c4b5787ba2e62052b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c7f6fea00e7cd91348970057f2d46a62a7845b3377a5e77803e58122da3e03fa303650cf3b0f2d30fb6e48b7f928838e0a83c6135892978635c9dee02582086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a5b07a5d921044f6e08ff6171825ccfc234d4fc02fec3f307eed180cfc3bbeeefcc474ebe3d918db046c4c77cab025d0d8cece936516073c36efe79c9b30a78e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/612411410d11fb1b65c5fcaa926466dfca9104ffd9f9c03715ee1d6f576fc493ba645c0499981803159cc9875688a3256a8ee45b560251946c865cbf945125f3"} 02:16:06 INFO - Running post-run listener: copy_logs_to_upload_dir 02:16:06 INFO - Copying logs to upload dir... 02:16:06 INFO - mkdir: /builds/slave/test/build/upload/logs 02:16:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.686736 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-05-18 02:16:06.096949) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 02:16:06.103083) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501 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/b0d3e6bf5ff1a633eb764b9c75cad15373026ca7bc6f9b47d15b08ba11fe6d11e3c45335f85667f3768356d86e42d4ea8e6a8dd21aa96b5c4b5787ba2e62052b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c7f6fea00e7cd91348970057f2d46a62a7845b3377a5e77803e58122da3e03fa303650cf3b0f2d30fb6e48b7f928838e0a83c6135892978635c9dee02582086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a5b07a5d921044f6e08ff6171825ccfc234d4fc02fec3f307eed180cfc3bbeeefcc474ebe3d918db046c4c77cab025d0d8cece936516073c36efe79c9b30a78e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/612411410d11fb1b65c5fcaa926466dfca9104ffd9f9c03715ee1d6f576fc493ba645c0499981803159cc9875688a3256a8ee45b560251946c865cbf945125f3"} build_url:https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/BCtiJzQjTTGMNAqN4HNAMw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b0d3e6bf5ff1a633eb764b9c75cad15373026ca7bc6f9b47d15b08ba11fe6d11e3c45335f85667f3768356d86e42d4ea8e6a8dd21aa96b5c4b5787ba2e62052b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c7f6fea00e7cd91348970057f2d46a62a7845b3377a5e77803e58122da3e03fa303650cf3b0f2d30fb6e48b7f928838e0a83c6135892978635c9dee02582086f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a5b07a5d921044f6e08ff6171825ccfc234d4fc02fec3f307eed180cfc3bbeeefcc474ebe3d918db046c4c77cab025d0d8cece936516073c36efe79c9b30a78e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/612411410d11fb1b65c5fcaa926466dfca9104ffd9f9c03715ee1d6f576fc493ba645c0499981803159cc9875688a3256a8ee45b560251946c865cbf945125f3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 02:16:06.128082) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:16:06.128443) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1495093492.599427-687609501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004616 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 02:16:06.212535) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 02:16:06.212863) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 02:16:06.213184) ========= ========= Total master_lag: 0.19 =========