builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1495717767.16 results: success (0) revision: 02e5b298abedc3fd641a8db70efd59009109ed82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.158323) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.158782) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.159073) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.230043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.230296) ========= 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=920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-25 06:09:27-- 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% 46.1M=0s 2017-05-25 06:09:27 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151540 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.394464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.394776) ========= 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=920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022211 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.445331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:27.445650) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02e5b298abedc3fd641a8db70efd59009109ed82 --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 02e5b298abedc3fd641a8db70efd59009109ed82 --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=920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-25 06:09:27,734 truncating revision to first 12 chars 2017-05-25 06:09:27,734 Setting DEBUG logging. 2017-05-25 06:09:27,734 attempt 1/10 2017-05-25 06:09:27,734 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/02e5b298abed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 06:09:28,174 unpacking tar archive at: mozilla-beta-02e5b298abed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.762372 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:28.221496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:28.221821) ========= script_repo_revision: 02e5b298abedc3fd641a8db70efd59009109ed82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:28.222191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:28.222496) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 06:09:28.240372) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-05-25 06:09:28.240669) ========= /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=920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:09:28 INFO - MultiFileLogger online at 20170525 06:09:28 in /builds/slave/test 06:09:28 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 06:09:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:09:28 INFO - {'append_to_log': False, 06:09:28 INFO - 'base_work_dir': '/builds/slave/test', 06:09:28 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 06:09:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:09:28 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 06:09:28 INFO - 'buildbot_json_path': 'buildprops.json', 06:09:28 INFO - 'config_files': ('talos/linux_config.py',), 06:09:28 INFO - 'default_actions': ('clobber', 06:09:28 INFO - 'read-buildbot-config', 06:09:28 INFO - 'download-and-extract', 06:09:28 INFO - 'populate-webroot', 06:09:28 INFO - 'create-virtualenv', 06:09:28 INFO - 'install', 06:09:28 INFO - 'run-tests'), 06:09:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:09:28 INFO - 'download_minidump_stackwalk': True, 06:09:28 INFO - 'download_symbols': 'ondemand', 06:09:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:09:28 INFO - 'tooltool.py': '/tools/tooltool.py', 06:09:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:09:28 INFO - '/tools/misc-python/virtualenv.py')}, 06:09:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:09:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:09:28 INFO - 'gecko_profile': False, 06:09:28 INFO - 'gecko_profile_interval': 0, 06:09:28 INFO - 'installer_path': 'installer.exe', 06:09:28 INFO - 'log_level': 'info', 06:09:28 INFO - 'log_name': 'talos', 06:09:28 INFO - 'log_to_console': True, 06:09:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:09:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:09:28 INFO - 'opt_config_files': (), 06:09:28 INFO - 'pip_index': False, 06:09:28 INFO - 'suite': 'dromaeojs', 06:09:28 INFO - 'system_bits': '32', 06:09:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:09:28 INFO - 'title': 'talos-linux64-ix-053', 06:09:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:09:28 INFO - 'use_talos_json': True, 06:09:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:09:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:09:28 INFO - 'work_dir': 'build'} 06:09:28 INFO - [mozharness: 2017-05-25 13:09:28.627977Z] Running clobber step. 06:09:28 INFO - Running pre-action listener: _resource_record_pre_action 06:09:28 INFO - Running main action method: clobber 06:09:28 INFO - rmtree: /builds/slave/test/build 06:09:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:09:29 INFO - Running post-action listener: _resource_record_post_action 06:09:29 INFO - [mozharness: 2017-05-25 13:09:29.645325Z] Finished clobber step (success) 06:09:29 INFO - [mozharness: 2017-05-25 13:09:29.645424Z] Running read-buildbot-config step. 06:09:29 INFO - Running pre-action listener: _resource_record_pre_action 06:09:29 INFO - Running main action method: read_buildbot_config 06:09:29 INFO - Using buildbot properties: 06:09:29 INFO - { 06:09:29 INFO - "project": "", 06:09:29 INFO - "product": "firefox", 06:09:29 INFO - "who": "jkew@mozilla.com", 06:09:29 INFO - "installer_path": "public/build/target.tar.bz2", 06:09:29 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 06:09:29 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 06:09:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 06:09:29 INFO - "stage_platform": "linux64", 06:09:29 INFO - "basedir": "/builds/slave/test", 06:09:29 INFO - "buildnumber": 59, 06:09:29 INFO - "platform": "ubuntu64_hw", 06:09:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:09:29 INFO - "slavebuilddir": "test", 06:09:29 INFO - "taskId": "KnnoQsfyRYiLHbK16mx3Qw", 06:09:29 INFO - "branch": "mozilla-beta", 06:09:29 INFO - "script_repo_revision": "production", 06:09:29 INFO - "revision": "02e5b298abedc3fd641a8db70efd59009109ed82", 06:09:29 INFO - "slavename": "talos-linux64-ix-053", 06:09:29 INFO - "repo_path": "releases/mozilla-beta" 06:09:29 INFO - } 06:09:29 INFO - Finding installer, test and symbols from parent task. 06:09:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KnnoQsfyRYiLHbK16mx3Qw'}, attempt #1 06:09:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KnnoQsfyRYiLHbK16mx3Qw'}, attempt #1 06:09:30 INFO - Task dependencies: QLgep2mlRPSPxgEIn9YFGg 06:09:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg'}, attempt #1 06:09:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2 06:09:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/test_packages.json 06:09:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.crashreporter-symbols.zip 06:09:31 INFO - Running post-action listener: _resource_record_post_action 06:09:31 INFO - [mozharness: 2017-05-25 13:09:31.031782Z] Finished read-buildbot-config step (success) 06:09:31 INFO - [mozharness: 2017-05-25 13:09:31.031999Z] Running download-and-extract step. 06:09:31 INFO - Running pre-action listener: _resource_record_pre_action 06:09:31 INFO - Running main action method: download_and_extract 06:09:31 INFO - mkdir: /builds/slave/test/build/tests 06:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:09:31 INFO - trying https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.test_packages.json 06:09:31 INFO - Downloading https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:09:31 INFO - Downloaded 986 bytes. 06:09:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:09:31 INFO - Using the following test package requirements: 06:09:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:09:31 INFO - u'common': [u'target.common.tests.zip'], 06:09:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:09:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:09:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:09:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:09:31 INFO - u'mozbase': [u'target.common.tests.zip'], 06:09:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:09:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:09:31 INFO - u'web-platform': [u'target.common.tests.zip', 06:09:31 INFO - u'target.web-platform.tests.zip'], 06:09:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:09:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:09:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.common.tests.zip 06:09:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:09:31 INFO - Fetch https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.common.tests.zip into memory 06:09:34 INFO - Expected file size: 59711102 06:09:34 INFO - Obtained file size: 59711102 06:09:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:09:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.common.tests.zip 06:09:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:09:43 INFO - Fetch https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.common.tests.zip into memory 06:09:45 INFO - Expected file size: 59711102 06:09:45 INFO - Obtained file size: 59711102 06:09:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.talos.tests.zip 06:09:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:09:56 INFO - Fetch https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.talos.tests.zip into memory 06:09:57 INFO - Expected file size: 14404449 06:09:57 INFO - Obtained file size: 14404449 06:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:09:57 INFO - trying https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2 06:09:57 INFO - Downloading https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:09:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:10:00 INFO - Downloaded 59704983 bytes. 06:10:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2 06:10:00 INFO - mkdir: /builds/slave/test/properties 06:10:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:10:00 INFO - Writing to file /builds/slave/test/properties/build_url 06:10:00 INFO - Contents: 06:10:00 INFO - build_url:https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2 06:10:00 INFO - Running post-action listener: _resource_record_post_action 06:10:00 INFO - Running post-action listener: set_extra_try_arguments 06:10:00 INFO - [mozharness: 2017-05-25 13:10:00.451233Z] Finished download-and-extract step (success) 06:10:00 INFO - [mozharness: 2017-05-25 13:10:00.451458Z] Running populate-webroot step. 06:10:00 INFO - Running pre-action listener: _resource_record_pre_action 06:10:00 INFO - Running main action method: populate_webroot 06:10:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:10:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:10:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:10:00 INFO - '--tppagecycles', 06:10:00 INFO - '7'], 06:10:00 INFO - 'tests': ['tsvgm']}}, 06:10:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:10:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:10:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:10:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:10:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:10:00 INFO - 'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:10:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:10:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:10:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:10:00 INFO - 'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['damp', 'tps']}, 06:10:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:10:00 INFO - 'tests': ['damp', 'tps']}, 06:10:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['dromaeo_dom']}, 06:10:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:10:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['basic_compositor_video']}, 06:10:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:10:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['a11yr', 06:10:00 INFO - 'ts_paint', 06:10:00 INFO - 'tpaint', 06:10:00 INFO - 'sessionrestore', 06:10:00 INFO - 'sessionrestore_no_auto_restore', 06:10:00 INFO - 'tabpaint']}, 06:10:00 INFO - 'other-e10s': {'tests': ['a11yr', 06:10:00 INFO - 'ts_paint', 06:10:00 INFO - 'tpaint', 06:10:00 INFO - 'sessionrestore', 06:10:00 INFO - 'sessionrestore_no_auto_restore', 06:10:00 INFO - 'tabpaint']}, 06:10:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:10:00 INFO - 'ts_paint', 06:10:00 INFO - 'tpaint', 06:10:00 INFO - 'sessionrestore', 06:10:00 INFO - 'sessionrestore_no_auto_restore', 06:10:00 INFO - 'tabpaint']}, 06:10:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:10:00 INFO - 'ts_paint', 06:10:00 INFO - 'tpaint', 06:10:00 INFO - 'sessionrestore', 06:10:00 INFO - 'sessionrestore_no_auto_restore', 06:10:00 INFO - 'tabpaint']}, 06:10:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['a11yr', 06:10:00 INFO - 'ts_paint', 06:10:00 INFO - 'tpaint', 06:10:00 INFO - 'sessionrestore', 06:10:00 INFO - 'sessionrestore_no_auto_restore', 06:10:00 INFO - 'tabpaint']}, 06:10:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['a11yr', 06:10:00 INFO - 'ts_paint', 06:10:00 INFO - 'tpaint', 06:10:00 INFO - 'sessionrestore', 06:10:00 INFO - 'sessionrestore_no_auto_restore', 06:10:00 INFO - 'tabpaint']}, 06:10:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['tsvgx', 06:10:00 INFO - 'tsvgr_opacity', 06:10:00 INFO - 'tart', 06:10:00 INFO - 'tscrollx', 06:10:00 INFO - 'cart', 06:10:00 INFO - 'tsvg_static']}, 06:10:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:10:00 INFO - 'tsvgr_opacity', 06:10:00 INFO - 'tart', 06:10:00 INFO - 'tscrollx', 06:10:00 INFO - 'cart', 06:10:00 INFO - 'tsvg_static']}, 06:10:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:10:00 INFO - 'talos_options': ['--disable-e10s'], 06:10:00 INFO - 'tests': ['tp5o']}, 06:10:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:10:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:10:00 INFO - 'talos_options': ['--disable-e10s', 06:10:00 INFO - '--xperf_path', 06:10:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:10:00 INFO - 'tests': ['tp5n']}, 06:10:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:10:00 INFO - 'talos_options': ['--xperf_path', 06:10:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:10:00 INFO - 'tests': ['tp5n']}}, 06:10:00 INFO - 'talos.zip': {'path': '', 06:10:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:10:00 INFO - Running post-action listener: _resource_record_post_action 06:10:00 INFO - [mozharness: 2017-05-25 13:10:00.470238Z] Finished populate-webroot step (success) 06:10:00 INFO - [mozharness: 2017-05-25 13:10:00.470328Z] Running create-virtualenv step. 06:10:00 INFO - Running pre-action listener: _resource_record_pre_action 06:10:00 INFO - Running main action method: create_virtualenv 06:10:00 INFO - Creating virtualenv /builds/slave/test/build/venv 06:10:00 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 06:10:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:10:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:10:00 INFO - Using real prefix '/usr' 06:10:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:10:01 INFO - Installing distribute.............................................................................................................................................................................................done. 06:10:03 INFO - Installing pip.................done. 06:10:03 INFO - Return code: 0 06:10:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:10:03 INFO - Reading from file tmpfile_stdout 06:10:03 INFO - Output received: 06:10:03 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) 06:10:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:03 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:03 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 06:10:03 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 06:10:03 INFO - Using env: {'DISPLAY': ':0', 06:10:03 INFO - 'HOME': '/home/cltbld', 06:10:03 INFO - 'LANG': 'en_US.UTF-8', 06:10:03 INFO - 'LANGUAGE': 'en_US:en', 06:10:03 INFO - 'LOGNAME': 'cltbld', 06:10:03 INFO - 'MAIL': '/var/mail/cltbld', 06:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:03 INFO - 'MOZ_NO_REMOTE': '1', 06:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:03 INFO - 'NO_EM_RESTART': '1', 06:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:03 INFO - 'PWD': '/builds/slave/test', 06:10:03 INFO - 'SHELL': '/bin/bash', 06:10:03 INFO - 'SHLVL': '1', 06:10:03 INFO - 'TERM': 'linux', 06:10:03 INFO - 'TMOUT': '86400', 06:10:03 INFO - 'USER': 'cltbld', 06:10:03 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:03 INFO - '_': '/tools/buildbot/bin/python'} 06:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:03 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 06:10:03 INFO - Cleaning up... 06:10:03 INFO - Return code: 0 06:10:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:03 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:03 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 06:10:03 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 06:10:03 INFO - Using env: {'DISPLAY': ':0', 06:10:03 INFO - 'HOME': '/home/cltbld', 06:10:03 INFO - 'LANG': 'en_US.UTF-8', 06:10:03 INFO - 'LANGUAGE': 'en_US:en', 06:10:03 INFO - 'LOGNAME': 'cltbld', 06:10:03 INFO - 'MAIL': '/var/mail/cltbld', 06:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:03 INFO - 'MOZ_NO_REMOTE': '1', 06:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:03 INFO - 'NO_EM_RESTART': '1', 06:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:03 INFO - 'PWD': '/builds/slave/test', 06:10:03 INFO - 'SHELL': '/bin/bash', 06:10:03 INFO - 'SHLVL': '1', 06:10:03 INFO - 'TERM': 'linux', 06:10:03 INFO - 'TMOUT': '86400', 06:10:03 INFO - 'USER': 'cltbld', 06:10:03 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:03 INFO - '_': '/tools/buildbot/bin/python'} 06:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:03 INFO - Downloading/unpacking psutil>=3.1.1 06:10:03 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 06:10:03 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 06:10:03 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 06:10:03 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 06:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:10:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:10:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:10:04 INFO - Installing collected packages: psutil 06:10:04 INFO - Running setup.py install for psutil 06:10:04 INFO - building 'psutil._psutil_linux' extension 06:10:04 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 06:10:05 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 06:10:05 INFO - building 'psutil._psutil_posix' extension 06:10:05 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 06:10:05 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 06:10:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:10:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:10:05 INFO - Successfully installed psutil 06:10:05 INFO - Cleaning up... 06:10:05 INFO - Return code: 0 06:10:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:05 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:05 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 06:10:05 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 06:10:05 INFO - Using env: {'DISPLAY': ':0', 06:10:05 INFO - 'HOME': '/home/cltbld', 06:10:05 INFO - 'LANG': 'en_US.UTF-8', 06:10:05 INFO - 'LANGUAGE': 'en_US:en', 06:10:05 INFO - 'LOGNAME': 'cltbld', 06:10:05 INFO - 'MAIL': '/var/mail/cltbld', 06:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:05 INFO - 'MOZ_NO_REMOTE': '1', 06:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:05 INFO - 'NO_EM_RESTART': '1', 06:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:05 INFO - 'PWD': '/builds/slave/test', 06:10:05 INFO - 'SHELL': '/bin/bash', 06:10:05 INFO - 'SHLVL': '1', 06:10:05 INFO - 'TERM': 'linux', 06:10:05 INFO - 'TMOUT': '86400', 06:10:05 INFO - 'USER': 'cltbld', 06:10:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:05 INFO - '_': '/tools/buildbot/bin/python'} 06:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:10:05 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 06:10:05 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 06:10:05 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 06:10:05 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 06:10:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:10:06 INFO - Installing collected packages: mozsystemmonitor 06:10:06 INFO - Running setup.py install for mozsystemmonitor 06:10:06 INFO - Successfully installed mozsystemmonitor 06:10:06 INFO - Cleaning up... 06:10:06 INFO - Return code: 0 06:10:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:10: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')]} 06:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10: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')]} 06:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:06 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:06 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 06:10:06 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 06:10:06 INFO - Using env: {'DISPLAY': ':0', 06:10:06 INFO - 'HOME': '/home/cltbld', 06:10:06 INFO - 'LANG': 'en_US.UTF-8', 06:10:06 INFO - 'LANGUAGE': 'en_US:en', 06:10:06 INFO - 'LOGNAME': 'cltbld', 06:10:06 INFO - 'MAIL': '/var/mail/cltbld', 06:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:06 INFO - 'MOZ_NO_REMOTE': '1', 06:10:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:06 INFO - 'NO_EM_RESTART': '1', 06:10:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:06 INFO - 'PWD': '/builds/slave/test', 06:10:06 INFO - 'SHELL': '/bin/bash', 06:10:06 INFO - 'SHLVL': '1', 06:10:06 INFO - 'TERM': 'linux', 06:10:06 INFO - 'TMOUT': '86400', 06:10:06 INFO - 'USER': 'cltbld', 06:10:06 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:06 INFO - '_': '/tools/buildbot/bin/python'} 06:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:07 INFO - Downloading/unpacking jsonschema==2.5.1 06:10: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 06:10: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 06:10: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 06:10: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 06:10:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:10:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:08 INFO - Downloading functools32-3.2.3-2.tar.gz 06:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:10:08 INFO - warning: no files found matching '*.txt' 06:10:08 INFO - no previously-included directories found matching 'build' 06:10:08 INFO - no previously-included directories found matching 'dist' 06:10:08 INFO - no previously-included directories found matching '.git*' 06:10:08 INFO - Installing collected packages: jsonschema, functools32 06:10:08 INFO - Running setup.py install for functools32 06:10:08 INFO - warning: no files found matching '*.txt' 06:10:08 INFO - no previously-included directories found matching 'build' 06:10:08 INFO - no previously-included directories found matching 'dist' 06:10:08 INFO - no previously-included directories found matching '.git*' 06:10:08 INFO - Successfully installed jsonschema functools32 06:10:08 INFO - Cleaning up... 06:10:08 INFO - Return code: 0 06:10:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:10:08 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')]} 06:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:08 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')]} 06:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:08 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:08 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 06:10:08 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 06:10:08 INFO - Using env: {'DISPLAY': ':0', 06:10:08 INFO - 'HOME': '/home/cltbld', 06:10:08 INFO - 'LANG': 'en_US.UTF-8', 06:10:08 INFO - 'LANGUAGE': 'en_US:en', 06:10:08 INFO - 'LOGNAME': 'cltbld', 06:10:08 INFO - 'MAIL': '/var/mail/cltbld', 06:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:08 INFO - 'MOZ_NO_REMOTE': '1', 06:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:08 INFO - 'NO_EM_RESTART': '1', 06:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:08 INFO - 'PWD': '/builds/slave/test', 06:10:08 INFO - 'SHELL': '/bin/bash', 06:10:08 INFO - 'SHLVL': '1', 06:10:08 INFO - 'TERM': 'linux', 06:10:08 INFO - 'TMOUT': '86400', 06:10:08 INFO - 'USER': 'cltbld', 06:10:08 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:08 INFO - '_': '/tools/buildbot/bin/python'} 06:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:10:08 INFO - Cleaning up... 06:10:08 INFO - Return code: 0 06:10:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:10:08 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')]} 06:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:08 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')]} 06:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:08 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:08 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 06:10:08 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 06:10:08 INFO - Using env: {'DISPLAY': ':0', 06:10:08 INFO - 'HOME': '/home/cltbld', 06:10:08 INFO - 'LANG': 'en_US.UTF-8', 06:10:08 INFO - 'LANGUAGE': 'en_US:en', 06:10:08 INFO - 'LOGNAME': 'cltbld', 06:10:08 INFO - 'MAIL': '/var/mail/cltbld', 06:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:08 INFO - 'MOZ_NO_REMOTE': '1', 06:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:08 INFO - 'NO_EM_RESTART': '1', 06:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:08 INFO - 'PWD': '/builds/slave/test', 06:10:08 INFO - 'SHELL': '/bin/bash', 06:10:08 INFO - 'SHLVL': '1', 06:10:08 INFO - 'TERM': 'linux', 06:10:08 INFO - 'TMOUT': '86400', 06:10:08 INFO - 'USER': 'cltbld', 06:10:08 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:08 INFO - '_': '/tools/buildbot/bin/python'} 06:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:09 INFO - Downloading/unpacking blobuploader==1.2.4 06:10:09 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 06:10:09 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 06:10:09 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 06:10:09 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 06:10:10 INFO - Downloading blobuploader-1.2.4.tar.gz 06:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:10:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:10:10 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 06:10:10 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 06:10:10 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 06:10:10 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 06:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:10:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:10:10 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 06:10:10 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 06:10:10 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 06:10:10 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 06:10:10 INFO - Downloading docopt-0.6.1.tar.gz 06:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:10:10 INFO - Installing collected packages: blobuploader, requests, docopt 06:10:10 INFO - Running setup.py install for blobuploader 06:10:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:10:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:10:10 INFO - Running setup.py install for requests 06:10:11 INFO - Running setup.py install for docopt 06:10:11 INFO - Successfully installed blobuploader requests docopt 06:10:11 INFO - Cleaning up... 06:10:11 INFO - Return code: 0 06:10:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:10:11 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')]} 06:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:11 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')]} 06:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:11 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:11 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 06:10:11 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 06:10:11 INFO - Using env: {'DISPLAY': ':0', 06:10:11 INFO - 'HOME': '/home/cltbld', 06:10:11 INFO - 'LANG': 'en_US.UTF-8', 06:10:11 INFO - 'LANGUAGE': 'en_US:en', 06:10:11 INFO - 'LOGNAME': 'cltbld', 06:10:11 INFO - 'MAIL': '/var/mail/cltbld', 06:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:11 INFO - 'MOZ_NO_REMOTE': '1', 06:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:11 INFO - 'NO_EM_RESTART': '1', 06:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:11 INFO - 'PWD': '/builds/slave/test', 06:10:11 INFO - 'SHELL': '/bin/bash', 06:10:11 INFO - 'SHLVL': '1', 06:10:11 INFO - 'TERM': 'linux', 06:10:11 INFO - 'TMOUT': '86400', 06:10:11 INFO - 'USER': 'cltbld', 06:10:11 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:11 INFO - '_': '/tools/buildbot/bin/python'} 06:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:10:11 INFO - Running setup.py (path:/tmp/pip-rJsxpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:10:11 INFO - Running setup.py (path:/tmp/pip-aOCNuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:10:11 INFO - Running setup.py (path:/tmp/pip-jBgqlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:10:11 INFO - Running setup.py (path:/tmp/pip-qbsepH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:10:11 INFO - Running setup.py (path:/tmp/pip-HR1y1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:10:11 INFO - Running setup.py (path:/tmp/pip-p2ZFlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:10:11 INFO - Running setup.py (path:/tmp/pip-J0FfAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:10:12 INFO - Running setup.py (path:/tmp/pip-AXG9pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:10:12 INFO - Running setup.py (path:/tmp/pip-Fj3daA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:10:12 INFO - Running setup.py (path:/tmp/pip-eUQqbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:10:12 INFO - Running setup.py (path:/tmp/pip-1caOEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:10:12 INFO - Running setup.py (path:/tmp/pip-5FY1tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:10:12 INFO - Running setup.py (path:/tmp/pip-TTD4T4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:10:12 INFO - Running setup.py (path:/tmp/pip-7GeE5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:12 INFO - Running setup.py (path:/tmp/pip-8VT2Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:10:12 INFO - Running setup.py (path:/tmp/pip-wpdKn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:10:12 INFO - Running setup.py (path:/tmp/pip-vg1XS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:10:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:10:12 INFO - Running setup.py install for manifestparser 06:10:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:10:12 INFO - Running setup.py install for mozcrash 06:10:12 INFO - Running setup.py install for mozdebug 06:10:13 INFO - Running setup.py install for mozdevice 06:10:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Running setup.py install for mozfile 06:10:13 INFO - Running setup.py install for mozhttpd 06:10:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Running setup.py install for mozinfo 06:10:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Running setup.py install for mozInstall 06:10:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Running setup.py install for mozleak 06:10:13 INFO - Running setup.py install for mozlog 06:10:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Running setup.py install for moznetwork 06:10:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:10:13 INFO - Running setup.py install for mozprocess 06:10:13 INFO - Running setup.py install for mozprofile 06:10:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:10:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:10:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:10:14 INFO - Running setup.py install for mozrunner 06:10:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:10:14 INFO - Running setup.py install for mozscreenshot 06:10:14 INFO - Running setup.py install for moztest 06:10:14 INFO - Running setup.py install for mozversion 06:10:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:10:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:10:14 INFO - Cleaning up... 06:10:14 INFO - Return code: 0 06:10:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:10:14 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')]} 06:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:14 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')]} 06:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:14 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:14 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 06:10:14 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 06:10:14 INFO - Using env: {'DISPLAY': ':0', 06:10:14 INFO - 'HOME': '/home/cltbld', 06:10:14 INFO - 'LANG': 'en_US.UTF-8', 06:10:14 INFO - 'LANGUAGE': 'en_US:en', 06:10:14 INFO - 'LOGNAME': 'cltbld', 06:10:14 INFO - 'MAIL': '/var/mail/cltbld', 06:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:14 INFO - 'MOZ_NO_REMOTE': '1', 06:10:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:14 INFO - 'NO_EM_RESTART': '1', 06:10:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:14 INFO - 'PWD': '/builds/slave/test', 06:10:14 INFO - 'SHELL': '/bin/bash', 06:10:14 INFO - 'SHLVL': '1', 06:10:14 INFO - 'TERM': 'linux', 06:10:14 INFO - 'TMOUT': '86400', 06:10:14 INFO - 'USER': 'cltbld', 06:10:14 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:14 INFO - '_': '/tools/buildbot/bin/python'} 06:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:10:14 INFO - Running setup.py (path:/tmp/pip-9moZ6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:10:14 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)) 06:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:10:14 INFO - Running setup.py (path:/tmp/pip-k1hvlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:10:14 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)) 06:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:10:14 INFO - Running setup.py (path:/tmp/pip-iAzyL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:10:14 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)) 06:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:10:14 INFO - Running setup.py (path:/tmp/pip-BI0yUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:10:15 INFO - Running setup.py (path:/tmp/pip-SyeEzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:10:15 INFO - Running setup.py (path:/tmp/pip-8V1XIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:10:15 INFO - Running setup.py (path:/tmp/pip-fhXgyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:10:15 INFO - Running setup.py (path:/tmp/pip-j61nQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:10:15 INFO - Running setup.py (path:/tmp/pip-MTIAk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:10:15 INFO - Running setup.py (path:/tmp/pip-0UEdVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:10:15 INFO - Running setup.py (path:/tmp/pip-LC_ztT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:10:15 INFO - Running setup.py (path:/tmp/pip-9X1P5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:10:15 INFO - Running setup.py (path:/tmp/pip-v1s28c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:10:15 INFO - Running setup.py (path:/tmp/pip-R7pC27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:15 INFO - Running setup.py (path:/tmp/pip-UnbJtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:10:15 INFO - Running setup.py (path:/tmp/pip-IccSi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:10:15 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)) 06:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:10:15 INFO - Running setup.py (path:/tmp/pip-Rtfx9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:10:15 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)) 06:10:15 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)) 06:10:15 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)) 06:10:15 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)) 06:10:15 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)) 06:10:15 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)) 06:10:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:10:15 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 06:10:15 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 06:10:15 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 06:10:15 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 06:10:17 INFO - Downloading blessings-1.6.tar.gz 06:10:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:10:17 INFO - Installing collected packages: blessings 06:10:17 INFO - Running setup.py install for blessings 06:10:17 INFO - Successfully installed blessings 06:10:17 INFO - Cleaning up... 06:10:17 INFO - Return code: 0 06:10:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:10:17 INFO - Reading from file tmpfile_stdout 06:10:17 INFO - Current package versions: 06:10:17 INFO - argparse == 1.2.1 06:10:17 INFO - blessings == 1.6 06:10:17 INFO - blobuploader == 1.2.4 06:10:17 INFO - docopt == 0.6.1 06:10:17 INFO - functools32 == 3.2.3-2 06:10:17 INFO - jsonschema == 2.5.1 06:10:17 INFO - manifestparser == 1.1 06:10:17 INFO - mozInstall == 1.12 06:10:17 INFO - mozcrash == 1.0 06:10:17 INFO - mozdebug == 0.1 06:10:17 INFO - mozdevice == 0.49 06:10:17 INFO - mozfile == 1.2 06:10:17 INFO - mozhttpd == 0.7 06:10:17 INFO - mozinfo == 0.9 06:10:17 INFO - mozleak == 0.1 06:10:17 INFO - mozlog == 3.4 06:10:17 INFO - moznetwork == 0.27 06:10:17 INFO - mozprocess == 0.25 06:10:17 INFO - mozprofile == 0.28 06:10:17 INFO - mozrunner == 6.13 06:10:17 INFO - mozscreenshot == 0.1 06:10:17 INFO - mozsystemmonitor == 0.3 06:10:17 INFO - moztest == 0.8 06:10:17 INFO - mozversion == 1.4 06:10:17 INFO - psutil == 3.1.1 06:10:17 INFO - requests == 1.2.3 06:10:17 INFO - wsgiref == 0.1.2 06:10:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:10:17 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')]} 06:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:17 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')]} 06:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:17 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:17 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 06:10:17 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 06:10:17 INFO - Using env: {'DISPLAY': ':0', 06:10:17 INFO - 'HOME': '/home/cltbld', 06:10:17 INFO - 'LANG': 'en_US.UTF-8', 06:10:17 INFO - 'LANGUAGE': 'en_US:en', 06:10:17 INFO - 'LOGNAME': 'cltbld', 06:10:17 INFO - 'MAIL': '/var/mail/cltbld', 06:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:17 INFO - 'MOZ_NO_REMOTE': '1', 06:10:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:17 INFO - 'NO_EM_RESTART': '1', 06:10:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:17 INFO - 'PWD': '/builds/slave/test', 06:10:17 INFO - 'SHELL': '/bin/bash', 06:10:17 INFO - 'SHLVL': '1', 06:10:17 INFO - 'TERM': 'linux', 06:10:17 INFO - 'TMOUT': '86400', 06:10:17 INFO - 'USER': 'cltbld', 06:10:17 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:17 INFO - '_': '/tools/buildbot/bin/python'} 06:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 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)) 06:10:17 INFO - Cleaning up... 06:10:17 INFO - Return code: 0 06:10:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:10:17 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')]} 06:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:17 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')]} 06:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:17 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 0x17f51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1833a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a93310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, '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': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', '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 06:10:17 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 06:10:17 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 06:10:17 INFO - Using env: {'DISPLAY': ':0', 06:10:17 INFO - 'HOME': '/home/cltbld', 06:10:17 INFO - 'LANG': 'en_US.UTF-8', 06:10:17 INFO - 'LANGUAGE': 'en_US:en', 06:10:17 INFO - 'LOGNAME': 'cltbld', 06:10:17 INFO - 'MAIL': '/var/mail/cltbld', 06:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:17 INFO - 'MOZ_NO_REMOTE': '1', 06:10:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:17 INFO - 'NO_EM_RESTART': '1', 06:10:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:17 INFO - 'PWD': '/builds/slave/test', 06:10:17 INFO - 'SHELL': '/bin/bash', 06:10:17 INFO - 'SHLVL': '1', 06:10:17 INFO - 'TERM': 'linux', 06:10:17 INFO - 'TMOUT': '86400', 06:10:17 INFO - 'USER': 'cltbld', 06:10:17 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:17 INFO - '_': '/tools/buildbot/bin/python'} 06:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:10:17 INFO - Cleaning up... 06:10:17 INFO - Return code: 0 06:10:17 INFO - Running post-action listener: _resource_record_post_action 06:10:17 INFO - Running post-action listener: _start_resource_monitoring 06:10:17 INFO - Starting resource monitoring. 06:10:17 INFO - [mozharness: 2017-05-25 13:10:17.983910Z] Finished create-virtualenv step (success) 06:10:17 INFO - [mozharness: 2017-05-25 13:10:17.984378Z] Running install step. 06:10:17 INFO - Running pre-action listener: _resource_record_pre_action 06:10:17 INFO - Running main action method: install 06:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:10:18 INFO - Reading from file tmpfile_stdout 06:10:18 INFO - Detecting whether we're running mozinstall >=1.0... 06:10:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:10:18 INFO - Reading from file tmpfile_stdout 06:10:18 INFO - Output received: 06:10:18 INFO - Usage: mozinstall [options] installer 06:10:18 INFO - Options: 06:10:18 INFO - -h, --help show this help message and exit 06:10:18 INFO - -d DEST, --destination=DEST 06:10:18 INFO - Directory to install application into. [default: 06:10:18 INFO - "/builds/slave/test"] 06:10:18 INFO - --app=APP Application being installed. [default: firefox] 06:10:18 INFO - mkdir: /builds/slave/test/build/application 06:10:18 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'] 06:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:10:32 INFO - Reading from file tmpfile_stdout 06:10:32 INFO - Output received: 06:10:32 INFO - /builds/slave/test/build/application/firefox/firefox 06:10:32 INFO - Running post-action listener: _resource_record_post_action 06:10:32 INFO - [mozharness: 2017-05-25 13:10:32.327740Z] Finished install step (success) 06:10:32 INFO - [mozharness: 2017-05-25 13:10:32.327984Z] Running run-tests step. 06:10:32 INFO - Running pre-action listener: _resource_record_pre_action 06:10:32 INFO - Running main action method: run_tests 06:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:10:32 INFO - Python 2.7.3 06:10:32 INFO - Return code: 0 06:10:32 INFO - grabbing minidump binary from tooltool 06:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:32 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 0x1a90400>, '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 0x1a913b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a91840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:10:32 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 06:10:32 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 06:10:32 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 06:10:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:10:32 INFO - Return code: 0 06:10:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:10:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:10:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:10:32 INFO - ENV: RUST_BACKTRACE is now 1 06:10:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:10:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:10:32 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/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 06:10:32 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/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 06:10:32 INFO - Using env: {'DISPLAY': ':0', 06:10:32 INFO - 'HOME': '/home/cltbld', 06:10:32 INFO - 'LANG': 'en_US.UTF-8', 06:10:32 INFO - 'LANGUAGE': 'en_US:en', 06:10:32 INFO - 'LOGNAME': 'cltbld', 06:10:32 INFO - 'MAIL': '/var/mail/cltbld', 06:10:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:10:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:32 INFO - 'MOZ_NO_REMOTE': '1', 06:10:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:10:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:10:32 INFO - 'NO_EM_RESTART': '1', 06:10:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:32 INFO - 'PWD': '/builds/slave/test', 06:10:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:10:32 INFO - 'RUST_BACKTRACE': '1', 06:10:32 INFO - 'SHELL': '/bin/bash', 06:10:32 INFO - 'SHLVL': '1', 06:10:32 INFO - 'TERM': 'linux', 06:10:32 INFO - 'TMOUT': '86400', 06:10:32 INFO - 'USER': 'cltbld', 06:10:32 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163', 06:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:32 INFO - '_': '/tools/buildbot/bin/python'} 06:10:32 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/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 06:10:32 INFO - mozversion application_buildid: 20170525121611 06:10:32 INFO - mozversion application_changeset: 02e5b298abedc3fd641a8db70efd59009109ed82 06:10:32 INFO - mozversion application_display_name: Firefox 06:10:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:10:32 INFO - mozversion application_name: Firefox 06:10:32 INFO - mozversion application_remotingname: firefox 06:10:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:10:32 INFO - mozversion application_vendor: Mozilla 06:10:32 INFO - mozversion application_version: 54.0 06:10:32 INFO - mozversion platform_buildid: 20170525121611 06:10:32 INFO - mozversion platform_changeset: 02e5b298abedc3fd641a8db70efd59009109ed82 06:10:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:10:32 INFO - mozversion platform_version: 54.0 06:10:32 INFO - using testdate: 1495717832 06:10:32 INFO - actual date: 1495717832 06:10:32 INFO - starting webserver on 'localhost:47227' 06:10:32 INFO - SUITE-START | Running 2 tests 06:10:32 INFO - TEST-START | dromaeo_css 06:10:32 INFO - operating with platform_type : linux_ 06:10:32 INFO - Initialising browser for dromaeo_css test... 06:10:32 INFO - TEST-INFO | started process 14131 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCJ0uim/profile http://localhost:47227/getInfo.html) 06:10:37 INFO - PROCESS | 14131 | __metrics Screen width/height:1600/1200 06:10:37 INFO - PROCESS | 14131 | colorDepth:24 06:10:37 INFO - PROCESS | 14131 | Browser inner width/height: 1024/697 06:10:37 INFO - PROCESS | 14131 | __metrics 06:10:38 INFO - PROCESS | 14131 | 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" 06:10:38 INFO - PROCESS | 14131 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:10:38 INFO - PROCESS | 14131 | console.error: PushService: 06:10:38 INFO - PROCESS | 14131 | stateChangeProcessEnqueue: Error transitioning state 06:10:38 INFO - PROCESS | 14131 | UnknownError 06:10:39 INFO - TEST-INFO | 14131: exit 0 06:10:39 INFO - Browser initialized. 06:10:39 INFO - Running cycle 1/1 for dromaeo_css test... 06:10:39 INFO - TEST-INFO | started process 14224 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCJ0uim/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:10:51 INFO - PROCESS | 14224 | RSS: Main: 141340672 06:10:51 INFO - PROCESS | 14224 | 06:13:52 INFO - PROCESS | 14224 | Cycle 1(1): loaded http://localhost:47227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47227/tests/dromaeo/cssquery-ext.html) 06:13:54 INFO - PROCESS | 14224 | RSS: Main: 278933504 06:13:54 INFO - PROCESS | 14224 | 06:16:56 INFO - PROCESS | 14224 | Cycle 1(1): loaded http://localhost:47227/tests/dromaeo/cssquery-ext.html (next: http://localhost:47227/tests/dromaeo/cssquery-jquery.html) 06:16:56 INFO - PROCESS | 14224 | RSS: Main: 230473728 06:16:56 INFO - PROCESS | 14224 | 06:19:58 INFO - PROCESS | 14224 | Cycle 1(1): loaded http://localhost:47227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47227/tests/dromaeo/cssquery-mootools.html) 06:19:59 INFO - PROCESS | 14224 | RSS: Main: 204996608 06:19:59 INFO - PROCESS | 14224 | 06:23:01 INFO - PROCESS | 14224 | Cycle 1(1): loaded http://localhost:47227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47227/tests/dromaeo/cssquery-prototype.html) 06:23:01 INFO - PROCESS | 14224 | RSS: Main: 174862336 06:23:01 INFO - PROCESS | 14224 | 06:26:04 INFO - PROCESS | 14224 | Cycle 1(1): loaded http://localhost:47227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47227/tests/dromaeo/cssquery-yui.html) 06:26:04 INFO - PROCESS | 14224 | RSS: Main: 178036736 06:26:04 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | Cycle 1(1): loaded http://localhost:47227/tests/dromaeo/cssquery-yui.html (next: http://localhost:47227/tests/dromaeo/cssquery-dojo.html) 06:29:07 INFO - PROCESS | 14224 | RSS: Main: 262963200 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | __start_tp_report 06:29:07 INFO - PROCESS | 14224 | _x_x_mozilla_page_load 06:29:07 INFO - PROCESS | 14224 | _x_x_mozilla_page_load_details 06:29:07 INFO - PROCESS | 14224 | |i|pagename|runs| 06:29:07 INFO - PROCESS | 14224 | |0;dojo.html;3356.983215083925;3398.5581874356344;3402.285520040792;3416.6241713411505;3445.052610532105;5613.129964855449;5686.0617998030375;5705.372409035002;5719.656820590762;5720.141978703192;5648.774049038044;5718.08510638298;5723.656580605167;5749.8275051748515;5784.855378615537;5238.842834714946;5247.842564723055;5250.212468129792;5274.551663108621;5316.494932981361;5103.693778373291;5132.050570644424;5321.228421878825;5328.5470734987575;5329.147336426173;5070.239464080399;5123.052235336456;5138.640295179339;5141.845744627667;5142.228665700156;5245.86885327866;5256.737163141846;5265.236540701577;5268.236105764681;5290.523852853262;5045.293658887758;5114.386273647149;5182.352205974254;5223.6343455958095;5228.477152284741;5579.302587176562;5633.126865335879;5663.575231857586;5678.971605141989;5694.943050569483;6079.726412311413;6109.969450152721;6123.969380153116;6124.2650881894015;6134.8773024539705;6176.320604733476;6184.783532576338;6194.752209911643;6196.566240363177;6202.534809889231;5583.106702927553;5610.410906854803;5692.345380281238;5717.2281741965135;5731.770729170787;6285.402886725754;6286.18279623646;6291.842703932347;6294.15028971083;6296.0555916612875;5986.910196347058;6001.549883758736;6006.309274433409;6041.093835924558;6045.939540604626;5846.678432686158;5890.9410905891245;5985.461308482258;6010.338862725097;6091.14723938649;6102.0541816018585;6111.144439778434;6119.051547010221;6120.387961203933;6127.20346354971;5879.794207202727;5918.023526118142;6031.155638210654;6036.185115009505;6116.457510225599;4598.0803839232285;4637.327587499861;4650.116477869262;4652.651051171176;4671.929921051187;4018.3369743991916;4021.4973128358956;4152.688548358885;4166.708330416842;4229.302165142717;5746.626469279567;5768.134779782982;5833.7083145842535;5857.47282744555;5877.38287479817;6167.722452489649;6171.105189747462;6183.072539119168;6183.443490085914;6186.752529898754;5655.293088363955;5839.941600584025;5841.591088623755;5851.034579294369;5851.795187168514;5109.233614957786;5131.409887862869;5157.406898206755;5185.403678577012;5216.9739151304;6367.522435817332;6412.551121421456;6415.422611964759;6416.454601358937;6418.261899881573;6084.482818960261;6132.693365331805;6141.447269745744;6143.84640383994;6146.0473626587955;6169.043798211284;6216.595921265103;6289.748410063729;6310.463610593151;6326.43062124411;6077.05805600115;6078.817635470943;6080.2399700037495;6082.178905847831;6106.938930610637;6166.722497487534;6169.753209871555;6182.041783523561;6182.28903676083;6192.752289908353;5722.713864306851;5836.562257830595;5839.299284085937;5840.445157710011;6014.27828660546;5928.081147422156;5937.435943585352;5942.465178133989;5953.464188223081;5967.015442451947;5873.207117039145;5914.763409463745;5929.911051334319;5942.1383899335215;5947.553933454921;5767.423257674367;5799.710014499343;5829.47534721877;5923.614965027259;5949.3753155918;5828.242328497268;5833.241678581757;5837.357890632112;5859.824205273849;5868.970655146697 06:29:07 INFO - PROCESS | 14224 | |1;ext.html;754.8414832885094;830.7590798668384;847.7160151349298;893.6693423433327;902.0888902208767;31299.374012519736;31626.367472650534;34261.314773704544;34265.31469370611;43426.131477370436;46830.29754553684;47046.52953470464;47074.293885591745;47107;47132.764336178356;9110.18008379244;9256.67601633943;9263.21262692672;9269.397489163217;9276.304277179204;7537.585432801194;7643.312101910827;9080.909190908105;9179.403338782959;9182.724518264462;10755.516001779939;11027.724306892353;11430.599929002487;11432.142589305793;11440.427978601077;10934.671959841218;10977.176711746572;10994.890051099466;10995.945020274887;11002.669919902415;10760.73098172544;10810.729731756768;10824.13406927444;10825.783484330348;10828.566857325697;42579.57420425787;42729.14541709149;42812.35781463281;42961.57038429607;43015.56984430147;4308;4308.46144231971;4348.673849461303;4429.11417716458;4454.33185022246;30004.69995300041;30047;31262.843685781652;31493.212669683442;31878.521822172686;4254.383114448418;4390.846320378771;4397.560243975577;4425.491068527129;4476;227693;227705;227897;228995;229034.85482572648;52673.473265267625;52789.2081618776;52829;52893.7355313221;52915.73542132265;55329.446705533235;55423.44576554264;55477.445225548036;55478.16782748262;55542;15078.924605376902;15400.46098386551;15404.383824647113;15415.460458883885;15424.46014389491;13305.467781288833;13320.600381988363;13331.733365332639;13360.131591446525;13366.732665346639;11305.547778088949;11515.827262591069;12505.874941250653;12579.245245285312;13044.54344097952;5492.450754924555;5526.065876943118;5545.61180717354;5571.359293681197;5604.859878502988;1004.7136566078633;1011.893751156118;1018.0379541333369;1018.592562974816;1021.6526381030485;28742;28793.280167995967;28841.134765957057;29066.41867162645;29215.561766573093;1070.240129508058;1094.6442406218011;1102.5038732570376;1115.185914282562;1115.520326259716;14075.225862577656;15197.240137993056;15218.771718424232;15219.162946037854;15292.853036022343;279478;283635;284615;285350;285480;48595.5140448598;49636.50363496391;55469;55813.16280255799;55874.72062639661;1465.6482444213525;1469.5885152157414;1469.7648376259747;1481.4148411377446;1506.6384067823644;27502.724972750017;28750.424991499636;29023.12930612085;29109.7089029107;29267.560986585646;37642;45813.541864580926;46551.301730473395;51479.485205147466;51559.742201288755;316555;317463;318380;318693;320597;28647.713522864506;29046.27384315409;30674.846625767623;30754.538681920236;30884.0734777957;40786.38820417756;40834.1833163341;40973.975650608976;41012.58987410207;41877.37183942182;31986.68013319837;32518.67481325251;32652;32699.182520437178;32718.018459446255;31303.530447042856;31419.685803141678;31702.524462133548;31811.36377272488;32606.51090233601;35741.82129089338;36296.27407451889;36642.26715465623;36671;36728.08179795527;29773.404531908807;29838;30028;30305;30729.231769205948;24645.753542464347;24737.628935565623;25050;25220.24339269825;25234.495310094062 06:29:07 INFO - PROCESS | 14224 | |2;jquery.html;458.53916813602325;473.1790343753587;478.7797613097975;481.73504572485126;498.14070727994203;20287.695684564726;21453.14187432503;21824.563508729814;21885.562288754234;22082.11671533137;21776.455588610293;22048.33854984353;22228.777712222873;22261;22678.09287628493;6765.864682706343;6835.658217089151;6978.825529361756;6984.301569843001;6990.405815505663;6855.794326170223;6956.373926346628;7418.406527477789;7700.152983171846;7742;7484.476086673935;7866.7639970800965;7867.567283799389;7879.369650427952;7879.566623835687;7767.106782720003;7822.765317040498;7934.960325198337;7946.523208607473;7948.324392426651;7709.922900770976;7801.882971755428;7804.414668899798;7819.491733037391;7819.882701759479;25499.617505737428;26038.739612603822;26477;26491.602625960626;26548.070817521486;3088.8146711197173;3210.727088197506;3231.579894613709;3235.2558911450265;3278.327942771726;16631.83368166315;16676.58308542284;17023;17131;17679.20443580042;2238.921637742671;2282.121383267454;2285.8285628577923;2296.7014288142436;2314;72821.63589182073;106118;111572;111608.88391116066;116627;17803.910980445016;18197.27125923035;18236.726449103273;18341.81658183428;18606.162722677513;31205.375892482476;31285.217869553442;31320;31640.36719265648;31890.681093189236;10092.545835437277;10186;10245.948770256251;10258.12805911491;10529.789404211873;8303.501789892625;8402.873956890651;8434.62044208012;8607;8625.69810056645;9302.767430814283;9414.529273536296;9516.000819913948;9530.857037144448;9620.567074481525;3316.369889720945;3476.495908093312;3586.605473397924;3772.5661548922226;3829.8659546915724;952.1573407534382;977.237754551451;982.8820541535061;988.7874107067015;989.2679417231197;20846.583068338547;21466.57066858654;21566.56866862654;21589.812560309332;21644.891775541022;998.3361064891766;1005.9748506287401;1010.7978404319166;1012.3470361616863;1015.4618052432223;11274.774504509864;11494.770104597861;12389.75220495585;12468.563600273947;12544.811778101508;326442;636140;640885;656942;661016;91163;100274;101501;118391;119514.4024279873;1311.9737605247842;1357.6130802721177;1388.687545302299;1392.7005693775684;1394.6652803327129;19406.8059319405;19690.606187875877;19727.40817775469;19794.50513737168;19829.900850495655;51806.48193518016;52783.736081319345;52804;52878;52996;67002;67007.66496167488;68024;68121;69029.65485172543;12543.372831358578;12769.489220431078;12925.676858078623;13015.414306356048;13113.672158196121;13212.471501139848;13708.24604646754;14021.789673155115;14262;14299.714005720036;12595.87404125947;12609.928156106833;12627.74744505123;12698.619041428772;12899.548515802277;10721.517531710935;11286.435678215892;11540.422978850856;11815.054795616503;11953.701157471132;12376.938115309365;12715.364231788559;12762;12797;12893.677658058623;12577.874221258036;12594.559190428292;12684.365781711062;12711.55509557161;12720.427580758704;12053.035757139232;12268.693282667648;12620.368981550702;12727;12897.355132243538 06:29:07 INFO - PROCESS | 14224 | |3;mootools.html;1403.649087728068;1564.280431001739;1567.0362726922947;1604.5106242596012;1611.0172794595294;9490.66782662606;11138.832917506244;11460.656180314583;11820.527178912833;11820.58627948022;11529.654110376681;11618.535258589647;11642;11644.475998580063;11655;2013.2047841102756;2339.9883000584973;2373.620220764678;2377.6552399902002;2379.1435083369975;2069.793020697933;2074.035573458349;2094.486850721575;2329.8252631052655;2399.3401814500976;2378.95242095158;2379.262428647125;2382.0709923129994;2388;2388.820838437124;2268.6937263469467;2288.439332363573;2300.0454811253308;2303.827212959018;2305.5965206088867;2220.9000594973104;2242.0807468937655;2245.65192395179;2247.045005872514;2248.460369511322;11280.717982050515;11527.308361498337;11640.825387619192;11659.475323610457;11668.35824029678;5656;5810.796622118206;5812.622179558315;5815.941840581582;5816.82549523515;3467.358538670329;3524.1718196223865;3560.9702469592344;3594.4069228577514;3626.9455958160647;1404.4592831759744;1414.215110613609;1440.6182361674164;1473.0866862545183;1475.611693676516;16795.83204167955;16876.156192190465;17008.91495542527;17023.659526809395;17024.319027238897;4601.838935637236;4661.440627124767;4756.6432517561325;4855.174620314484;4855.344528488679;14359.497417590333;14405;14410.495632652806;14412.351444185037;14412.567622971328;5872.207252021008;5928.703564821742;5955.702214889238;5958.910616340759;5959.165716799652;5252.474752524793;5338.199270109437;5384.003959267548;5387.757550910218;5425.511703946664;3597.172650290448;3721.627837216311;4055.97972010144;4179.435776170239;4181.226473102486;1053.5996321397972;1073.1843798713035;1075.3171735947728;1084.4415126210008;1091.4924560079442;1036.4817591204398;1038.0605551975475;1047.8690163729534;1048.4286064094933;1053.0890779475699;6765.323467653196;6816.863662726718;6908.3091690830515;7012.859742805115;7169.749058782918;1069.1340014588209;1069.8181309177457;1098.4562641492512;1103.0988500244466;1103.8289065194908;4216.578342165821;4309.547497512716;4324.481062272547;4337.978310108492;4344.066025804467;14575.125492470486;15165.924170379078;15207;15215.239238038053;15217.6195595111;14918.627034324012;14975.475858344906;15095.471658492119;15364.769528457082;15371;11069.335839849635;11102.222844400814;11177.49701263445;11191.66425007251;11196.04833589154;6569.441597464269;6729.764458243938;6759.290274521104;6770.1537307836525;6809;13048.869511304765;13109.410076546388;13382;13418.993575482073;13424.597262082154;13431;13486.86513134856;13494.52769153075;13551.661208469866;13643.522476713664;9968.651097211852;9982.850257246293;10147.137493313152;10165.440900750235;10175.84736228972;11108.277961932448;11113.777724445628;11127.888721112686;11130.833037504282;11250.774984500427;9563.09150630689;9678.95160524193;9709.854352184864;9816.754581135528;9849.409035457895;9136.634534618806;9299.395539289924;9394.342396032212;9636.373635713657;9659.275554333313;8431.620577073923;8510.148985101441;8514.276286515467;8592.742217733477;8616.870746938675;6254.593451425642;6263.1857858479925;6347.936520634735;6390.392912673288;6465.741370345134;5996.400359963969;6073.331933487401;6108.144859719554;6168.167297414791;6208.2239720034995 06:29:07 INFO - PROCESS | 14224 | |4;prototype.html;415.792103948026;423.0819122504167;436.51982818899194;443.9556044395559;444.95550444955506;5209.609279304058;5233.508450091725;5275.920861187085;5285.28648632434;5382.59630527711;5156.478838742571;5171.818986335479;5179.715115668637;5198.506141916512;5220.399654039787;4429.667774916894;4435.3125265583885;4469.195544801934;4548.863534093983;4581.931271030937;4552.271636538155;4560.71964017991;4561.315802629599;4585.839495617654;4586.564276393754;4554.180247555439;4571.47428045774;4571.657125715585;4573.405457290548;4577.473590537097;4419.734815911055;4543.727376357429;4554.908901821978;4606.9078618427775;4615.215413379717;4338.1540599583095;4350.934735978963;4392.275274579691;4408.647308215335;4409.625181859546;5102.974485127587;5115.488451154892;5122.538971492583;5130.1791713326065;5131.871703207413;5094.923576146323;5208.5051920067535;5226.843194704165;5268;5273.551748101416;5458.263134476874;5459.044667183267;5482.725863706799;5497.340319161686;5510.972445137789;4797.328374027639;4801.7118972861745;4807.447143578498;4869.878253043702;4885.584725298354;5509;5737.999999999958;5741.19623252745;5748.281464816898;5759.889659583861;5052.873678158076;5057.317262169634;5079.97460012705;5099.745012749348;5111.463296353903;5079.161938280216;5102.107131252016;5114.514121158484;5168.586513078944;5182.663126896739;5030.597552195815;5226.63413561047;5226.7717086484645;5226.869328266824;5255.211718242294;5404.567634589223;5449.536789372868;5461.208124821878;5461.590380721462;5474.616776825584;1673.2554013463891;1745.6683230186225;1758.156085079169;1759.3050744955926;1766.0375095572945;1402.6072699644117;1412.7668934625672;1446.8987170897935;1452.6295794572316;1472.889533285008;5349.705766182898;5351.9296140772;5354.571634269249;5378.821577261216;5411.729413529387;5613.8596535086135;5617.9438205618235;5622.269105016322;5624.803131890364;5627.240322556484;5101.234814777738;5341.145416733304;5378.650387724785;5387;5451.754671039813;2981.8061825981677;2982.7464665503676;2990.2524368907775;2997.5461900978007;2999.820010799359;5751.597388182787;5815.79644712433;5821.214136091573;5925.466707996301;5928.466438020512;5336.706481143575;5491.835244942658;5612.326520817528;5668.291463567054;5679.943200567942;5668.8866222675315;5692.800751973661;5806.564507661942;5813.651180929242;5833.241678581757;5567.69377684215;5572.60991730575;5585.078462053716;5618.971905140448;5620.8875822482505;5379.838604841861;5512.503874651301;5540.944590554204;5552.5280351168985;5558.943800677858;5796.507296879812;5799.3040835100055;5813.192359004858;5815.098659707752;5823.4467725566;4569.405977222939;4569.588737013684;4669.673122881366;4707.91717904877;4747.715137091785;5389.299391079135;5530.419305972976;5559.305086864142;5577.27495425592;5606.971965140311;5431.456854314537;5462.863428414322;5467.425920278313;5473.917891231715;5483.369412517452;5303.647569869659;5440.564754819526;5468.9179662304305;5474.2609747683555;5501.587380946365;5411.404745478073;5417.702026388429;5489.313835770528;5497.3952865184;5511.476409741068;5524.171374293727;5558.416366281483;5558.610897237317;5561;5562.800085935093;5243.449437808975;5293.947060529345;5357.767713426012;5358.812441564526;5414.837554873203 06:29:07 INFO - PROCESS | 14224 | |5;yui.html;885.720997885666;921.6772937647737;931.4225180388161;931.4504442378995;978.0341513557747;17352.219150138244;18380.448586542407;19853.205871765113;19872.205111795512;19882.90058549707;17455.21451534681;17999.730004049918;18034.188461519232;18238.544036399097;18252;884.9380543361967;908.4594666173616;912.370464379576;913.7304495173939;914.5655813488606;885.4952676974127;887.6005797391167;887.8535041718109;907.2061945797427;910.744991402405;944.329526036515;946.5456580841201;949.1125797379437;949.349695458613;949.6818565780471;928.3130483442271;929.8465753150722;932.8320902237595;937.1893312284893;937.5640327247828;684.7055766020609;690.7685925215059;690.9585424874524;691.4433880726014;692.1140939597324;18412.815871841245;18565.257389704395;18601.162947667388;19171.32900348495;19364.419067428;16139.83860161395;16204.675906481805;16284.674306513922;16612;16672.33310667572;13806.93096534521;13958.651033724136;14220.92889535556;14646.34091465876;14909.701805963929;4085.1829634073306;4085.5301640311445;4086.182763447293;4165.937510937339;4245.617894389489;96861.51569242179;96890;97227;98235.50882245615;100777;12465.813012804816;12497.75004499905;12511.3744312784;12585.93707031459;12608.306543140032;24468.755312447003;25041.87479062593;25179.49641007206;25438.745612543637;25891.611625825626;9076.41003334781;9529.523523823782;9769.951150244346;9828.950855245821;9895.109440150422;5120.92318615221;5255.605829562798;5271.42014378418;5291.3121294231505;5369.979703856255;3949.447077409165;4012.53855806586;4028.073543085048;4035.838566457309;4069.8575549855614;689.4381079420257;690.8860038093658;694.7741983855267;698.1099098649162;698.6436917172279;843.4306842881031;857.4683696108452;868.8088620503479;871.5859966515854;876.1851478125277;11709.941450292865;11715.297082175097;11982.58060967862;12118.939405302917;12198.268103913793;832.3878134460326;846.724814435311;864.8443280209497;870.4211699219948;874.1477059866579;5565.693886836263;5578.358488773762;5724.227229324071;5779.682117483495;5844.8538786529825;83837.58081209555;87129;88112.5594372024;89797;90661.54669226744;46819.063618727436;52205;52698.20952685713;65771.67114164494;71499.64250178715;64477;68187;69136.6543167291;69159.65420172967;69173.65413173003;11558.133140014364;11923.701907452383;12694.746105077662;12804.807927880904;12918.93540532329;41644.791776040925;42576;43084.569154308054;43660.126797463236;44113.93824606924;45957.540424595325;55552.72223638856;56410.43589564216;57429.71285143548;58365.70817145887;14033.228172450614;14439.278036097943;14462.493812716504;14492.565223043326;14670.339834707676;16951.66096678035;17262.568435789206;17451;18318.450446486626;18493.53766155857;15629.609259768597;16492.34030638761;16778.1610919456;16802.57993550122;17363.2186551608;15189.544313670607;15849.330818198689;16019.519414417586;16396.83603163953;16508.504744857673;15044.323005465;15166.241687915339;15792.684146317239;15801.13093779853;15806.209689515708;11426.25729327591;11706.1220408468;12139.514419423478;12439.440225189706;12477.25136491777;13493.932530337286;13620.250886201355;13681.863181368059;13687.24720140362;13835.169889806644 06:29:07 INFO - PROCESS | 14224 | __end_tp_report 06:29:07 INFO - PROCESS | 14224 | __start_cc_report 06:29:07 INFO - PROCESS | 14224 | _x_x_mozilla_cycle_collect,1387 06:29:07 INFO - PROCESS | 14224 | __end_cc_report 06:29:07 INFO - PROCESS | 14224 | __startTimestamp1495718947787__endTimestamp 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | ------- Summary: start ------- 06:29:07 INFO - PROCESS | 14224 | Number of tests: 6 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | [#0] dojo.html Cycles:165 Average:5669.17 Median:5851.03 stddev:640.42 (10.9%) stddev-sans-first:616.17 06:29:07 INFO - PROCESS | 14224 | Values: 3357.0 3398.6 3402.3 3416.6 3445.1 5613.1 5686.1 5705.4 5719.7 5720.1 5648.8 5718.1 5723.7 5749.8 5784.9 5238.8 5247.8 5250.2 5274.6 5316.5 5103.7 5132.1 5321.2 5328.5 5329.1 5070.2 5123.1 5138.6 5141.8 5142.2 5245.9 5256.7 5265.2 5268.2 5290.5 5045.3 5114.4 5182.4 5223.6 5228.5 5579.3 5633.1 5663.6 5679.0 5694.9 6079.7 6110.0 6124.0 6124.3 6134.9 6176.3 6184.8 6194.8 6196.6 6202.5 5583.1 5610.4 5692.3 5717.2 5731.8 6285.4 6286.2 6291.8 6294.2 6296.1 5986.9 6001.5 6006.3 6041.1 6045.9 5846.7 5890.9 5985.5 6010.3 6091.1 6102.1 6111.1 6119.1 6120.4 6127.2 5879.8 5918.0 6031.2 6036.2 6116.5 4598.1 4637.3 4650.1 4652.7 4671.9 4018.3 4021.5 4152.7 4166.7 4229.3 5746.6 5768.1 5833.7 5857.5 5877.4 6167.7 6171.1 6183.1 6183.4 6186.8 5655.3 5839.9 5841.6 5851.0 5851.8 5109.2 5131.4 5157.4 5185.4 5217.0 6367.5 6412.6 6415.4 6416.5 6418.3 6084.5 6132.7 6141.4 6143.8 6146.0 6169.0 6216.6 6289.7 6310.5 6326.4 6077.1 6078.8 6080.2 6082.2 6106.9 6166.7 6169.8 6182.0 6182.3 6192.8 5722.7 5836.6 5839.3 5840.4 6014.3 5928.1 5937.4 5942.5 5953.5 5967.0 5873.2 5914.8 5929.9 5942.1 5947.6 5767.4 5799.7 5829.5 5923.6 5949.4 5828.2 5833.2 5837.4 5859.8 5869.0 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | [#1] ext.html Cycles:180 Average:44555.59 Median:31456.45 stddev:72884.57 (231.7%) stddev-sans-first:73014.83 06:29:07 INFO - PROCESS | 14224 | Values: 754.8 830.8 847.7 893.7 902.1 31299.4 31626.4 34261.3 34265.3 43426.1 46830.3 47046.5 47074.3 47107.0 47132.8 9110.2 9256.7 9263.2 9269.4 9276.3 7537.6 7643.3 9080.9 9179.4 9182.7 10755.5 11027.7 11430.6 11432.1 11440.4 10934.7 10977.2 10994.9 10995.9 11002.7 10760.7 10810.7 10824.1 10825.8 10828.6 42579.6 42729.1 42812.4 42961.6 43015.6 4308.0 4308.5 4348.7 4429.1 4454.3 30004.7 30047.0 31262.8 31493.2 31878.5 4254.4 4390.8 4397.6 4425.5 4476.0 227693.0 227705.0 227897.0 228995.0 229034.9 52673.5 52789.2 52829.0 52893.7 52915.7 55329.4 55423.4 55477.4 55478.2 55542.0 15078.9 15400.5 15404.4 15415.5 15424.5 13305.5 13320.6 13331.7 13360.1 13366.7 11305.5 11515.8 12505.9 12579.2 13044.5 5492.5 5526.1 5545.6 5571.4 5604.9 1004.7 1011.9 1018.0 1018.6 1021.7 28742.0 28793.3 28841.1 29066.4 29215.6 1070.2 1094.6 1102.5 1115.2 1115.5 14075.2 15197.2 15218.8 15219.2 15292.9 279478.0 283635.0 284615.0 285350.0 285480.0 48595.5 49636.5 55469.0 55813.2 55874.7 1465.6 1469.6 1469.8 1481.4 1506.6 27502.7 28750.4 29023.1 29109.7 29267.6 37642.0 45813.5 46551.3 51479.5 51559.7 316555.0 317463.0 318380.0 318693.0 320597.0 28647.7 29046.3 30674.8 30754.5 30884.1 40786.4 40834.2 40974.0 41012.6 41877.4 31986.7 32518.7 32652.0 32699.2 32718.0 31303.5 31419.7 31702.5 31811.4 32606.5 35741.8 36296.3 36642.3 36671.0 36728.1 29773.4 29838.0 30028.0 30305.0 30729.2 24645.8 24737.6 25050.0 25220.2 25234.5 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | [#2] jquery.html Cycles:180 Average:35337.45 Median:12249.96 stddev:98719.28 (805.9%) stddev-sans-first:98961.47 06:29:07 INFO - PROCESS | 14224 | Values: 458.5 473.2 478.8 481.7 498.1 20287.7 21453.1 21824.6 21885.6 22082.1 21776.5 22048.3 22228.8 22261.0 22678.1 6765.9 6835.7 6978.8 6984.3 6990.4 6855.8 6956.4 7418.4 7700.2 7742.0 7484.5 7866.8 7867.6 7879.4 7879.6 7767.1 7822.8 7935.0 7946.5 7948.3 7709.9 7801.9 7804.4 7819.5 7819.9 25499.6 26038.7 26477.0 26491.6 26548.1 3088.8 3210.7 3231.6 3235.3 3278.3 16631.8 16676.6 17023.0 17131.0 17679.2 2238.9 2282.1 2285.8 2296.7 2314.0 72821.6 106118.0 111572.0 111608.9 116627.0 17803.9 18197.3 18236.7 18341.8 18606.2 31205.4 31285.2 31320.0 31640.4 31890.7 10092.5 10186.0 10245.9 10258.1 10529.8 8303.5 8402.9 8434.6 8607.0 8625.7 9302.8 9414.5 9516.0 9530.9 9620.6 3316.4 3476.5 3586.6 3772.6 3829.9 952.2 977.2 982.9 988.8 989.3 20846.6 21466.6 21566.6 21589.8 21644.9 998.3 1006.0 1010.8 1012.3 1015.5 11274.8 11494.8 12389.8 12468.6 12544.8 326442.0 636140.0 640885.0 656942.0 661016.0 91163.0 100274.0 101501.0 118391.0 119514.4 1312.0 1357.6 1388.7 1392.7 1394.7 19406.8 19690.6 19727.4 19794.5 19829.9 51806.5 52783.7 52804.0 52878.0 52996.0 67002.0 67007.7 68024.0 68121.0 69029.7 12543.4 12769.5 12925.7 13015.4 13113.7 13212.5 13708.2 14021.8 14262.0 14299.7 12595.9 12609.9 12627.7 12698.6 12899.5 10721.5 11286.4 11540.4 11815.1 11953.7 12376.9 12715.4 12762.0 12797.0 12893.7 12577.9 12594.6 12684.4 12711.6 12720.4 12053.0 12268.7 12620.4 12727.0 12897.4 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | [#3] mootools.html Cycles:180 Average:7225.11 Median:2258.58 stddev:4735.83 (209.7%) stddev-sans-first:4728.92 06:29:07 INFO - PROCESS | 14224 | Values: 1403.6 1564.3 1567.0 1604.5 1611.0 9490.7 11138.8 11460.7 11820.5 11820.6 11529.7 11618.5 11642.0 11644.5 11655.0 2013.2 2340.0 2373.6 2377.7 2379.1 2069.8 2074.0 2094.5 2329.8 2399.3 2379.0 2379.3 2382.1 2388.0 2388.8 2268.7 2288.4 2300.0 2303.8 2305.6 2220.9 2242.1 2245.7 2247.0 2248.5 11280.7 11527.3 11640.8 11659.5 11668.4 5656.0 5810.8 5812.6 5815.9 5816.8 3467.4 3524.2 3561.0 3594.4 3626.9 1404.5 1414.2 1440.6 1473.1 1475.6 16795.8 16876.2 17008.9 17023.7 17024.3 4601.8 4661.4 4756.6 4855.2 4855.3 14359.5 14405.0 14410.5 14412.4 14412.6 5872.2 5928.7 5955.7 5958.9 5959.2 5252.5 5338.2 5384.0 5387.8 5425.5 3597.2 3721.6 4056.0 4179.4 4181.2 1053.6 1073.2 1075.3 1084.4 1091.5 1036.5 1038.1 1047.9 1048.4 1053.1 6765.3 6816.9 6908.3 7012.9 7169.7 1069.1 1069.8 1098.5 1103.1 1103.8 4216.6 4309.5 4324.5 4338.0 4344.1 14575.1 15165.9 15207.0 15215.2 15217.6 14918.6 14975.5 15095.5 15364.8 15371.0 11069.3 11102.2 11177.5 11191.7 11196.0 6569.4 6729.8 6759.3 6770.2 6809.0 13048.9 13109.4 13382.0 13419.0 13424.6 13431.0 13486.9 13494.5 13551.7 13643.5 9968.7 9982.9 10147.1 10165.4 10175.8 11108.3 11113.8 11127.9 11130.8 11250.8 9563.1 9679.0 9709.9 9816.8 9849.4 9136.6 9299.4 9394.3 9636.4 9659.3 8431.6 8510.1 8514.3 8592.7 8616.9 6254.6 6263.2 6347.9 6390.4 6465.7 5996.4 6073.3 6108.1 6168.2 6208.2 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | [#4] prototype.html Cycles:180 Average:4851.90 Median:5289.62 stddev:1238.23 (23.4%) stddev-sans-first:1196.10 06:29:07 INFO - PROCESS | 14224 | Values: 415.8 423.1 436.5 444.0 445.0 5209.6 5233.5 5275.9 5285.3 5382.6 5156.5 5171.8 5179.7 5198.5 5220.4 4429.7 4435.3 4469.2 4548.9 4581.9 4552.3 4560.7 4561.3 4585.8 4586.6 4554.2 4571.5 4571.7 4573.4 4577.5 4419.7 4543.7 4554.9 4606.9 4615.2 4338.2 4350.9 4392.3 4408.6 4409.6 5103.0 5115.5 5122.5 5130.2 5131.9 5094.9 5208.5 5226.8 5268.0 5273.6 5458.3 5459.0 5482.7 5497.3 5511.0 4797.3 4801.7 4807.4 4869.9 4885.6 5509.0 5738.0 5741.2 5748.3 5759.9 5052.9 5057.3 5080.0 5099.7 5111.5 5079.2 5102.1 5114.5 5168.6 5182.7 5030.6 5226.6 5226.8 5226.9 5255.2 5404.6 5449.5 5461.2 5461.6 5474.6 1673.3 1745.7 1758.2 1759.3 1766.0 1402.6 1412.8 1446.9 1452.6 1472.9 5349.7 5351.9 5354.6 5378.8 5411.7 5613.9 5617.9 5622.3 5624.8 5627.2 5101.2 5341.1 5378.7 5387.0 5451.8 2981.8 2982.7 2990.3 2997.5 2999.8 5751.6 5815.8 5821.2 5925.5 5928.5 5336.7 5491.8 5612.3 5668.3 5679.9 5668.9 5692.8 5806.6 5813.7 5833.2 5567.7 5572.6 5585.1 5619.0 5620.9 5379.8 5512.5 5540.9 5552.5 5558.9 5796.5 5799.3 5813.2 5815.1 5823.4 4569.4 4569.6 4669.7 4707.9 4747.7 5389.3 5530.4 5559.3 5577.3 5607.0 5431.5 5462.9 5467.4 5473.9 5483.4 5303.6 5440.6 5468.9 5474.3 5501.6 5411.4 5417.7 5489.3 5497.4 5511.5 5524.2 5558.4 5558.6 5561.0 5562.8 5243.4 5293.9 5357.8 5358.8 5414.8 06:29:07 INFO - PROCESS | 14224 | 06:29:07 INFO - PROCESS | 14224 | [#5] yui.html Cycles:180 Average:19459.12 Median:4205.78 stddev:24252.95 (576.7%) stddev-sans-first:24280.88 06:29:07 INFO - PROCESS | 14224 | Values: 885.7 921.7 931.4 931.5 978.0 17352.2 18380.4 19853.2 19872.2 19882.9 17455.2 17999.7 18034.2 18238.5 18252.0 884.9 908.5 912.4 913.7 914.6 885.5 887.6 887.9 907.2 910.7 944.3 946.5 949.1 949.3 949.7 928.3 929.8 932.8 937.2 937.6 684.7 690.8 691.0 691.4 692.1 18412.8 18565.3 18601.2 19171.3 19364.4 16139.8 16204.7 16284.7 16612.0 16672.3 13806.9 13958.7 14220.9 14646.3 14909.7 4085.2 4085.5 4086.2 4165.9 4245.6 96861.5 96890.0 97227.0 98235.5 100777.0 12465.8 12497.8 12511.4 12585.9 12608.3 24468.8 25041.9 25179.5 25438.7 25891.6 9076.4 9529.5 9770.0 9829.0 9895.1 5120.9 5255.6 5271.4 5291.3 5370.0 3949.4 4012.5 4028.1 4035.8 4069.9 689.4 690.9 694.8 698.1 698.6 843.4 857.5 868.8 871.6 876.2 11709.9 11715.3 11982.6 12118.9 12198.3 832.4 846.7 864.8 870.4 874.1 5565.7 5578.4 5724.2 5779.7 5844.9 83837.6 87129.0 88112.6 89797.0 90661.5 46819.1 52205.0 52698.2 65771.7 71499.6 64477.0 68187.0 69136.7 69159.7 69173.7 11558.1 11923.7 12694.7 12804.8 12918.9 41644.8 42576.0 43084.6 43660.1 44113.9 45957.5 55552.7 56410.4 57429.7 58365.7 14033.2 14439.3 14462.5 14492.6 14670.3 16951.7 17262.6 17451.0 18318.5 18493.5 15629.6 16492.3 16778.2 16802.6 17363.2 15189.5 15849.3 16019.5 16396.8 16508.5 15044.3 15166.2 15792.7 15801.1 15806.2 11426.3 11706.1 12139.5 12439.4 12477.3 13493.9 13620.3 13681.9 13687.2 13835.2 06:29:07 INFO - PROCESS | 14224 | -------- Summary: end -------- 06:29:07 INFO - PROCESS | 14224 | 06:29:08 INFO - TEST-INFO | 14224: exit 0 06:29:08 INFO - TEST-OK | dromaeo_css | took 1115956ms 06:29:08 INFO - TEST-START | kraken 06:29:08 INFO - operating with platform_type : linux_ 06:29:08 INFO - Initialising browser for kraken test... 06:29:08 INFO - TEST-INFO | started process 14529 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRsAkrD/profile http://localhost:47227/getInfo.html) 06:29:12 INFO - PROCESS | 14529 | __metrics Screen width/height:1600/1200 06:29:12 INFO - PROCESS | 14529 | colorDepth:24 06:29:12 INFO - PROCESS | 14529 | Browser inner width/height: 1024/697 06:29:12 INFO - PROCESS | 14529 | __metrics 06:29:13 INFO - PROCESS | 14529 | 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" 06:29:13 INFO - PROCESS | 14529 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:29:13 INFO - PROCESS | 14529 | console.error: PushService: 06:29:13 INFO - PROCESS | 14529 | stateChangeProcessEnqueue: Error transitioning state 06:29:13 INFO - PROCESS | 14529 | UnknownError 06:29:14 INFO - TEST-INFO | 14529: exit 0 06:29:14 INFO - Browser initialized. 06:29:14 INFO - Running cycle 1/1 for kraken test... 06:29:14 INFO - TEST-INFO | started process 14623 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRsAkrD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:29:26 INFO - PROCESS | 14623 | RSS: Main: 141852672 06:29:26 INFO - PROCESS | 14623 | 06:29:28 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47227/tests/kraken/driver.html?testName=audio-beat-detection) 06:29:29 INFO - PROCESS | 14623 | RSS: Main: 184049664 06:29:29 INFO - PROCESS | 14623 | 06:29:32 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47227/tests/kraken/driver.html?testName=audio-dft) 06:29:32 INFO - PROCESS | 14623 | RSS: Main: 173432832 06:29:32 INFO - PROCESS | 14623 | 06:29:35 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47227/tests/kraken/driver.html?testName=audio-fft) 06:29:36 INFO - PROCESS | 14623 | RSS: Main: 265351168 06:29:36 INFO - PROCESS | 14623 | 06:29:38 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47227/tests/kraken/driver.html?testName=audio-oscillator) 06:29:38 INFO - PROCESS | 14623 | RSS: Main: 171384832 06:29:38 INFO - PROCESS | 14623 | 06:29:40 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:29:41 INFO - PROCESS | 14623 | RSS: Main: 174014464 06:29:41 INFO - PROCESS | 14623 | 06:29:44 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47227/tests/kraken/driver.html?testName=imaging-darkroom) 06:29:44 INFO - PROCESS | 14623 | RSS: Main: 208855040 06:29:44 INFO - PROCESS | 14623 | 06:29:48 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47227/tests/kraken/driver.html?testName=imaging-desaturate) 06:29:49 INFO - PROCESS | 14623 | RSS: Main: 213278720 06:29:49 INFO - PROCESS | 14623 | 06:29:51 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47227/tests/kraken/driver.html?testName=json-parse-financial) 06:29:52 INFO - PROCESS | 14623 | RSS: Main: 211841024 06:29:52 INFO - PROCESS | 14623 | 06:29:53 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:29:54 INFO - PROCESS | 14623 | RSS: Main: 174145536 06:29:54 INFO - PROCESS | 14623 | 06:29:56 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:29:56 INFO - PROCESS | 14623 | RSS: Main: 180318208 06:29:56 INFO - PROCESS | 14623 | 06:29:58 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:29:59 INFO - PROCESS | 14623 | RSS: Main: 189874176 06:29:59 INFO - PROCESS | 14623 | 06:30:01 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:30:02 INFO - PROCESS | 14623 | RSS: Main: 199475200 06:30:02 INFO - PROCESS | 14623 | 06:30:05 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:30:05 INFO - PROCESS | 14623 | RSS: Main: 195829760 06:30:05 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | Cycle 1(1): loaded http://localhost:47227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47227/tests/kraken/driver.html?testName=ai-astar) 06:30:07 INFO - PROCESS | 14623 | RSS: Main: 247242752 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | __start_tp_report 06:30:07 INFO - PROCESS | 14623 | _x_x_mozilla_page_load 06:30:07 INFO - PROCESS | 14623 | _x_x_mozilla_page_load_details 06:30:07 INFO - PROCESS | 14623 | |i|pagename|runs| 06:30:07 INFO - PROCESS | 14623 | |0;ai-astar;103;112;112;111;114;111;111;112;112;111 06:30:07 INFO - PROCESS | 14623 | |1;audio-beat-detection;137;114;116;159;121;118;114;119;142;115 06:30:07 INFO - PROCESS | 14623 | |2;audio-dft;160;158;160;161;159;161;159;160;161;159 06:30:07 INFO - PROCESS | 14623 | |3;audio-fft;74;75;74;109;75;75;75;76;91;74 06:30:07 INFO - PROCESS | 14623 | |4;audio-oscillator;82;82;115;83;82;82;99;82;82;113 06:30:07 INFO - PROCESS | 14623 | |5;imaging-gaussian-blur;113;113;113;113;113;112;113;113;113;112 06:30:07 INFO - PROCESS | 14623 | |6;imaging-darkroom;147;147;147;147;147;147;147;146;147;145 06:30:07 INFO - PROCESS | 14623 | |7;imaging-desaturate;93;95;94;95;94;93;94;94;94;94 06:30:07 INFO - PROCESS | 14623 | |8;json-parse-financial;55;56;54;54;55;55;56;54;56;54 06:30:07 INFO - PROCESS | 14623 | |9;json-stringify-tinderbox;52;52;52;52;51;52;52;53;52;53 06:30:07 INFO - PROCESS | 14623 | |10;stanford-crypto-aes;76;76;84;75;79;80;75;75;88;76 06:30:07 INFO - PROCESS | 14623 | |11;stanford-crypto-ccm;131;122;121;122;119;130;118;117;161;118 06:30:07 INFO - PROCESS | 14623 | |12;stanford-crypto-pbkdf2;161;168;160;156;157;161;157;158;156;155 06:30:07 INFO - PROCESS | 14623 | |13;stanford-crypto-sha256-iterative;59;60;59;59;60;58;59;59;61;58 06:30:07 INFO - PROCESS | 14623 | __end_tp_report 06:30:07 INFO - PROCESS | 14623 | __start_cc_report 06:30:07 INFO - PROCESS | 14623 | _x_x_mozilla_cycle_collect,1674 06:30:07 INFO - PROCESS | 14623 | __end_cc_report 06:30:07 INFO - PROCESS | 14623 | __startTimestamp1495719007711__endTimestamp 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | ------- Summary: start ------- 06:30:07 INFO - PROCESS | 14623 | Number of tests: 14 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.92 (2.6%) stddev-sans-first:0.97 06:30:07 INFO - PROCESS | 14623 | Values: 103.0 112.0 112.0 111.0 114.0 111.0 111.0 112.0 112.0 111.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:120.00 stddev:15.31 (12.8%) stddev-sans-first:15.67 06:30:07 INFO - PROCESS | 14623 | Values: 137.0 114.0 116.0 159.0 121.0 118.0 114.0 119.0 142.0 115.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 06:30:07 INFO - PROCESS | 14623 | Values: 160.0 158.0 160.0 161.0 159.0 161.0 159.0 160.0 161.0 159.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#3] audio-fft Cycles:10 Average:79.80 Median:75.00 stddev:11.48 (15.3%) stddev-sans-first:11.98 06:30:07 INFO - PROCESS | 14623 | Values: 74.0 75.0 74.0 109.0 75.0 75.0 75.0 76.0 91.0 74.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:82.00 stddev:13.61 (16.6%) stddev-sans-first:14.11 06:30:07 INFO - PROCESS | 14623 | Values: 82.0 82.0 115.0 83.0 82.0 82.0 99.0 82.0 82.0 113.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 06:30:07 INFO - PROCESS | 14623 | Values: 113.0 113.0 113.0 113.0 113.0 112.0 113.0 113.0 113.0 112.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 06:30:07 INFO - PROCESS | 14623 | Values: 147.0 147.0 147.0 147.0 147.0 147.0 147.0 146.0 147.0 145.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 06:30:07 INFO - PROCESS | 14623 | Values: 93.0 95.0 94.0 95.0 94.0 93.0 94.0 94.0 94.0 94.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 06:30:07 INFO - PROCESS | 14623 | Values: 55.0 56.0 54.0 54.0 55.0 55.0 56.0 54.0 56.0 54.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#9] json-stringify-tinderbox Cycles:10 Average:52.10 Median:52.00 stddev:0.57 (1.1%) stddev-sans-first:0.60 06:30:07 INFO - PROCESS | 14623 | Values: 52.0 52.0 52.0 52.0 51.0 52.0 52.0 53.0 52.0 53.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:77.50 stddev:4.45 (5.7%) stddev-sans-first:4.64 06:30:07 INFO - PROCESS | 14623 | Values: 76.0 76.0 84.0 75.0 79.0 80.0 75.0 75.0 88.0 76.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:122.00 stddev:13.25 (10.9%) stddev-sans-first:13.93 06:30:07 INFO - PROCESS | 14623 | Values: 131.0 122.0 121.0 122.0 119.0 130.0 118.0 117.0 161.0 118.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:159.00 stddev:3.84 (2.4%) stddev-sans-first:4.00 06:30:07 INFO - PROCESS | 14623 | Values: 161.0 168.0 160.0 156.0 157.0 161.0 157.0 158.0 156.0 155.0 06:30:07 INFO - PROCESS | 14623 | 06:30:07 INFO - PROCESS | 14623 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.20 Median:59.00 stddev:0.92 (1.6%) stddev-sans-first:0.97 06:30:07 INFO - PROCESS | 14623 | Values: 59.0 60.0 59.0 59.0 60.0 58.0 59.0 59.0 61.0 58.0 06:30:07 INFO - PROCESS | 14623 | -------- Summary: end -------- 06:30:07 INFO - PROCESS | 14623 | 06:30:08 INFO - TEST-INFO | 14623: exit 0 06:30:08 INFO - TEST-OK | kraken | took 60174ms 06:30:08 INFO - SUITE-END | took 1176s 06:30:08 INFO - Completed test suite (00:19:36) 06:30:08 INFO - javascript benchmark 06:30:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7918.075889621059, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3356.983215083925, 3398.5581874356344, 3402.285520040792, 3416.6241713411505, 3445.052610532105, 5613.129964855449, 5686.0617998030375, 5705.372409035002, 5719.656820590762, 5720.141978703192, 5648.774049038044, 5718.08510638298, 5723.656580605167, 5749.8275051748515, 5784.855378615537, 5238.842834714946, 5247.842564723055, 5250.212468129792, 5274.551663108621, 5316.494932981361, 5103.693778373291, 5132.050570644424, 5321.228421878825, 5328.5470734987575, 5329.147336426173, 5070.239464080399, 5123.052235336456, 5138.640295179339, 5141.845744627667, 5142.228665700156, 5245.86885327866, 5256.737163141846, 5265.236540701577, 5268.236105764681, 5290.523852853262, 5045.293658887758, 5114.386273647149, 5182.352205974254, 5223.6343455958095, 5228.477152284741, 5579.302587176562, 5633.126865335879, 5663.575231857586, 5678.971605141989, 5694.943050569483, 6079.726412311413, 6109.969450152721, 6123.969380153116, 6124.2650881894015, 6134.8773024539705, 6176.320604733476, 6184.783532576338, 6194.752209911643, 6196.566240363177, 6202.534809889231, 5583.106702927553, 5610.410906854803, 5692.345380281238, 5717.2281741965135, 5731.770729170787, 6285.402886725754, 6286.18279623646, 6291.842703932347, 6294.15028971083, 6296.0555916612875, 5986.910196347058, 6001.549883758736, 6006.309274433409, 6041.093835924558, 6045.939540604626, 5846.678432686158, 5890.9410905891245, 5985.461308482258, 6010.338862725097, 6091.14723938649, 6102.0541816018585, 6111.144439778434, 6119.051547010221, 6120.387961203933, 6127.20346354971, 5879.794207202727, 5918.023526118142, 6031.155638210654, 6036.185115009505, 6116.457510225599, 4598.0803839232285, 4637.327587499861, 4650.116477869262, 4652.651051171176, 4671.929921051187, 4018.3369743991916, 4021.4973128358956, 4152.688548358885, 4166.708330416842, 4229.302165142717, 5746.626469279567, 5768.134779782982, 5833.7083145842535, 5857.47282744555, 5877.38287479817, 6167.722452489649, 6171.105189747462, 6183.072539119168, 6183.443490085914, 6186.752529898754, 5655.293088363955, 5839.941600584025, 5841.591088623755, 5851.034579294369, 5851.795187168514, 5109.233614957786, 5131.409887862869, 5157.406898206755, 5185.403678577012, 5216.9739151304, 6367.522435817332, 6412.551121421456, 6415.422611964759, 6416.454601358937, 6418.261899881573, 6084.482818960261, 6132.693365331805, 6141.447269745744, 6143.84640383994, 6146.0473626587955, 6169.043798211284, 6216.595921265103, 6289.748410063729, 6310.463610593151, 6326.43062124411, 6077.05805600115, 6078.817635470943, 6080.2399700037495, 6082.178905847831, 6106.938930610637, 6166.722497487534, 6169.753209871555, 6182.041783523561, 6182.28903676083, 6192.752289908353, 5722.713864306851, 5836.562257830595, 5839.299284085937, 5840.445157710011, 6014.27828660546, 5928.081147422156, 5937.435943585352, 5942.465178133989, 5953.464188223081, 5967.015442451947, 5873.207117039145, 5914.763409463745, 5929.911051334319, 5942.1383899335215, 5947.553933454921, 5767.423257674367, 5799.710014499343, 5829.47534721877, 5923.614965027259, 5949.3753155918, 5828.242328497268, 5833.241678581757, 5837.357890632112, 5859.824205273849, 5868.970655146697], "value": 5626.747526831822, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.8414832885094, 830.7590798668384, 847.7160151349298, 893.6693423433327, 902.0888902208767, 31299.374012519736, 31626.367472650534, 34261.314773704544, 34265.31469370611, 43426.131477370436, 46830.29754553684, 47046.52953470464, 47074.293885591745, 47107.0, 47132.764336178356, 9110.18008379244, 9256.67601633943, 9263.21262692672, 9269.397489163217, 9276.304277179204, 7537.585432801194, 7643.312101910827, 9080.909190908105, 9179.403338782959, 9182.724518264462, 10755.516001779939, 11027.724306892353, 11430.599929002487, 11432.142589305793, 11440.427978601077, 10934.671959841218, 10977.176711746572, 10994.890051099466, 10995.945020274887, 11002.669919902415, 10760.73098172544, 10810.729731756768, 10824.13406927444, 10825.783484330348, 10828.566857325697, 42579.57420425787, 42729.14541709149, 42812.35781463281, 42961.57038429607, 43015.56984430147, 4308.0, 4308.46144231971, 4348.673849461303, 4429.11417716458, 4454.33185022246, 30004.69995300041, 30047.0, 31262.843685781652, 31493.212669683442, 31878.521822172686, 4254.383114448418, 4390.846320378771, 4397.560243975577, 4425.491068527129, 4476.0, 227693.0, 227705.0, 227897.0, 228995.0, 229034.85482572648, 52673.473265267625, 52789.2081618776, 52829.0, 52893.7355313221, 52915.73542132265, 55329.446705533235, 55423.44576554264, 55477.445225548036, 55478.16782748262, 55542.0, 15078.924605376902, 15400.46098386551, 15404.383824647113, 15415.460458883885, 15424.46014389491, 13305.467781288833, 13320.600381988363, 13331.733365332639, 13360.131591446525, 13366.732665346639, 11305.547778088949, 11515.827262591069, 12505.874941250653, 12579.245245285312, 13044.54344097952, 5492.450754924555, 5526.065876943118, 5545.61180717354, 5571.359293681197, 5604.859878502988, 1004.7136566078633, 1011.893751156118, 1018.0379541333369, 1018.592562974816, 1021.6526381030485, 28742.0, 28793.280167995967, 28841.134765957057, 29066.41867162645, 29215.561766573093, 1070.240129508058, 1094.6442406218011, 1102.5038732570376, 1115.185914282562, 1115.520326259716, 14075.225862577656, 15197.240137993056, 15218.771718424232, 15219.162946037854, 15292.853036022343, 279478.0, 283635.0, 284615.0, 285350.0, 285480.0, 48595.5140448598, 49636.50363496391, 55469.0, 55813.16280255799, 55874.72062639661, 1465.6482444213525, 1469.5885152157414, 1469.7648376259747, 1481.4148411377446, 1506.6384067823644, 27502.724972750017, 28750.424991499636, 29023.12930612085, 29109.7089029107, 29267.560986585646, 37642.0, 45813.541864580926, 46551.301730473395, 51479.485205147466, 51559.742201288755, 316555.0, 317463.0, 318380.0, 318693.0, 320597.0, 28647.713522864506, 29046.27384315409, 30674.846625767623, 30754.538681920236, 30884.0734777957, 40786.38820417756, 40834.1833163341, 40973.975650608976, 41012.58987410207, 41877.37183942182, 31986.68013319837, 32518.67481325251, 32652.0, 32699.182520437178, 32718.018459446255, 31303.530447042856, 31419.685803141678, 31702.524462133548, 31811.36377272488, 32606.51090233601, 35741.82129089338, 36296.27407451889, 36642.26715465623, 36671.0, 36728.08179795527, 29773.404531908807, 29838.0, 30028.0, 30305.0, 30729.231769205948, 24645.753542464347, 24737.628935565623, 25050.0, 25220.24339269825, 25234.495310094062], "value": 18647.46478522482, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.53916813602325, 473.1790343753587, 478.7797613097975, 481.73504572485126, 498.14070727994203, 20287.695684564726, 21453.14187432503, 21824.563508729814, 21885.562288754234, 22082.11671533137, 21776.455588610293, 22048.33854984353, 22228.777712222873, 22261.0, 22678.09287628493, 6765.864682706343, 6835.658217089151, 6978.825529361756, 6984.301569843001, 6990.405815505663, 6855.794326170223, 6956.373926346628, 7418.406527477789, 7700.152983171846, 7742.0, 7484.476086673935, 7866.7639970800965, 7867.567283799389, 7879.369650427952, 7879.566623835687, 7767.106782720003, 7822.765317040498, 7934.960325198337, 7946.523208607473, 7948.324392426651, 7709.922900770976, 7801.882971755428, 7804.414668899798, 7819.491733037391, 7819.882701759479, 25499.617505737428, 26038.739612603822, 26477.0, 26491.602625960626, 26548.070817521486, 3088.8146711197173, 3210.727088197506, 3231.579894613709, 3235.2558911450265, 3278.327942771726, 16631.83368166315, 16676.58308542284, 17023.0, 17131.0, 17679.20443580042, 2238.921637742671, 2282.121383267454, 2285.8285628577923, 2296.7014288142436, 2314.0, 72821.63589182073, 106118.0, 111572.0, 111608.88391116066, 116627.0, 17803.910980445016, 18197.27125923035, 18236.726449103273, 18341.81658183428, 18606.162722677513, 31205.375892482476, 31285.217869553442, 31320.0, 31640.36719265648, 31890.681093189236, 10092.545835437277, 10186.0, 10245.948770256251, 10258.12805911491, 10529.789404211873, 8303.501789892625, 8402.873956890651, 8434.62044208012, 8607.0, 8625.69810056645, 9302.767430814283, 9414.529273536296, 9516.000819913948, 9530.857037144448, 9620.567074481525, 3316.369889720945, 3476.495908093312, 3586.605473397924, 3772.5661548922226, 3829.8659546915724, 952.1573407534382, 977.237754551451, 982.8820541535061, 988.7874107067015, 989.2679417231197, 20846.583068338547, 21466.57066858654, 21566.56866862654, 21589.812560309332, 21644.891775541022, 998.3361064891766, 1005.9748506287401, 1010.7978404319166, 1012.3470361616863, 1015.4618052432223, 11274.774504509864, 11494.770104597861, 12389.75220495585, 12468.563600273947, 12544.811778101508, 326442.0, 636140.0, 640885.0, 656942.0, 661016.0, 91163.0, 100274.0, 101501.0, 118391.0, 119514.4024279873, 1311.9737605247842, 1357.6130802721177, 1388.687545302299, 1392.7005693775684, 1394.6652803327129, 19406.8059319405, 19690.606187875877, 19727.40817775469, 19794.50513737168, 19829.900850495655, 51806.48193518016, 52783.736081319345, 52804.0, 52878.0, 52996.0, 67002.0, 67007.66496167488, 68024.0, 68121.0, 69029.65485172543, 12543.372831358578, 12769.489220431078, 12925.676858078623, 13015.414306356048, 13113.672158196121, 13212.471501139848, 13708.24604646754, 14021.789673155115, 14262.0, 14299.714005720036, 12595.87404125947, 12609.928156106833, 12627.74744505123, 12698.619041428772, 12899.548515802277, 10721.517531710935, 11286.435678215892, 11540.422978850856, 11815.054795616503, 11953.701157471132, 12376.938115309365, 12715.364231788559, 12762.0, 12797.0, 12893.677658058623, 12577.874221258036, 12594.559190428292, 12684.365781711062, 12711.55509557161, 12720.427580758704, 12053.035757139232, 12268.693282667648, 12620.368981550702, 12727.0, 12897.355132243538], "value": 11664.932712871749, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.649087728068, 1564.280431001739, 1567.0362726922947, 1604.5106242596012, 1611.0172794595294, 9490.66782662606, 11138.832917506244, 11460.656180314583, 11820.527178912833, 11820.58627948022, 11529.654110376681, 11618.535258589647, 11642.0, 11644.475998580063, 11655.0, 2013.2047841102756, 2339.9883000584973, 2373.620220764678, 2377.6552399902002, 2379.1435083369975, 2069.793020697933, 2074.035573458349, 2094.486850721575, 2329.8252631052655, 2399.3401814500976, 2378.95242095158, 2379.262428647125, 2382.0709923129994, 2388.0, 2388.820838437124, 2268.6937263469467, 2288.439332363573, 2300.0454811253308, 2303.827212959018, 2305.5965206088867, 2220.9000594973104, 2242.0807468937655, 2245.65192395179, 2247.045005872514, 2248.460369511322, 11280.717982050515, 11527.308361498337, 11640.825387619192, 11659.475323610457, 11668.35824029678, 5656.0, 5810.796622118206, 5812.622179558315, 5815.941840581582, 5816.82549523515, 3467.358538670329, 3524.1718196223865, 3560.9702469592344, 3594.4069228577514, 3626.9455958160647, 1404.4592831759744, 1414.215110613609, 1440.6182361674164, 1473.0866862545183, 1475.611693676516, 16795.83204167955, 16876.156192190465, 17008.91495542527, 17023.659526809395, 17024.319027238897, 4601.838935637236, 4661.440627124767, 4756.6432517561325, 4855.174620314484, 4855.344528488679, 14359.497417590333, 14405.0, 14410.495632652806, 14412.351444185037, 14412.567622971328, 5872.207252021008, 5928.703564821742, 5955.702214889238, 5958.910616340759, 5959.165716799652, 5252.474752524793, 5338.199270109437, 5384.003959267548, 5387.757550910218, 5425.511703946664, 3597.172650290448, 3721.627837216311, 4055.97972010144, 4179.435776170239, 4181.226473102486, 1053.5996321397972, 1073.1843798713035, 1075.3171735947728, 1084.4415126210008, 1091.4924560079442, 1036.4817591204398, 1038.0605551975475, 1047.8690163729534, 1048.4286064094933, 1053.0890779475699, 6765.323467653196, 6816.863662726718, 6908.3091690830515, 7012.859742805115, 7169.749058782918, 1069.1340014588209, 1069.8181309177457, 1098.4562641492512, 1103.0988500244466, 1103.8289065194908, 4216.578342165821, 4309.547497512716, 4324.481062272547, 4337.978310108492, 4344.066025804467, 14575.125492470486, 15165.924170379078, 15207.0, 15215.239238038053, 15217.6195595111, 14918.627034324012, 14975.475858344906, 15095.471658492119, 15364.769528457082, 15371.0, 11069.335839849635, 11102.222844400814, 11177.49701263445, 11191.66425007251, 11196.04833589154, 6569.441597464269, 6729.764458243938, 6759.290274521104, 6770.1537307836525, 6809.0, 13048.869511304765, 13109.410076546388, 13382.0, 13418.993575482073, 13424.597262082154, 13431.0, 13486.86513134856, 13494.52769153075, 13551.661208469866, 13643.522476713664, 9968.651097211852, 9982.850257246293, 10147.137493313152, 10165.440900750235, 10175.84736228972, 11108.277961932448, 11113.777724445628, 11127.888721112686, 11130.833037504282, 11250.774984500427, 9563.09150630689, 9678.95160524193, 9709.854352184864, 9816.754581135528, 9849.409035457895, 9136.634534618806, 9299.395539289924, 9394.342396032212, 9636.373635713657, 9659.275554333313, 8431.620577073923, 8510.148985101441, 8514.276286515467, 8592.742217733477, 8616.870746938675, 6254.593451425642, 6263.1857858479925, 6347.936520634735, 6390.392912673288, 6465.741370345134, 5996.400359963969, 6073.331933487401, 6108.144859719554, 6168.167297414791, 6208.2239720034995], "value": 5402.843985268442, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.792103948026, 423.0819122504167, 436.51982818899194, 443.9556044395559, 444.95550444955506, 5209.609279304058, 5233.508450091725, 5275.920861187085, 5285.28648632434, 5382.59630527711, 5156.478838742571, 5171.818986335479, 5179.715115668637, 5198.506141916512, 5220.399654039787, 4429.667774916894, 4435.3125265583885, 4469.195544801934, 4548.863534093983, 4581.931271030937, 4552.271636538155, 4560.71964017991, 4561.315802629599, 4585.839495617654, 4586.564276393754, 4554.180247555439, 4571.47428045774, 4571.657125715585, 4573.405457290548, 4577.473590537097, 4419.734815911055, 4543.727376357429, 4554.908901821978, 4606.9078618427775, 4615.215413379717, 4338.1540599583095, 4350.934735978963, 4392.275274579691, 4408.647308215335, 4409.625181859546, 5102.974485127587, 5115.488451154892, 5122.538971492583, 5130.1791713326065, 5131.871703207413, 5094.923576146323, 5208.5051920067535, 5226.843194704165, 5268.0, 5273.551748101416, 5458.263134476874, 5459.044667183267, 5482.725863706799, 5497.340319161686, 5510.972445137789, 4797.328374027639, 4801.7118972861745, 4807.447143578498, 4869.878253043702, 4885.584725298354, 5509.0, 5737.999999999958, 5741.19623252745, 5748.281464816898, 5759.889659583861, 5052.873678158076, 5057.317262169634, 5079.97460012705, 5099.745012749348, 5111.463296353903, 5079.161938280216, 5102.107131252016, 5114.514121158484, 5168.586513078944, 5182.663126896739, 5030.597552195815, 5226.63413561047, 5226.7717086484645, 5226.869328266824, 5255.211718242294, 5404.567634589223, 5449.536789372868, 5461.208124821878, 5461.590380721462, 5474.616776825584, 1673.2554013463891, 1745.6683230186225, 1758.156085079169, 1759.3050744955926, 1766.0375095572945, 1402.6072699644117, 1412.7668934625672, 1446.8987170897935, 1452.6295794572316, 1472.889533285008, 5349.705766182898, 5351.9296140772, 5354.571634269249, 5378.821577261216, 5411.729413529387, 5613.8596535086135, 5617.9438205618235, 5622.269105016322, 5624.803131890364, 5627.240322556484, 5101.234814777738, 5341.145416733304, 5378.650387724785, 5387.0, 5451.754671039813, 2981.8061825981677, 2982.7464665503676, 2990.2524368907775, 2997.5461900978007, 2999.820010799359, 5751.597388182787, 5815.79644712433, 5821.214136091573, 5925.466707996301, 5928.466438020512, 5336.706481143575, 5491.835244942658, 5612.326520817528, 5668.291463567054, 5679.943200567942, 5668.8866222675315, 5692.800751973661, 5806.564507661942, 5813.651180929242, 5833.241678581757, 5567.69377684215, 5572.60991730575, 5585.078462053716, 5618.971905140448, 5620.8875822482505, 5379.838604841861, 5512.503874651301, 5540.944590554204, 5552.5280351168985, 5558.943800677858, 5796.507296879812, 5799.3040835100055, 5813.192359004858, 5815.098659707752, 5823.4467725566, 4569.405977222939, 4569.588737013684, 4669.673122881366, 4707.91717904877, 4747.715137091785, 5389.299391079135, 5530.419305972976, 5559.305086864142, 5577.27495425592, 5606.971965140311, 5431.456854314537, 5462.863428414322, 5467.425920278313, 5473.917891231715, 5483.369412517452, 5303.647569869659, 5440.564754819526, 5468.9179662304305, 5474.2609747683555, 5501.587380946365, 5411.404745478073, 5417.702026388429, 5489.313835770528, 5497.3952865184, 5511.476409741068, 5524.171374293727, 5558.416366281483, 5558.610897237317, 5561.0, 5562.800085935093, 5243.449437808975, 5293.947060529345, 5357.767713426012, 5358.812441564526, 5414.837554873203], "value": 4500.710797573099, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.720997885666, 921.6772937647737, 931.4225180388161, 931.4504442378995, 978.0341513557747, 17352.219150138244, 18380.448586542407, 19853.205871765113, 19872.205111795512, 19882.90058549707, 17455.21451534681, 17999.730004049918, 18034.188461519232, 18238.544036399097, 18252.0, 884.9380543361967, 908.4594666173616, 912.370464379576, 913.7304495173939, 914.5655813488606, 885.4952676974127, 887.6005797391167, 887.8535041718109, 907.2061945797427, 910.744991402405, 944.329526036515, 946.5456580841201, 949.1125797379437, 949.349695458613, 949.6818565780471, 928.3130483442271, 929.8465753150722, 932.8320902237595, 937.1893312284893, 937.5640327247828, 684.7055766020609, 690.7685925215059, 690.9585424874524, 691.4433880726014, 692.1140939597324, 18412.815871841245, 18565.257389704395, 18601.162947667388, 19171.32900348495, 19364.419067428, 16139.83860161395, 16204.675906481805, 16284.674306513922, 16612.0, 16672.33310667572, 13806.93096534521, 13958.651033724136, 14220.92889535556, 14646.34091465876, 14909.701805963929, 4085.1829634073306, 4085.5301640311445, 4086.182763447293, 4165.937510937339, 4245.617894389489, 96861.51569242179, 96890.0, 97227.0, 98235.50882245615, 100777.0, 12465.813012804816, 12497.75004499905, 12511.3744312784, 12585.93707031459, 12608.306543140032, 24468.755312447003, 25041.87479062593, 25179.49641007206, 25438.745612543637, 25891.611625825626, 9076.41003334781, 9529.523523823782, 9769.951150244346, 9828.950855245821, 9895.109440150422, 5120.92318615221, 5255.605829562798, 5271.42014378418, 5291.3121294231505, 5369.979703856255, 3949.447077409165, 4012.53855806586, 4028.073543085048, 4035.838566457309, 4069.8575549855614, 689.4381079420257, 690.8860038093658, 694.7741983855267, 698.1099098649162, 698.6436917172279, 843.4306842881031, 857.4683696108452, 868.8088620503479, 871.5859966515854, 876.1851478125277, 11709.941450292865, 11715.297082175097, 11982.58060967862, 12118.939405302917, 12198.268103913793, 832.3878134460326, 846.724814435311, 864.8443280209497, 870.4211699219948, 874.1477059866579, 5565.693886836263, 5578.358488773762, 5724.227229324071, 5779.682117483495, 5844.8538786529825, 83837.58081209555, 87129.0, 88112.5594372024, 89797.0, 90661.54669226744, 46819.063618727436, 52205.0, 52698.20952685713, 65771.67114164494, 71499.64250178715, 64477.0, 68187.0, 69136.6543167291, 69159.65420172967, 69173.65413173003, 11558.133140014364, 11923.701907452383, 12694.746105077662, 12804.807927880904, 12918.93540532329, 41644.791776040925, 42576.0, 43084.569154308054, 43660.126797463236, 44113.93824606924, 45957.540424595325, 55552.72223638856, 56410.43589564216, 57429.71285143548, 58365.70817145887, 14033.228172450614, 14439.278036097943, 14462.493812716504, 14492.565223043326, 14670.339834707676, 16951.66096678035, 17262.568435789206, 17451.0, 18318.450446486626, 18493.53766155857, 15629.609259768597, 16492.34030638761, 16778.1610919456, 16802.57993550122, 17363.2186551608, 15189.544313670607, 15849.330818198689, 16019.519414417586, 16396.83603163953, 16508.504744857673, 15044.323005465, 15166.241687915339, 15792.684146317239, 15801.13093779853, 15806.209689515708, 11426.25729327591, 11706.1220408468, 12139.514419423478, 12439.440225189706, 12477.25136491777, 13493.932530337286, 13620.250886201355, 13681.863181368059, 13687.24720140362, 13835.169889806644], "value": 8279.746283150826, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 111.0, 114.0, 111.0, 111.0, 112.0, 112.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 114.0, 116.0, 159.0, 121.0, 118.0, 114.0, 119.0, 142.0, 115.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 158.0, 160.0, 161.0, 159.0, 161.0, 159.0, 160.0, 161.0, 159.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 109.0, 75.0, 75.0, 75.0, 76.0, 91.0, 74.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 115.0, 83.0, 82.0, 82.0, 99.0, 82.0, 82.0, 113.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 145.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 54.0, 55.0, 55.0, 56.0, 54.0, 56.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 51.0, 52.0, 52.0, 53.0, 52.0, 53.0], "value": 52.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 84.0, 75.0, 79.0, 80.0, 75.0, 75.0, 88.0, 76.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 122.0, 121.0, 122.0, 119.0, 130.0, 118.0, 117.0, 161.0, 118.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 168.0, 160.0, 156.0, 157.0, 161.0, 157.0, 158.0, 156.0, 155.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 60.0, 58.0, 59.0, 59.0, 61.0, 58.0], "value": 59.2, "unit": "score"}]}]} 06:30:08 INFO - Return code: 0 06:30:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:30:09 INFO - # TBPL SUCCESS # 06:30:09 INFO - Running post-action listener: _resource_record_post_action 06:30:09 INFO - [mozharness: 2017-05-25 13:30:09.029296Z] Finished run-tests step (success) 06:30:09 INFO - Running post-run listener: _resource_record_post_run 06:30:09 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 520192; Write bytes: 325365760; Read time: 224; Write time: 207472 06:30:09 INFO - TinderboxPrint: CPU usage
13.1% 06:30:09 INFO - TinderboxPrint: I/O read bytes / time
520,192 / 224 06:30:09 INFO - TinderboxPrint: I/O write bytes / time
325,365,760 / 207,472 06:30:09 INFO - TinderboxPrint: CPU idle
8,255.1 (86.9%) 06:30:09 INFO - TinderboxPrint: CPU user
1,196.9 (12.6%) 06:30:09 INFO - TinderboxPrint: Swap in / out
0 / 0 06:30:09 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 434176; Read time: 0; Write time: 48 06:30:09 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 516096; Write bytes: 257466368; Read time: 48; Write time: 168016 06:30:09 INFO - Running post-run listener: _upload_blobber_files 06:30:09 INFO - Blob upload gear active. 06:30:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:30:09 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 06:30:09 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'] 06:30:09 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 06:30:10 INFO - (blobuploader) - INFO - Open directory for files ... 06:30:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:30:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:30:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:10 INFO - (blobuploader) - INFO - Done attempting. 06:30:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:30:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:30:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:11 INFO - (blobuploader) - INFO - Done attempting. 06:30:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:30:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:30:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:18 INFO - (blobuploader) - INFO - Done attempting. 06:30:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:30:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:30:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:30:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:30:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:30:19 INFO - (blobuploader) - INFO - Done attempting. 06:30:19 INFO - (blobuploader) - INFO - Iteration through files over. 06:30:19 INFO - Return code: 0 06:30:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:30:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:30:19 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/170ae6fd757be6eba7d087092d06648545cb27157b25dd3152a93e5b8d4845bf14dfd39dd26c833f8bf8b0110634de2cf0fe1f33aa7d77f792fccae8cafe1c6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5283d12eb9cd26227d21e594489f959afad647493baa6b97be5b9ae1d7184cbb63b246baa96abfd4093f674d2a2f3707a1514cd401a6cd760687077b51353e36", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7e5cd61578a4888cfd10bcba126a4b808844f53553ac480f5b5a9041a182e541f4da33181afbbd983c877ecca515d5e070b021421dd82e94c250ca2916a49605", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cbccfdef64f0a90c610312d92928cb2a414dd324c83106379eae2cf709a06269c4228ce7e735e3a8235eaab09f783865679c833b7962ed11778aa4abb628f95d"} 06:30:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:30:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:30:19 INFO - Contents: 06:30:19 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/170ae6fd757be6eba7d087092d06648545cb27157b25dd3152a93e5b8d4845bf14dfd39dd26c833f8bf8b0110634de2cf0fe1f33aa7d77f792fccae8cafe1c6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5283d12eb9cd26227d21e594489f959afad647493baa6b97be5b9ae1d7184cbb63b246baa96abfd4093f674d2a2f3707a1514cd401a6cd760687077b51353e36", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7e5cd61578a4888cfd10bcba126a4b808844f53553ac480f5b5a9041a182e541f4da33181afbbd983c877ecca515d5e070b021421dd82e94c250ca2916a49605", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cbccfdef64f0a90c610312d92928cb2a414dd324c83106379eae2cf709a06269c4228ce7e735e3a8235eaab09f783865679c833b7962ed11778aa4abb628f95d"} 06:30:19 INFO - Running post-run listener: copy_logs_to_upload_dir 06:30:19 INFO - Copying logs to upload dir... 06:30:19 INFO - mkdir: /builds/slave/test/build/upload/logs 06:30:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.682785 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-05-25 06:30:18.927024) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 06:30:18.930050) ========= 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=920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163 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/170ae6fd757be6eba7d087092d06648545cb27157b25dd3152a93e5b8d4845bf14dfd39dd26c833f8bf8b0110634de2cf0fe1f33aa7d77f792fccae8cafe1c6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5283d12eb9cd26227d21e594489f959afad647493baa6b97be5b9ae1d7184cbb63b246baa96abfd4093f674d2a2f3707a1514cd401a6cd760687077b51353e36", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7e5cd61578a4888cfd10bcba126a4b808844f53553ac480f5b5a9041a182e541f4da33181afbbd983c877ecca515d5e070b021421dd82e94c250ca2916a49605", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cbccfdef64f0a90c610312d92928cb2a414dd324c83106379eae2cf709a06269c4228ce7e735e3a8235eaab09f783865679c833b7962ed11778aa4abb628f95d"} build_url:https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011335 build_url: 'https://queue.taskcluster.net/v1/task/QLgep2mlRPSPxgEIn9YFGg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/170ae6fd757be6eba7d087092d06648545cb27157b25dd3152a93e5b8d4845bf14dfd39dd26c833f8bf8b0110634de2cf0fe1f33aa7d77f792fccae8cafe1c6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5283d12eb9cd26227d21e594489f959afad647493baa6b97be5b9ae1d7184cbb63b246baa96abfd4093f674d2a2f3707a1514cd401a6cd760687077b51353e36", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7e5cd61578a4888cfd10bcba126a4b808844f53553ac480f5b5a9041a182e541f4da33181afbbd983c877ecca515d5e070b021421dd82e94c250ca2916a49605", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cbccfdef64f0a90c610312d92928cb2a414dd324c83106379eae2cf709a06269c4228ce7e735e3a8235eaab09f783865679c833b7962ed11778aa4abb628f95d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 06:30:18.957489) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:30:18.957835) ========= 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=920470bf432669ad7e3a5387000001ca-1495710241.444854-1505330163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004418 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:30:19.044921) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 06:30:19.045242) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 06:30:19.045536) ========= ========= Total master_lag: 0.16 =========