builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-081 starttime: 1495729250.52 results: success (0) revision: 38c9c1ad194a58d08440919f0c4618993917315b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.519225) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.519642) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.519921) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.546143) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.546434) ========= 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=483e410453545d07cca6935c000001ab-1495728901.29347-956784776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-25 09:20:50-- 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.2M=0s 2017-05-25 09:20:50 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135994 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.713959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.714265) ========= 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=483e410453545d07cca6935c000001ab-1495728901.29347-956784776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015946 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:50.804033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 09:20:50.804349) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 38c9c1ad194a58d08440919f0c4618993917315b --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 38c9c1ad194a58d08440919f0c4618993917315b --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=483e410453545d07cca6935c000001ab-1495728901.29347-956784776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-25 09:20:51,177 truncating revision to first 12 chars 2017-05-25 09:20:51,177 Setting DEBUG logging. 2017-05-25 09:20:51,177 attempt 1/10 2017-05-25 09:20:51,177 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/38c9c1ad194a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 09:20:51,857 unpacking tar archive at: mozilla-beta-38c9c1ad194a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.981064 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 09:20:51.827583) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:51.827908) ========= script_repo_revision: 38c9c1ad194a58d08440919f0c4618993917315b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:51.828281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:51.828558) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 09:20:51.855357) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-25 09:20:51.855735) ========= /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=483e410453545d07cca6935c000001ab-1495728901.29347-956784776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:20:52 INFO - MultiFileLogger online at 20170525 09:20:52 in /builds/slave/test 09:20:52 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 09:20:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:20:52 INFO - {'append_to_log': False, 09:20:52 INFO - 'base_work_dir': '/builds/slave/test', 09:20:52 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 09:20:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:20:52 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 09:20:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:20:52 INFO - 'config_files': ('talos/linux_config.py',), 09:20:52 INFO - 'default_actions': ('clobber', 09:20:52 INFO - 'read-buildbot-config', 09:20:52 INFO - 'download-and-extract', 09:20:52 INFO - 'populate-webroot', 09:20:52 INFO - 'create-virtualenv', 09:20:52 INFO - 'install', 09:20:52 INFO - 'run-tests'), 09:20:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:20:52 INFO - 'download_minidump_stackwalk': True, 09:20:52 INFO - 'download_symbols': 'ondemand', 09:20:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:20:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:20:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:20:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:20:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:20:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:20:52 INFO - 'gecko_profile': False, 09:20:52 INFO - 'gecko_profile_interval': 0, 09:20:52 INFO - 'installer_path': 'installer.exe', 09:20:52 INFO - 'log_level': 'info', 09:20:52 INFO - 'log_name': 'talos', 09:20:52 INFO - 'log_to_console': True, 09:20:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:20:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:20:52 INFO - 'opt_config_files': (), 09:20:52 INFO - 'pip_index': False, 09:20:52 INFO - 'suite': 'dromaeojs', 09:20:52 INFO - 'system_bits': '32', 09:20:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:20:52 INFO - 'title': 'talos-linux64-ix-081', 09:20:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:20:52 INFO - 'use_talos_json': True, 09:20:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:20:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:20:52 INFO - 'work_dir': 'build'} 09:20:52 INFO - [mozharness: 2017-05-25 16:20:52.299131Z] Running clobber step. 09:20:52 INFO - Running pre-action listener: _resource_record_pre_action 09:20:52 INFO - Running main action method: clobber 09:20:52 INFO - rmtree: /builds/slave/test/build 09:20:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:20:53 INFO - Running post-action listener: _resource_record_post_action 09:20:53 INFO - [mozharness: 2017-05-25 16:20:53.324803Z] Finished clobber step (success) 09:20:53 INFO - [mozharness: 2017-05-25 16:20:53.324902Z] Running read-buildbot-config step. 09:20:53 INFO - Running pre-action listener: _resource_record_pre_action 09:20:53 INFO - Running main action method: read_buildbot_config 09:20:53 INFO - Using buildbot properties: 09:20:53 INFO - { 09:20:53 INFO - "project": "", 09:20:53 INFO - "product": "firefox", 09:20:53 INFO - "who": "ryanvm@gmail.com", 09:20:53 INFO - "installer_path": "public/build/target.tar.bz2", 09:20:53 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:20:53 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 09:20:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 09:20:53 INFO - "stage_platform": "linux64", 09:20:53 INFO - "basedir": "/builds/slave/test", 09:20:53 INFO - "buildnumber": 57, 09:20:53 INFO - "platform": "ubuntu64_hw", 09:20:53 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:20:53 INFO - "slavebuilddir": "test", 09:20:53 INFO - "taskId": "QSQ3Df0AQTSeKi__Ze_ENQ", 09:20:53 INFO - "branch": "mozilla-beta", 09:20:53 INFO - "script_repo_revision": "production", 09:20:53 INFO - "revision": "38c9c1ad194a58d08440919f0c4618993917315b", 09:20:53 INFO - "slavename": "talos-linux64-ix-081", 09:20:53 INFO - "repo_path": "releases/mozilla-beta" 09:20:53 INFO - } 09:20:53 INFO - Finding installer, test and symbols from parent task. 09:20:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QSQ3Df0AQTSeKi__Ze_ENQ'}, attempt #1 09:20:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QSQ3Df0AQTSeKi__Ze_ENQ'}, attempt #1 09:20:54 INFO - Task dependencies: VHgymqa8SV-Z-CZNTXU_TA 09:20:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA'}, attempt #1 09:20:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2 09:20:54 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/test_packages.json 09:20:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.crashreporter-symbols.zip 09:20:54 INFO - Running post-action listener: _resource_record_post_action 09:20:54 INFO - [mozharness: 2017-05-25 16:20:54.673736Z] Finished read-buildbot-config step (success) 09:20:54 INFO - [mozharness: 2017-05-25 16:20:54.673952Z] Running download-and-extract step. 09:20:54 INFO - Running pre-action listener: _resource_record_pre_action 09:20:54 INFO - Running main action method: download_and_extract 09:20:54 INFO - mkdir: /builds/slave/test/build/tests 09:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:54 INFO - trying https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.test_packages.json 09:20:54 INFO - Downloading https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:20:55 INFO - Downloaded 986 bytes. 09:20:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:20:55 INFO - Using the following test package requirements: 09:20:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:20:55 INFO - u'common': [u'target.common.tests.zip'], 09:20:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:20:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:20:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:20:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:20:55 INFO - u'mozbase': [u'target.common.tests.zip'], 09:20:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:20:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:20:55 INFO - u'web-platform': [u'target.common.tests.zip', 09:20:55 INFO - u'target.web-platform.tests.zip'], 09:20:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:20:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:20:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.common.tests.zip 09:20:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:20:55 INFO - Fetch https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.common.tests.zip into memory 09:20:57 INFO - Expected file size: 59712156 09:20:57 INFO - Obtained file size: 59712156 09:21:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:21:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.common.tests.zip 09:21:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:21:07 INFO - Fetch https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.common.tests.zip into memory 09:21:08 INFO - Expected file size: 59712156 09:21:08 INFO - Obtained file size: 59712156 09:21:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.talos.tests.zip 09:21:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:21:19 INFO - Fetch https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.talos.tests.zip into memory 09:21:19 INFO - Expected file size: 14404449 09:21:19 INFO - Obtained file size: 14404449 09:21:20 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')]} 09:21:20 INFO - trying https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2 09:21:20 INFO - Downloading https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:21:22 INFO - Downloaded 59811439 bytes. 09:21:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2 09:21:22 INFO - mkdir: /builds/slave/test/properties 09:21:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:21:22 INFO - Writing to file /builds/slave/test/properties/build_url 09:21:22 INFO - Contents: 09:21:22 INFO - build_url:https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2 09:21:22 INFO - Running post-action listener: _resource_record_post_action 09:21:22 INFO - Running post-action listener: set_extra_try_arguments 09:21:22 INFO - [mozharness: 2017-05-25 16:21:22.264502Z] Finished download-and-extract step (success) 09:21:22 INFO - [mozharness: 2017-05-25 16:21:22.264728Z] Running populate-webroot step. 09:21:22 INFO - Running pre-action listener: _resource_record_pre_action 09:21:22 INFO - Running main action method: populate_webroot 09:21:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:21:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:21:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:21:22 INFO - '--tppagecycles', 09:21:22 INFO - '7'], 09:21:22 INFO - 'tests': ['tsvgm']}}, 09:21:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:21:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:21:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:21:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:21:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:21:22 INFO - 'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:21:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:21:22 INFO - 'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['damp', 'tps']}, 09:21:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:21:22 INFO - 'tests': ['damp', 'tps']}, 09:21:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['dromaeo_dom']}, 09:21:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:21:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['basic_compositor_video']}, 09:21:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:21:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['a11yr', 09:21:22 INFO - 'ts_paint', 09:21:22 INFO - 'tpaint', 09:21:22 INFO - 'sessionrestore', 09:21:22 INFO - 'sessionrestore_no_auto_restore', 09:21:22 INFO - 'tabpaint']}, 09:21:22 INFO - 'other-e10s': {'tests': ['a11yr', 09:21:22 INFO - 'ts_paint', 09:21:22 INFO - 'tpaint', 09:21:22 INFO - 'sessionrestore', 09:21:22 INFO - 'sessionrestore_no_auto_restore', 09:21:22 INFO - 'tabpaint']}, 09:21:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:21:22 INFO - 'ts_paint', 09:21:22 INFO - 'tpaint', 09:21:22 INFO - 'sessionrestore', 09:21:22 INFO - 'sessionrestore_no_auto_restore', 09:21:22 INFO - 'tabpaint']}, 09:21:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:21:22 INFO - 'ts_paint', 09:21:22 INFO - 'tpaint', 09:21:22 INFO - 'sessionrestore', 09:21:22 INFO - 'sessionrestore_no_auto_restore', 09:21:22 INFO - 'tabpaint']}, 09:21:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['a11yr', 09:21:22 INFO - 'ts_paint', 09:21:22 INFO - 'tpaint', 09:21:22 INFO - 'sessionrestore', 09:21:22 INFO - 'sessionrestore_no_auto_restore', 09:21:22 INFO - 'tabpaint']}, 09:21:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['a11yr', 09:21:22 INFO - 'ts_paint', 09:21:22 INFO - 'tpaint', 09:21:22 INFO - 'sessionrestore', 09:21:22 INFO - 'sessionrestore_no_auto_restore', 09:21:22 INFO - 'tabpaint']}, 09:21:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['tsvgx', 09:21:22 INFO - 'tsvgr_opacity', 09:21:22 INFO - 'tart', 09:21:22 INFO - 'tscrollx', 09:21:22 INFO - 'cart', 09:21:22 INFO - 'tsvg_static']}, 09:21:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:21:22 INFO - 'tsvgr_opacity', 09:21:22 INFO - 'tart', 09:21:22 INFO - 'tscrollx', 09:21:22 INFO - 'cart', 09:21:22 INFO - 'tsvg_static']}, 09:21:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:21:22 INFO - 'talos_options': ['--disable-e10s'], 09:21:22 INFO - 'tests': ['tp5o']}, 09:21:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:21:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:21:22 INFO - 'talos_options': ['--disable-e10s', 09:21:22 INFO - '--xperf_path', 09:21:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:22 INFO - 'tests': ['tp5n']}, 09:21:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:21:22 INFO - 'talos_options': ['--xperf_path', 09:21:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:22 INFO - 'tests': ['tp5n']}}, 09:21:22 INFO - 'talos.zip': {'path': '', 09:21:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:21:22 INFO - Running post-action listener: _resource_record_post_action 09:21:22 INFO - [mozharness: 2017-05-25 16:21:22.281417Z] Finished populate-webroot step (success) 09:21:22 INFO - [mozharness: 2017-05-25 16:21:22.281506Z] Running create-virtualenv step. 09:21:22 INFO - Running pre-action listener: _resource_record_pre_action 09:21:22 INFO - Running main action method: create_virtualenv 09:21:22 INFO - Creating virtualenv /builds/slave/test/build/venv 09:21:22 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 09:21:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:21:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:21:22 INFO - Using real prefix '/usr' 09:21:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:21:22 INFO - Installing distribute.............................................................................................................................................................................................done. 09:21:24 INFO - Installing pip.................done. 09:21:24 INFO - Return code: 0 09:21:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:21:25 INFO - Reading from file tmpfile_stdout 09:21:25 INFO - Output received: 09:21:25 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) 09:21:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:25 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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:25 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 09:21:25 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 09:21:25 INFO - Using env: {'DISPLAY': ':0', 09:21:25 INFO - 'HOME': '/home/cltbld', 09:21:25 INFO - 'LANG': 'en_US.UTF-8', 09:21:25 INFO - 'LANGUAGE': 'en_US:en', 09:21:25 INFO - 'LOGNAME': 'cltbld', 09:21:25 INFO - 'MAIL': '/var/mail/cltbld', 09:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:25 INFO - 'MOZ_NO_REMOTE': '1', 09:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:25 INFO - 'NO_EM_RESTART': '1', 09:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:25 INFO - 'PWD': '/builds/slave/test', 09:21:25 INFO - 'SHELL': '/bin/bash', 09:21:25 INFO - 'SHLVL': '1', 09:21:25 INFO - 'TERM': 'linux', 09:21:25 INFO - 'TMOUT': '86400', 09:21:25 INFO - 'USER': 'cltbld', 09:21:25 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:25 INFO - '_': '/tools/buildbot/bin/python'} 09:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:25 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 09:21:25 INFO - Cleaning up... 09:21:25 INFO - Return code: 0 09:21:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:25 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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:25 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 09:21:25 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 09:21:25 INFO - Using env: {'DISPLAY': ':0', 09:21:25 INFO - 'HOME': '/home/cltbld', 09:21:25 INFO - 'LANG': 'en_US.UTF-8', 09:21:25 INFO - 'LANGUAGE': 'en_US:en', 09:21:25 INFO - 'LOGNAME': 'cltbld', 09:21:25 INFO - 'MAIL': '/var/mail/cltbld', 09:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:25 INFO - 'MOZ_NO_REMOTE': '1', 09:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:25 INFO - 'NO_EM_RESTART': '1', 09:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:25 INFO - 'PWD': '/builds/slave/test', 09:21:25 INFO - 'SHELL': '/bin/bash', 09:21:25 INFO - 'SHLVL': '1', 09:21:25 INFO - 'TERM': 'linux', 09:21:25 INFO - 'TMOUT': '86400', 09:21:25 INFO - 'USER': 'cltbld', 09:21:25 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:25 INFO - '_': '/tools/buildbot/bin/python'} 09:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:25 INFO - Downloading/unpacking psutil>=3.1.1 09:21:25 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 09:21:25 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 09:21:25 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 09:21:25 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 09:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:21:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:26 INFO - Installing collected packages: psutil 09:21:26 INFO - Running setup.py install for psutil 09:21:26 INFO - building 'psutil._psutil_linux' extension 09:21:26 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 09:21:26 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 09:21:26 INFO - building 'psutil._psutil_posix' extension 09:21:26 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 09:21:27 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 09:21:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:27 INFO - Successfully installed psutil 09:21:27 INFO - Cleaning up... 09:21:27 INFO - Return code: 0 09:21:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:21:27 INFO - Using env: {'DISPLAY': ':0', 09:21:27 INFO - 'HOME': '/home/cltbld', 09:21:27 INFO - 'LANG': 'en_US.UTF-8', 09:21:27 INFO - 'LANGUAGE': 'en_US:en', 09:21:27 INFO - 'LOGNAME': 'cltbld', 09:21:27 INFO - 'MAIL': '/var/mail/cltbld', 09:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:27 INFO - 'MOZ_NO_REMOTE': '1', 09:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:27 INFO - 'NO_EM_RESTART': '1', 09:21:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:27 INFO - 'PWD': '/builds/slave/test', 09:21:27 INFO - 'SHELL': '/bin/bash', 09:21:27 INFO - 'SHLVL': '1', 09:21:27 INFO - 'TERM': 'linux', 09:21:27 INFO - 'TMOUT': '86400', 09:21:27 INFO - 'USER': 'cltbld', 09:21:27 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:27 INFO - '_': '/tools/buildbot/bin/python'} 09:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:21:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:21:28 INFO - Installing collected packages: mozsystemmonitor 09:21:28 INFO - Running setup.py install for mozsystemmonitor 09:21:28 INFO - Successfully installed mozsystemmonitor 09:21:28 INFO - Cleaning up... 09:21:28 INFO - Return code: 0 09:21:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:21:28 INFO - Using env: {'DISPLAY': ':0', 09:21:28 INFO - 'HOME': '/home/cltbld', 09:21:28 INFO - 'LANG': 'en_US.UTF-8', 09:21:28 INFO - 'LANGUAGE': 'en_US:en', 09:21:28 INFO - 'LOGNAME': 'cltbld', 09:21:28 INFO - 'MAIL': '/var/mail/cltbld', 09:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:28 INFO - 'MOZ_NO_REMOTE': '1', 09:21:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:28 INFO - 'NO_EM_RESTART': '1', 09:21:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:28 INFO - 'PWD': '/builds/slave/test', 09:21:28 INFO - 'SHELL': '/bin/bash', 09:21:28 INFO - 'SHLVL': '1', 09:21:28 INFO - 'TERM': 'linux', 09:21:28 INFO - 'TMOUT': '86400', 09:21:28 INFO - 'USER': 'cltbld', 09:21:28 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:28 INFO - '_': '/tools/buildbot/bin/python'} 09:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:28 INFO - Downloading/unpacking jsonschema==2.5.1 09:21:28 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 09:21:28 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 09:21:28 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 09:21:28 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 09:21:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:21:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:21:30 INFO - warning: no files found matching '*.txt' 09:21:30 INFO - no previously-included directories found matching 'build' 09:21:30 INFO - no previously-included directories found matching 'dist' 09:21:30 INFO - no previously-included directories found matching '.git*' 09:21:30 INFO - Installing collected packages: jsonschema, functools32 09:21:30 INFO - Running setup.py install for functools32 09:21:30 INFO - warning: no files found matching '*.txt' 09:21:30 INFO - no previously-included directories found matching 'build' 09:21:30 INFO - no previously-included directories found matching 'dist' 09:21:30 INFO - no previously-included directories found matching '.git*' 09:21:30 INFO - Successfully installed jsonschema functools32 09:21:30 INFO - Cleaning up... 09:21:30 INFO - Return code: 0 09:21:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:21:30 INFO - Using env: {'DISPLAY': ':0', 09:21:30 INFO - 'HOME': '/home/cltbld', 09:21:30 INFO - 'LANG': 'en_US.UTF-8', 09:21:30 INFO - 'LANGUAGE': 'en_US:en', 09:21:30 INFO - 'LOGNAME': 'cltbld', 09:21:30 INFO - 'MAIL': '/var/mail/cltbld', 09:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:30 INFO - 'MOZ_NO_REMOTE': '1', 09:21:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:30 INFO - 'NO_EM_RESTART': '1', 09:21:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:30 INFO - 'PWD': '/builds/slave/test', 09:21:30 INFO - 'SHELL': '/bin/bash', 09:21:30 INFO - 'SHLVL': '1', 09:21:30 INFO - 'TERM': 'linux', 09:21:30 INFO - 'TMOUT': '86400', 09:21:30 INFO - 'USER': 'cltbld', 09:21:30 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:30 INFO - '_': '/tools/buildbot/bin/python'} 09:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:21:30 INFO - Cleaning up... 09:21:30 INFO - Return code: 0 09:21:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:21:30 INFO - Using env: {'DISPLAY': ':0', 09:21:30 INFO - 'HOME': '/home/cltbld', 09:21:30 INFO - 'LANG': 'en_US.UTF-8', 09:21:30 INFO - 'LANGUAGE': 'en_US:en', 09:21:30 INFO - 'LOGNAME': 'cltbld', 09:21:30 INFO - 'MAIL': '/var/mail/cltbld', 09:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:30 INFO - 'MOZ_NO_REMOTE': '1', 09:21:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:30 INFO - 'NO_EM_RESTART': '1', 09:21:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:30 INFO - 'PWD': '/builds/slave/test', 09:21:30 INFO - 'SHELL': '/bin/bash', 09:21:30 INFO - 'SHLVL': '1', 09:21:30 INFO - 'TERM': 'linux', 09:21:30 INFO - 'TMOUT': '86400', 09:21:30 INFO - 'USER': 'cltbld', 09:21:30 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:30 INFO - '_': '/tools/buildbot/bin/python'} 09:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:30 INFO - Downloading/unpacking blobuploader==1.2.4 09:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:31 INFO - Downloading blobuploader-1.2.4.tar.gz 09:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:21:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:21:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:32 INFO - Downloading docopt-0.6.1.tar.gz 09:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:21:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:21:32 INFO - Running setup.py install for blobuploader 09:21:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:21:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:21:32 INFO - Running setup.py install for requests 09:21:32 INFO - Running setup.py install for docopt 09:21:33 INFO - Successfully installed blobuploader requests docopt 09:21:33 INFO - Cleaning up... 09:21:33 INFO - Return code: 0 09:21:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:33 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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:33 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 09:21:33 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 09:21:33 INFO - Using env: {'DISPLAY': ':0', 09:21:33 INFO - 'HOME': '/home/cltbld', 09:21:33 INFO - 'LANG': 'en_US.UTF-8', 09:21:33 INFO - 'LANGUAGE': 'en_US:en', 09:21:33 INFO - 'LOGNAME': 'cltbld', 09:21:33 INFO - 'MAIL': '/var/mail/cltbld', 09:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:33 INFO - 'MOZ_NO_REMOTE': '1', 09:21:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:33 INFO - 'NO_EM_RESTART': '1', 09:21:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:33 INFO - 'PWD': '/builds/slave/test', 09:21:33 INFO - 'SHELL': '/bin/bash', 09:21:33 INFO - 'SHLVL': '1', 09:21:33 INFO - 'TERM': 'linux', 09:21:33 INFO - 'TMOUT': '86400', 09:21:33 INFO - 'USER': 'cltbld', 09:21:33 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:33 INFO - '_': '/tools/buildbot/bin/python'} 09:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:21:33 INFO - Running setup.py (path:/tmp/pip-i4WDol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:21:33 INFO - Running setup.py (path:/tmp/pip-fsu5CY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:21:33 INFO - Running setup.py (path:/tmp/pip-4BH2lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:33 INFO - Running setup.py (path:/tmp/pip-AqzqY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:33 INFO - Running setup.py (path:/tmp/pip-7VxXjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:33 INFO - Running setup.py (path:/tmp/pip-bRBF3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:33 INFO - Running setup.py (path:/tmp/pip-DxBy0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:33 INFO - Running setup.py (path:/tmp/pip-umUnrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:33 INFO - Running setup.py (path:/tmp/pip-qxnYHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:33 INFO - Running setup.py (path:/tmp/pip-xQwbOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:34 INFO - Running setup.py (path:/tmp/pip-h11U1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:34 INFO - Running setup.py (path:/tmp/pip-iWdlRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:34 INFO - Running setup.py (path:/tmp/pip-v6yU7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:34 INFO - Running setup.py (path:/tmp/pip-4dQjpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:34 INFO - Running setup.py (path:/tmp/pip-rthvUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:34 INFO - Running setup.py (path:/tmp/pip-ivquO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:34 INFO - Running setup.py (path:/tmp/pip-oXtpDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:21:34 INFO - Running setup.py install for manifestparser 09:21:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:21:34 INFO - Running setup.py install for mozcrash 09:21:34 INFO - Running setup.py install for mozdebug 09:21:34 INFO - Running setup.py install for mozdevice 09:21:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:21:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Running setup.py install for mozfile 09:21:35 INFO - Running setup.py install for mozhttpd 09:21:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Running setup.py install for mozinfo 09:21:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Running setup.py install for mozInstall 09:21:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Running setup.py install for mozleak 09:21:35 INFO - Running setup.py install for mozlog 09:21:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Running setup.py install for moznetwork 09:21:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Running setup.py install for mozprocess 09:21:35 INFO - Running setup.py install for mozprofile 09:21:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:21:35 INFO - Running setup.py install for mozrunner 09:21:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:21:36 INFO - Running setup.py install for mozscreenshot 09:21:36 INFO - Running setup.py install for moztest 09:21:36 INFO - Running setup.py install for mozversion 09:21:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:21:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:21:36 INFO - Cleaning up... 09:21:36 INFO - Return code: 0 09:21:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:36 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')]} 09:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:36 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')]} 09:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:36 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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:36 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 09:21:36 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 09:21:36 INFO - Using env: {'DISPLAY': ':0', 09:21:36 INFO - 'HOME': '/home/cltbld', 09:21:36 INFO - 'LANG': 'en_US.UTF-8', 09:21:36 INFO - 'LANGUAGE': 'en_US:en', 09:21:36 INFO - 'LOGNAME': 'cltbld', 09:21:36 INFO - 'MAIL': '/var/mail/cltbld', 09:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:36 INFO - 'MOZ_NO_REMOTE': '1', 09:21:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:36 INFO - 'NO_EM_RESTART': '1', 09:21:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:36 INFO - 'PWD': '/builds/slave/test', 09:21:36 INFO - 'SHELL': '/bin/bash', 09:21:36 INFO - 'SHLVL': '1', 09:21:36 INFO - 'TERM': 'linux', 09:21:36 INFO - 'TMOUT': '86400', 09:21:36 INFO - 'USER': 'cltbld', 09:21:36 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:36 INFO - '_': '/tools/buildbot/bin/python'} 09:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:21:36 INFO - Running setup.py (path:/tmp/pip-f9JHal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:21:36 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)) 09:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:21:36 INFO - Running setup.py (path:/tmp/pip-x7wnFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:21:36 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)) 09:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:21:36 INFO - Running setup.py (path:/tmp/pip-DcHe7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:36 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)) 09:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:36 INFO - Running setup.py (path:/tmp/pip-t6vWyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:36 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)) 09:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:36 INFO - Running setup.py (path:/tmp/pip-uGBXLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:36 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)) 09:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:36 INFO - Running setup.py (path:/tmp/pip-lIgk9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:37 INFO - Running setup.py (path:/tmp/pip-Kz3msb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:37 INFO - Running setup.py (path:/tmp/pip-yFFrAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:37 INFO - Running setup.py (path:/tmp/pip-QWuKbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:37 INFO - Running setup.py (path:/tmp/pip-M9DOKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:37 INFO - Running setup.py (path:/tmp/pip-zrXPSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:37 INFO - Running setup.py (path:/tmp/pip-DroL6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:37 INFO - Running setup.py (path:/tmp/pip-THAYc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:37 INFO - Running setup.py (path:/tmp/pip-hBY_5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:37 INFO - Running setup.py (path:/tmp/pip-iRHEPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:37 INFO - Running setup.py (path:/tmp/pip-HvbVWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:37 INFO - Running setup.py (path:/tmp/pip-7CpFSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:37 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)) 09:21:37 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)) 09:21:37 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)) 09:21:37 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)) 09:21:37 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)) 09:21:37 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)) 09:21:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:38 INFO - Downloading blessings-1.6.tar.gz 09:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:21:38 INFO - Installing collected packages: blessings 09:21:38 INFO - Running setup.py install for blessings 09:21:39 INFO - Successfully installed blessings 09:21:39 INFO - Cleaning up... 09:21:39 INFO - Return code: 0 09:21:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:21:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:39 INFO - Reading from file tmpfile_stdout 09:21:39 INFO - Current package versions: 09:21:39 INFO - argparse == 1.2.1 09:21:39 INFO - blessings == 1.6 09:21:39 INFO - blobuploader == 1.2.4 09:21:39 INFO - docopt == 0.6.1 09:21:39 INFO - functools32 == 3.2.3-2 09:21:39 INFO - jsonschema == 2.5.1 09:21:39 INFO - manifestparser == 1.1 09:21:39 INFO - mozInstall == 1.12 09:21:39 INFO - mozcrash == 1.0 09:21:39 INFO - mozdebug == 0.1 09:21:39 INFO - mozdevice == 0.49 09:21:39 INFO - mozfile == 1.2 09:21:39 INFO - mozhttpd == 0.7 09:21:39 INFO - mozinfo == 0.9 09:21:39 INFO - mozleak == 0.1 09:21:39 INFO - mozlog == 3.4 09:21:39 INFO - moznetwork == 0.27 09:21:39 INFO - mozprocess == 0.25 09:21:39 INFO - mozprofile == 0.28 09:21:39 INFO - mozrunner == 6.13 09:21:39 INFO - mozscreenshot == 0.1 09:21:39 INFO - mozsystemmonitor == 0.3 09:21:39 INFO - moztest == 0.8 09:21:39 INFO - mozversion == 1.4 09:21:39 INFO - psutil == 3.1.1 09:21:39 INFO - requests == 1.2.3 09:21:39 INFO - wsgiref == 0.1.2 09:21:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:39 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')]} 09:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:39 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')]} 09:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:39 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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:39 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 09:21:39 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 09:21:39 INFO - Using env: {'DISPLAY': ':0', 09:21:39 INFO - 'HOME': '/home/cltbld', 09:21:39 INFO - 'LANG': 'en_US.UTF-8', 09:21:39 INFO - 'LANGUAGE': 'en_US:en', 09:21:39 INFO - 'LOGNAME': 'cltbld', 09:21:39 INFO - 'MAIL': '/var/mail/cltbld', 09:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:39 INFO - 'MOZ_NO_REMOTE': '1', 09:21:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:39 INFO - 'NO_EM_RESTART': '1', 09:21:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:39 INFO - 'PWD': '/builds/slave/test', 09:21:39 INFO - 'SHELL': '/bin/bash', 09:21:39 INFO - 'SHLVL': '1', 09:21:39 INFO - 'TERM': 'linux', 09:21:39 INFO - 'TMOUT': '86400', 09:21:39 INFO - 'USER': 'cltbld', 09:21:39 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:39 INFO - '_': '/tools/buildbot/bin/python'} 09:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 INFO - Cleaning up... 09:21:39 INFO - Return code: 0 09:21:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:21:39 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')]} 09:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:39 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')]} 09:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:39 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 0x26491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, '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': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', '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 09:21:39 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 09:21:39 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 09:21:39 INFO - Using env: {'DISPLAY': ':0', 09:21:39 INFO - 'HOME': '/home/cltbld', 09:21:39 INFO - 'LANG': 'en_US.UTF-8', 09:21:39 INFO - 'LANGUAGE': 'en_US:en', 09:21:39 INFO - 'LOGNAME': 'cltbld', 09:21:39 INFO - 'MAIL': '/var/mail/cltbld', 09:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:39 INFO - 'MOZ_NO_REMOTE': '1', 09:21:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:39 INFO - 'NO_EM_RESTART': '1', 09:21:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:39 INFO - 'PWD': '/builds/slave/test', 09:21:39 INFO - 'SHELL': '/bin/bash', 09:21:39 INFO - 'SHLVL': '1', 09:21:39 INFO - 'TERM': 'linux', 09:21:39 INFO - 'TMOUT': '86400', 09:21:39 INFO - 'USER': 'cltbld', 09:21:39 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:39 INFO - '_': '/tools/buildbot/bin/python'} 09:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:21:39 INFO - Cleaning up... 09:21:39 INFO - Return code: 0 09:21:39 INFO - Running post-action listener: _resource_record_post_action 09:21:39 INFO - Running post-action listener: _start_resource_monitoring 09:21:39 INFO - Starting resource monitoring. 09:21:39 INFO - [mozharness: 2017-05-25 16:21:39.819271Z] Finished create-virtualenv step (success) 09:21:39 INFO - [mozharness: 2017-05-25 16:21:39.819790Z] Running install step. 09:21:39 INFO - Running pre-action listener: _resource_record_pre_action 09:21:39 INFO - Running main action method: install 09:21:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:40 INFO - Reading from file tmpfile_stdout 09:21:40 INFO - Detecting whether we're running mozinstall >=1.0... 09:21:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:21:40 INFO - Reading from file tmpfile_stdout 09:21:40 INFO - Output received: 09:21:40 INFO - Usage: mozinstall [options] installer 09:21:40 INFO - Options: 09:21:40 INFO - -h, --help show this help message and exit 09:21:40 INFO - -d DEST, --destination=DEST 09:21:40 INFO - Directory to install application into. [default: 09:21:40 INFO - "/builds/slave/test"] 09:21:40 INFO - --app=APP Application being installed. [default: firefox] 09:21:40 INFO - mkdir: /builds/slave/test/build/application 09:21:40 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'] 09:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:21:54 INFO - Reading from file tmpfile_stdout 09:21:54 INFO - Output received: 09:21:54 INFO - /builds/slave/test/build/application/firefox/firefox 09:21:54 INFO - Running post-action listener: _resource_record_post_action 09:21:54 INFO - [mozharness: 2017-05-25 16:21:54.234517Z] Finished install step (success) 09:21:54 INFO - [mozharness: 2017-05-25 16:21:54.234768Z] Running run-tests step. 09:21:54 INFO - Running pre-action listener: _resource_record_pre_action 09:21:54 INFO - Running main action method: run_tests 09:21:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:21:54 INFO - Python 2.7.3 09:21:54 INFO - Return code: 0 09:21:54 INFO - grabbing minidump binary from tooltool 09:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:54 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 0x28e4400>, '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 0x28e53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28e5840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:21:54 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 09:21:54 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 09:21:54 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 09:21:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:21:54 INFO - Return code: 0 09:21:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:21:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:21:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:21:54 INFO - ENV: RUST_BACKTRACE is now 1 09:21:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:21:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:21:54 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/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 09:21:54 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/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --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 09:21:54 INFO - Using env: {'DISPLAY': ':0', 09:21:54 INFO - 'HOME': '/home/cltbld', 09:21:54 INFO - 'LANG': 'en_US.UTF-8', 09:21:54 INFO - 'LANGUAGE': 'en_US:en', 09:21:54 INFO - 'LOGNAME': 'cltbld', 09:21:54 INFO - 'MAIL': '/var/mail/cltbld', 09:21:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:21:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:54 INFO - 'MOZ_NO_REMOTE': '1', 09:21:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:21:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:54 INFO - 'NO_EM_RESTART': '1', 09:21:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:54 INFO - 'PWD': '/builds/slave/test', 09:21:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:21:54 INFO - 'RUST_BACKTRACE': '1', 09:21:54 INFO - 'SHELL': '/bin/bash', 09:21:54 INFO - 'SHLVL': '1', 09:21:54 INFO - 'TERM': 'linux', 09:21:54 INFO - 'TMOUT': '86400', 09:21:54 INFO - 'USER': 'cltbld', 09:21:54 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1495728901.29347-956784776', 09:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:54 INFO - '_': '/tools/buildbot/bin/python'} 09:21:54 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/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 09:21:54 INFO - mozversion application_buildid: 20170525150358 09:21:54 INFO - mozversion application_changeset: 38c9c1ad194a58d08440919f0c4618993917315b 09:21:54 INFO - mozversion application_display_name: Firefox 09:21:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:21:54 INFO - mozversion application_name: Firefox 09:21:54 INFO - mozversion application_remotingname: firefox 09:21:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:21:54 INFO - mozversion application_vendor: Mozilla 09:21:54 INFO - mozversion application_version: 54.0 09:21:54 INFO - mozversion platform_buildid: 20170525150358 09:21:54 INFO - mozversion platform_changeset: 38c9c1ad194a58d08440919f0c4618993917315b 09:21:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:21:54 INFO - mozversion platform_version: 54.0 09:21:54 INFO - using testdate: 1495729314 09:21:54 INFO - actual date: 1495729314 09:21:54 INFO - starting webserver on 'localhost:56896' 09:21:54 INFO - SUITE-START | Running 2 tests 09:21:54 INFO - TEST-START | dromaeo_css 09:21:54 INFO - operating with platform_type : linux_ 09:21:54 INFO - Initialising browser for dromaeo_css test... 09:21:54 INFO - TEST-INFO | started process 22112 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0fN_Y5/profile http://localhost:56896/getInfo.html) 09:21:58 INFO - PROCESS | 22112 | __metrics Screen width/height:1600/1200 09:21:58 INFO - PROCESS | 22112 | colorDepth:24 09:21:58 INFO - PROCESS | 22112 | Browser inner width/height: 1024/697 09:21:58 INFO - PROCESS | 22112 | __metrics 09:21:59 INFO - PROCESS | 22112 | 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" 09:21:59 INFO - PROCESS | 22112 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:00 INFO - TEST-INFO | 22112: exit 0 09:22:00 INFO - Browser initialized. 09:22:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:22:00 INFO - TEST-INFO | started process 22205 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0fN_Y5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:12 INFO - PROCESS | 22205 | RSS: Main: 141479936 09:22:12 INFO - PROCESS | 22205 | 09:25:14 INFO - PROCESS | 22205 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56896/tests/dromaeo/cssquery-ext.html) 09:25:15 INFO - PROCESS | 22205 | RSS: Main: 233852928 09:25:15 INFO - PROCESS | 22205 | 09:28:17 INFO - PROCESS | 22205 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-ext.html (next: http://localhost:56896/tests/dromaeo/cssquery-jquery.html) 09:28:18 INFO - PROCESS | 22205 | RSS: Main: 220475392 09:28:18 INFO - PROCESS | 22205 | 09:31:20 INFO - PROCESS | 22205 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56896/tests/dromaeo/cssquery-mootools.html) 09:31:20 INFO - PROCESS | 22205 | RSS: Main: 173236224 09:31:20 INFO - PROCESS | 22205 | 09:34:22 INFO - PROCESS | 22205 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56896/tests/dromaeo/cssquery-prototype.html) 09:34:23 INFO - PROCESS | 22205 | RSS: Main: 172244992 09:34:23 INFO - PROCESS | 22205 | 09:37:26 INFO - PROCESS | 22205 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56896/tests/dromaeo/cssquery-yui.html) 09:37:26 INFO - PROCESS | 22205 | RSS: Main: 178606080 09:37:26 INFO - PROCESS | 22205 | 09:40:28 INFO - PROCESS | 22205 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-yui.html (next: http://localhost:56896/tests/dromaeo/cssquery-dojo.html) 09:40:29 INFO - PROCESS | 22205 | RSS: Main: 257933312 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | __start_tp_report 09:40:29 INFO - PROCESS | 22205 | _x_x_mozilla_page_load 09:40:29 INFO - PROCESS | 22205 | _x_x_mozilla_page_load_details 09:40:29 INFO - PROCESS | 22205 | |i|pagename|runs| 09:40:29 INFO - PROCESS | 22205 | |0;dojo.html;3366.2089408988886;3425.7430692698044;3430.7255419566436;3447.207142357256;3473.235888104616;5728.598998070137;5803.158542011411;5814.389489103647;5817.272840894888;5830.679312637803;5804.187413762066;5820.767169313233;5881;5881.088431293146;5886.440788125132;5291.206319052144;5330.093884039723;5336.466353364672;5349.277847490578;5393.892122157555;5160.122779127539;5162.432132465426;5414.40441551431;5417.133258678612;5420.403755586883;5256.500632439931;5263.815766448175;5265.341832270966;5267.631265811395;5277.947220527803;5311.256424100629;5311.309529761145;5315.149576067811;5316.096263635152;5351.117065684158;5191.792328306901;5231.95360927816;5279.683219006872;5353.732313384316;5355.009323275207;5673;5693.146028095777;5698.51562617178;5712.2574065371655;5730.283714535683;6166.36708607948;6193.876122477525;6235.594686345373;6275.623462592213;6276.686165691697;6095.177151084591;6234.657093859836;6244.500439964792;6245.156903817971;6286.937130628681;5759.107338362561;5796.768129274824;5828.79599214034;5834.3290521589715;5841.32824725162;6375.458086062737;6386.16979792624;6409.166808314889;6410.038494225811;6415.166028416276;6068.605540639933;6167.722452489649;6171.290301615282;6174.104754810617;6177.1969643945995;6068.666223357669;6077.392260773887;6088.756449741961;6131.651934473353;6152.446279834837;6165.10605962133;6171.537134714914;6176.536759743038;6177.62934223948;6184.752609895644;5989.52083833301;6026.849328766816;6075.453209211104;6125.20492926512;6177.444030037318;4691.319758634979;4729.668923175344;4755.239161734106;4760.238361862154;4780.784864681098;4115.032967252753;4131.235721391552;4135.421041054255;4142.502899652061;4202.159568086395;5940.34656187819;5975.372585878507;5993.730282137314;6006.579539432285;6020.337762846083;6246.187995560548;6275.811725648238;6284.654344011124;6287.339829317947;6287.654179020198;5834.241548598655;5869.148973398833;5938.524918006636;5978.581499295007;6010.699465026732;5299.125644268653;5305.840824775263;5306.787728490895;5309.707966061904;5311.49540793624;6364.554481186365;6385.904211436832;6390.360963903572;6417.165768450165;6441.098246245436;6242.656653883898;6276.937230627818;6301.6534090625455;6302;6305.464035556845;6530.575512591667;6539.869202616016;6542.869142617026;6544.901826472512;6545.803625891231;5946.613470124428;5985.371535988658;6103.908441373294;6122.663253521099;6209.161763161916;6286.842828929313;6299.716512756845;6302.1492098566105;6305.716242768995;6311.116443697978;6106.7862624809695;6107.5724699272405;6116.082587611894;6117.663528505975;6141;5946.970265148647;5983.820485385445;5999.790007349722;6134.141220229082;6139.570230083851;5906.645601263938;5932.584719069624;6068.9089663654195;6105.419985101408;6109;6065.272167339947;6065.5754097212775;6072;6114.113453549299;6117.021276595723;5936.1095835625;5948.702564871825;5974.4324289192455;5992.310884248367;6011.458968692838 09:40:29 INFO - PROCESS | 22205 | |1;ext.html;780.6643143448315;827.6606591297567;843.4644001059329;862.0775769926178;877.8814859993898;31824.68175318246;47226.2916056259;48407.51592484074;48425.273620895714;48430.273545896795;46352.523560732996;46621;46710.76644616781;46759.29861052087;46774;7744.883826742575;7922.881156782653;9173.220276276508;9306.022867598906;9307.301952353566;10416.63541776038;10642.414667193301;10929.071028962548;10932;10935.070519005894;10706.78586428271;10741.570337186504;10755.516001779899;10755.83866242007;10758.193135514828;10672.199585031154;10672.733181670443;10706.197035222389;10708.892911070945;10711.732206694816;10802.5678972841;10876.510557024953;10880.292780969212;10884.564617415293;10885.346879187275;43459.78270108661;43672.3449148263;43680;43806;43834;4262.232798096342;4319.287317592625;4322.589354011396;4385.035292235703;4437;30850;31204.531932021036;31213.8439307802;31701;32649.020529383917;4046.271671099201;4106.507219133693;4131.215069136854;4240.300350442173;4365.759883206422;257108;259046;292880;297334;379209.1039544812;46950;47215;47284.52715472801;47556.5244347559;47620.52379476161;50005.49994500081;50356.244656330186;50655;50750;50785.492145078075;14937.551873443814;15035.398584056515;15054.548363549111;15069.171195584348;15072.623184420478;12157.574484892986;12379.876201238052;13141.408636611375;13233.338333083308;13595.660108497168;13347.265900375573;13377.063605547713;13444.193348399127;13545;13570.253636049914;5359.651622644516;5391.406945236021;5439.4288599696465;5481.123020316731;5526.557875369961;1028.5937054863286;1038.9688309350731;1044.2690116918188;1061.0132576703586;1064.1805809526777;28362.85818570894;29519.55720664192;29630;29880.55179172314;30024.24939376533;1093.136422226448;1095.64939219449;1117.391021893054;1134.3024040215207;1138.7836311100864;14955.700885982436;15738.370465181493;15855.841441585437;15970.441034563735;16103.19484025794;345163;345624;345972;346377;346789;58315.708421457624;58627.41372586219;58963.705181473815;59158;59222;1350.0751984890383;1532.0654400815495;1541.1446647110738;1570.214892553723;1571.324330537857;30551.54172687456;34104.65895341015;34379.14052148716;34455;34478.65521344855;44885.551144489444;47762.76118619524;48695.51304487052;53472.465275346745;53533;357462;358476;359028;359092;359722;27790.166295011182;28285.292867678472;33954.49068264027;34580.135496612784;34940.47589286214;39626;39669;39711.81585012852;39790.80104599459;39825.80087099546;31242.843785780926;31577.842110789297;31591.84204078965;31689.20776980594;32094.67905320917;31414.52878206783;31620;31774.841125794224;31933.84033079913;31978.84010579932;35784.46323305101;35832.28335433225;36056.819715901256;36059.45910811392;36186.638133618326;30709.846450767604;30995.845020774752;31049.8447507761;31134.68865311318;31397.686023139475;23947;24987.50024999526;25007;25116;25441 09:40:29 INFO - PROCESS | 22205 | |2;jquery.html;463.9791209395577;470.4448750474441;475.355892765303;486.79797883878194;493.7679290733355;20801.479963000915;22042.228522001733;22119;22429.55140897181;22473.10107595696;22872.428189295275;22978.08087676495;23347.299581012598;23785.40536486589;23868.880655596742;4993.400791904976;6719.227288861783;6832.965835170818;6880.036794848726;6886.896696549556;6639;6832.59004459734;6850.7602233921825;6853.28040555742;6908.896366554506;6777.830554236159;6786.049953006558;6805.319468053204;6812.216595091553;6813;6548.246951600579;6738.898916516257;6748.898766518506;6754.628495432774;6782.423494003016;6546.410823025951;6592.241892182413;6601.339866013408;6602.405783479462;6603.174603174603;21427.035783389783;21489.46276343088;21729.565408691895;25931;26331.73668263312;3665.376885929372;3692.4053709518234;3723.1250656095795;3767.5102236709326;3781.149241420686;17055.91472042644;17086.82913170877;17781.288748450046;17811.643767124715;18196.272149114153;2394.0902457066377;2459.0655550890547;2466.469709012553;2480.6899137607797;2535.8605276709773;97051;99401.5029924853;99643.50178249135;99753.99999999927;101563;19616;19973;20001.299954501523;20036.298729544396;20273.391798246077;35730.28539429249;35758.64241357553;35823;36017.8199109008;36237.45643815345;11208.551657933755;11322.716932076599;11412.315261084195;11420.942895285636;11554.711132221593;9392.201662858733;9455.952720236355;9497.097775711296;9651.90348096524;9681.322307438553;10329.605503257115;10555.208359373079;10565.471726413649;10732.195085368628;10807.67576972692;3664.72514561409;3703.5370578677666;3805.372113601225;3975;4080.9797550612348;783.7766236622649;887.1217494680349;897.4121950122681;968.660968660963;1025.3847691385258;22229.221977230718;22373.55252894933;22400.215992440186;22540.32379028632;22660.206892758673;990.7275499237624;1004.3973615830444;1012.4785735346303;1017.4302390661253;1030.8505266736431;12054.216475929037;12602.81095783564;12911.289879056741;12933.417996190194;12982;609733;609877.9506102441;612099;621472;651286;71265.81456563273;84469.57765211134;91571.5421422902;94163.52918235365;95954.33307763498;1332.573576455525;1346.6094832498661;1356.7829147336379;1358.3751474321725;1363.7954306853853;18516.537086572403;19168.71246931325;19205.90397048006;19477.902610486857;19587.412377628123;45401;45787.54212457966;45976.540234597225;46040.165782593685;47174.52825471701;55735;56212.64642600933;57055.71472142779;57437.425625743206;58460.70769646124;12893.48426062947;13525.18848869071;13536.796948045983;13696.452141914211;14095.365708542935;14368.13791172533;14428.567142985727;14471.348789304717;14498.63753406131;14558.980871338905;12576.685582860502;12610.432530535965;12628.936855315664;12652.746945061232;12738.108332416641;11974.521019159485;12151.81772273399;12310.507579696712;12338.198017128858;12478.18891772032;12723.109382343147;12728.87271127314;12775.169613975062;12815.679608009874;13114.672133196746;13354.332283385986;13419.329033548478;13438.059335846398;13544.79682804739;13546.458141674222;12315.076369272161;12507.061970352443;12528.624141275777;12550.435230414836;12893.355332233163 09:40:29 INFO - PROCESS | 22205 | |3;mootools.html;1536.162791278753;1537.7001484710472;1564.327339244125;1564.6479542103025;1577.5977125832903;10733.624323148691;11441.084713222932;11455.828162577567;11467.254628449165;11469.713257168565;11096.167787415936;11254.09967202624;11402.429878506062;11417.600383986563;11433.028192603619;2475.3316604516813;2508.49830033993;2512.5854234051358;2514.899404023846;2515.0819950717946;2431.8662473563945;2446.1805295226122;2448.24104527597;2456.1403508771964;2458.766417190364;2429.137656132074;2447.779699827015;2462.384403899025;2463.359526523108;2469.2468797016904;2381.583222935988;2391.4021494626345;2396.113438027923;2404.831661783676;2446.3150317911013;2332.5684748321446;2370.3363058343693;2373.062640257089;2380.6310021946624;2381.8570885746735;10818.404987725671;10980.505877235464;11000.559977600886;11005.119590432747;11013;5772;5914.645121292736;5916.053431450947;5921.940780592225;5925.2889653241455;3422.6064002639764;3463.341965026662;3544.9113772155642;3592.5329707138167;3626.637336266352;1263.684078980255;1270.9110362274644;1307.901907356952;1328.8471825740066;1339.7253563019558;14830.110193388324;15088.924555377153;15230.619234519116;16917.407890723767;17153.228104735317;4585.128825523207;4635.768211589474;4668.206404911173;4677.742724150136;4709;11010.944945275221;11273.154513411528;11907.464164112636;13073.346332683328;13079.869201308056;5825;5897.085951677497;5905.498032667186;5916.290045194604;5916.526677865761;5457.5633949283965;5482.890342193214;5517.531009864127;5523;5551.195076713854;3990.880273591797;4079.204555111725;4102.179564087135;4140.068484591003;4159.334506478949;1034.8447732840134;1041.9114375278034;1048.3081166430272;1048.6120135549895;1061.4533515239657;1084.2301965604352;1091.8908109189176;1094.7974624694298;1100.1748688483638;1103.2608152537819;7274.418046556263;7342.596157211405;7368.226336234726;7428.665710043061;7528.322450979329;1022.99488502557;1053.578568572577;1064.9094826939797;1107.1806862921542;1108.1024370259954;4853.80584776605;4886.462489126193;4976.278439626234;5065.645404821627;5070.670406423645;13412.731745365234;13695.520656777164;13741.519046833313;14203.431862725582;14370.209638469776;14208.502702405367;14223.217723025125;14227;14233.715325693429;14241.572752817432;10518.684439466828;10613.575456981634;10710.250282480152;10852.67441976742;10859.511321990529;7624.123225828879;7847.764567062997;7851.293383595275;7883.921160788319;8037.437379383387;12982.15615984958;13151.079424440197;13188.604341869759;13264.403101860633;13331.133476324008;13416.195028298333;13458.93270533641;13467.461301548221;13490.123141995738;13551.322433878464;8399;8681.08848570891;9784.706458806248;9973.052560006789;10015.849762253427;11200.271982321448;11230.94384528072;11312;11333.036691881283;11481.138914581607;9017.729468115967;9104.954475227581;9150.130737579919;9317.11487408695;9506.382085164441;9256.398334108262;9312.627494900127;9333.486658233862;9367.250619950524;9466.715998520056;8520.829583408173;8662.220400164015;8677.1756683115;8682.826343473222;8686.91313086861;6516.315786842313;6551.541392102507;6553.5084868634085;6565.146530950946;6623.337666233531;6371.362863713592;6419.8878578872;6454.160959075478;6542.967285163544;6556.934430655632 09:40:29 INFO - PROCESS | 22205 | |4;prototype.html;401.9517657881055;407.6901554818337;409.77257622019766;410.3045338151834;434.42438768631564;5300.867478313044;5346.465353464651;5359.19612058191;5384.1923711443305;5391.433899440552;4942.3080768692325;5194.77922688168;5248.973755131228;5277.366715994086;5298.7350632468315;4620.584147426731;4638.67529272951;4644.210484217674;4660.603848672867;4663.580277775;4680.765961701918;4712.670113092085;4738.810447582093;4741.976290118562;4784.856454306377;4617.122746678137;4647.861273987878;4662.97668511657;4663.720176789387;4678.274867395559;4618.8152473901;4630.606398456135;4638.930416043762;4660.510646382132;4669.501816528141;4458.264386376244;4459.550646040017;4473.194824931511;4514.774261286922;4519.615832654228;5101.234814777776;5102.061216737653;5127.307988363831;5177.301064356301;5216.426193118754;5345.529979255697;5361;5378.838634840922;5381.354237491526;5392.414044660296;5360.078757077402;5561.63849349791;5629.493345598916;5634.57713183331;5692.9146062809095;4896.412430508326;4908.435529914034;4920.204125494205;4928.457869634337;4928.876778080541;5532.723363831833;5713.542916566665;5816.738246778905;5829.242198514166;5829.708514574297;4532.32015197723;4890.975545122252;4917.6311776616185;4934.6545741798445;4946.886950436195;5115.616328775282;5136.5890728741615;5137;5164.870878227999;5181.455947125498;5307.691154347794;5320.707361095177;5338.706371149624;5360.597955153379;5370;5346.705931173745;5552.3892371839065;5601;5676.318841739017;5682.602217844711;1776.9289228430719;1786.9106544672713;1787.7765279340083;1794.425783749214;1804.2602532961423;1376.4700590272816;1394.8884089272833;1407.7395681798696;1426.3296250762126;1431.7208144411948;5328.413874473805;5357.8392648220615;5425.972870135703;5436.945630543723;5477.424870388551;5696.401877802854;5698.772049118072;5708.7155390036905;5754.971225143848;5764.8205051337445;5246.842594722164;5391.541718953933;5410.161424979135;5463.344398672105;5489;3017.7887547871437;3021.3655132422437;3022.455957927594;3075.6616772155044;3076.769242306791;5928.703564821742;5946.959282125611;5967.433093856077;5968.015992003998;5983.37174596661;5606.523445507178;5803.738831752581;5820;5838.1242813577455;5867.213073789916;5688.715564221772;5745.523958005347;5942.346341902387;5954.19618351517;5959.880802383928;5603;5702.344230413555;5709.943660422835;5711.314642242957;5740.598158128891;5632.746526406403;5648.943510564842;5652.85867853307;5731.742071606871;5736.6558006519745;5855.765769369348;5893.292804863444;5916.526677865674;5926.259217597801;6045.458408105905;4764.618830493483;4764.666473346832;4769.30845027476;4773;4777.617790576815;5572.275604171594;5738.7948530807325;5767.538596912321;5773.422657734193;5774.653520788766;5520.585956053232;5547.306586676666;5559.58303127259;5561.833145005657;5570.637908535932;5535.861603459945;5541.6120871540215;5552.361478429871;5557.805476808292;5580.38615752275;5382.3541175059245;5439.48324909133;5474.397816240131;5486.259354987025;5561.721913904369;5560.555155587463;5566.944330556643;5583.269186552182;5602.831915042555;5641.965994314085;5238.71187084714;5425.911024255769;5449.400565937823;5458.645188062763;5474.315710536183 09:40:29 INFO - PROCESS | 22205 | |5;yui.html;878.938474306798;892.9062448442913;916.8487199612065;920.7513971227769;958.856171574264;17884.55288617785;18281.63436731268;18310.542236444096;18346.724799128006;18369.448916532492;16201.918990405062;16509.09199994;16958;16982.235799389025;16985.830141698578;788.2039140468125;900.1853322742928;909.2135302962955;915.3683958068945;916.2852974679761;904.4844438669961;906.0305473143706;906.5829718329543;906.714384968736;906.7642412972641;978.2858513285306;979.0894468144659;980.7401038724744;981.617169303972;983.2429029647168;979.4661909259471;980.2599037726507;980.3627642032736;981.40134517944;981.926355523335;714.1822613107962;716.151360637646;717.4117223876424;717.7810767715845;718.5473151914313;15238.390464381411;15347.616309592238;15431.691366172727;15865.444709435229;16008.919955400264;12218.144729868913;12486.188397754117;13028.218306901616;13151;14563.199024053672;12821.551245706449;13453.529126480526;13561.07146756116;13745.38145783442;13948.790768138486;4081.5306239782212;4112.09533902541;4121.258173528764;4162.687798415101;4167.374893765929;55254.44745552573;56590.43409565893;71640.92538611926;71948;82275.17724822795;13342.866571334356;13419.865801342057;13447.798283025762;13855.445782168801;14024.579262622137;24624.50750985006;25091;25097.749022509906;25136;25153.119640812485;9263.861042084374;9275.860862087075;9484.336096473318;9603.951980240054;9844.704658860246;4978.103941290602;5051.520105589963;5081.364829396325;5113.795448182031;5215.947840521622;3993.5407428145554;4074.103697186614;4076.286649836267;4098.282800509899;4101.712880098365;676.5061505101304;680.0954730208811;713.593251846453;724.836911694875;728.8505856299446;836.9163083691582;854.3634991931051;861.5562985062697;863.5941107679381;867.6139118092389;11535.542625853788;11606.88393116075;11681.649550513499;11769.293842369485;11896.405179740977;776.444841938017;788.7239466186788;790.5335851847437;802.438293194766;809.3282575462352;5341.056626385683;5500.3949565548555;5532.225488431623;5552.361478429952;5575.860603484945;80146.59926700329;80587.59706201548;81156.59421702854;82510;85760;71788.64105679438;72992.63503682447;74939.25060749323;76653;77913.61043194862;43306.35040474396;55930.16104758432;64017.35982640207;64390.35609643844;64408;11923.344216068199;12389.318587477415;12389.442475088463;12527.895267869182;12540.372981351078;44396.55818929861;45030.0993980112;45511;45697.31454028259;46056;50049.24926126184;50370.49629503658;52155.73922130365;52382.95234095373;53307.73346133244;14739.92630036843;14842.18367989771;14898.627534311296;14920.104793712411;15134.318955646802;15455.459058933333;15720.449784257497;15745.921270393575;15751.427137725932;15769;14504.129752214902;14541.927290363903;14839.703205936037;14854.257287135817;15623.531294061197;14992.625184370478;15125.243737813285;15442.150681712614;15639.921800390925;15669;15901.52295431139;16048.518544443685;16060.598485037968;16114.919425403266;16351.754723678916;11408.372539510407;11500.654980350602;11704.356260405759;11766.58816941403;11785.941070294593;13244.197654725236;13300.933495332461;13344.5811037458;13497.39261733244;13535.72928541404 09:40:29 INFO - PROCESS | 22205 | __end_tp_report 09:40:29 INFO - PROCESS | 22205 | __start_cc_report 09:40:29 INFO - PROCESS | 22205 | _x_x_mozilla_cycle_collect,1007 09:40:29 INFO - PROCESS | 22205 | __end_cc_report 09:40:29 INFO - PROCESS | 22205 | __startTimestamp1495730429239__endTimestamp 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | ------- Summary: start ------- 09:40:29 INFO - PROCESS | 22205 | Number of tests: 6 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | [#0] dojo.html Cycles:165 Average:5779.06 Median:5993.73 stddev:659.07 (11.0%) stddev-sans-first:633.33 09:40:29 INFO - PROCESS | 22205 | Values: 3366.2 3425.7 3430.7 3447.2 3473.2 5728.6 5803.2 5814.4 5817.3 5830.7 5804.2 5820.8 5881.0 5881.1 5886.4 5291.2 5330.1 5336.5 5349.3 5393.9 5160.1 5162.4 5414.4 5417.1 5420.4 5256.5 5263.8 5265.3 5267.6 5277.9 5311.3 5311.3 5315.1 5316.1 5351.1 5191.8 5232.0 5279.7 5353.7 5355.0 5673.0 5693.1 5698.5 5712.3 5730.3 6166.4 6193.9 6235.6 6275.6 6276.7 6095.2 6234.7 6244.5 6245.2 6286.9 5759.1 5796.8 5828.8 5834.3 5841.3 6375.5 6386.2 6409.2 6410.0 6415.2 6068.6 6167.7 6171.3 6174.1 6177.2 6068.7 6077.4 6088.8 6131.7 6152.4 6165.1 6171.5 6176.5 6177.6 6184.8 5989.5 6026.8 6075.5 6125.2 6177.4 4691.3 4729.7 4755.2 4760.2 4780.8 4115.0 4131.2 4135.4 4142.5 4202.2 5940.3 5975.4 5993.7 6006.6 6020.3 6246.2 6275.8 6284.7 6287.3 6287.7 5834.2 5869.1 5938.5 5978.6 6010.7 5299.1 5305.8 5306.8 5309.7 5311.5 6364.6 6385.9 6390.4 6417.2 6441.1 6242.7 6276.9 6301.7 6302.0 6305.5 6530.6 6539.9 6542.9 6544.9 6545.8 5946.6 5985.4 6103.9 6122.7 6209.2 6286.8 6299.7 6302.1 6305.7 6311.1 6106.8 6107.6 6116.1 6117.7 6141.0 5947.0 5983.8 5999.8 6134.1 6139.6 5906.6 5932.6 6068.9 6105.4 6109.0 6065.3 6065.6 6072.0 6114.1 6117.0 5936.1 5948.7 5974.4 5992.3 6011.5 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | [#1] ext.html Cycles:180 Average:49787.13 Median:32036.76 stddev:88154.91 (275.2%) stddev-sans-first:88325.41 09:40:29 INFO - PROCESS | 22205 | Values: 780.7 827.7 843.5 862.1 877.9 31824.7 47226.3 48407.5 48425.3 48430.3 46352.5 46621.0 46710.8 46759.3 46774.0 7744.9 7922.9 9173.2 9306.0 9307.3 10416.6 10642.4 10929.1 10932.0 10935.1 10706.8 10741.6 10755.5 10755.8 10758.2 10672.2 10672.7 10706.2 10708.9 10711.7 10802.6 10876.5 10880.3 10884.6 10885.3 43459.8 43672.3 43680.0 43806.0 43834.0 4262.2 4319.3 4322.6 4385.0 4437.0 30850.0 31204.5 31213.8 31701.0 32649.0 4046.3 4106.5 4131.2 4240.3 4365.8 257108.0 259046.0 292880.0 297334.0 379209.1 46950.0 47215.0 47284.5 47556.5 47620.5 50005.5 50356.2 50655.0 50750.0 50785.5 14937.6 15035.4 15054.5 15069.2 15072.6 12157.6 12379.9 13141.4 13233.3 13595.7 13347.3 13377.1 13444.2 13545.0 13570.3 5359.7 5391.4 5439.4 5481.1 5526.6 1028.6 1039.0 1044.3 1061.0 1064.2 28362.9 29519.6 29630.0 29880.6 30024.2 1093.1 1095.6 1117.4 1134.3 1138.8 14955.7 15738.4 15855.8 15970.4 16103.2 345163.0 345624.0 345972.0 346377.0 346789.0 58315.7 58627.4 58963.7 59158.0 59222.0 1350.1 1532.1 1541.1 1570.2 1571.3 30551.5 34104.7 34379.1 34455.0 34478.7 44885.6 47762.8 48695.5 53472.5 53533.0 357462.0 358476.0 359028.0 359092.0 359722.0 27790.2 28285.3 33954.5 34580.1 34940.5 39626.0 39669.0 39711.8 39790.8 39825.8 31242.8 31577.8 31591.8 31689.2 32094.7 31414.5 31620.0 31774.8 31933.8 31978.8 35784.5 35832.3 36056.8 36059.5 36186.6 30709.8 30995.8 31049.8 31134.7 31397.7 23947.0 24987.5 25007.0 25116.0 25441.0 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | [#2] jquery.html Cycles:180 Average:35497.25 Median:23162.69 stddev:101636.84 (438.8%) stddev-sans-first:101887.92 09:40:29 INFO - PROCESS | 22205 | Values: 464.0 470.4 475.4 486.8 493.8 20801.5 22042.2 22119.0 22429.6 22473.1 22872.4 22978.1 23347.3 23785.4 23868.9 4993.4 6719.2 6833.0 6880.0 6886.9 6639.0 6832.6 6850.8 6853.3 6908.9 6777.8 6786.0 6805.3 6812.2 6813.0 6548.2 6738.9 6748.9 6754.6 6782.4 6546.4 6592.2 6601.3 6602.4 6603.2 21427.0 21489.5 21729.6 25931.0 26331.7 3665.4 3692.4 3723.1 3767.5 3781.1 17055.9 17086.8 17781.3 17811.6 18196.3 2394.1 2459.1 2466.5 2480.7 2535.9 97051.0 99401.5 99643.5 99754.0 101563.0 19616.0 19973.0 20001.3 20036.3 20273.4 35730.3 35758.6 35823.0 36017.8 36237.5 11208.6 11322.7 11412.3 11420.9 11554.7 9392.2 9456.0 9497.1 9651.9 9681.3 10329.6 10555.2 10565.5 10732.2 10807.7 3664.7 3703.5 3805.4 3975.0 4081.0 783.8 887.1 897.4 968.7 1025.4 22229.2 22373.6 22400.2 22540.3 22660.2 990.7 1004.4 1012.5 1017.4 1030.9 12054.2 12602.8 12911.3 12933.4 12982.0 609733.0 609878.0 612099.0 621472.0 651286.0 71265.8 84469.6 91571.5 94163.5 95954.3 1332.6 1346.6 1356.8 1358.4 1363.8 18516.5 19168.7 19205.9 19477.9 19587.4 45401.0 45787.5 45976.5 46040.2 47174.5 55735.0 56212.6 57055.7 57437.4 58460.7 12893.5 13525.2 13536.8 13696.5 14095.4 14368.1 14428.6 14471.3 14498.6 14559.0 12576.7 12610.4 12628.9 12652.7 12738.1 11974.5 12151.8 12310.5 12338.2 12478.2 12723.1 12728.9 12775.2 12815.7 13114.7 13354.3 13419.3 13438.1 13544.8 13546.5 12315.1 12507.1 12528.6 12550.4 12893.4 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | [#3] mootools.html Cycles:180 Average:7109.62 Median:2430.50 stddev:4415.03 (181.7%) stddev-sans-first:4407.55 09:40:29 INFO - PROCESS | 22205 | Values: 1536.2 1537.7 1564.3 1564.6 1577.6 10733.6 11441.1 11455.8 11467.3 11469.7 11096.2 11254.1 11402.4 11417.6 11433.0 2475.3 2508.5 2512.6 2514.9 2515.1 2431.9 2446.2 2448.2 2456.1 2458.8 2429.1 2447.8 2462.4 2463.4 2469.2 2381.6 2391.4 2396.1 2404.8 2446.3 2332.6 2370.3 2373.1 2380.6 2381.9 10818.4 10980.5 11000.6 11005.1 11013.0 5772.0 5914.6 5916.1 5921.9 5925.3 3422.6 3463.3 3544.9 3592.5 3626.6 1263.7 1270.9 1307.9 1328.8 1339.7 14830.1 15088.9 15230.6 16917.4 17153.2 4585.1 4635.8 4668.2 4677.7 4709.0 11010.9 11273.2 11907.5 13073.3 13079.9 5825.0 5897.1 5905.5 5916.3 5916.5 5457.6 5482.9 5517.5 5523.0 5551.2 3990.9 4079.2 4102.2 4140.1 4159.3 1034.8 1041.9 1048.3 1048.6 1061.5 1084.2 1091.9 1094.8 1100.2 1103.3 7274.4 7342.6 7368.2 7428.7 7528.3 1023.0 1053.6 1064.9 1107.2 1108.1 4853.8 4886.5 4976.3 5065.6 5070.7 13412.7 13695.5 13741.5 14203.4 14370.2 14208.5 14223.2 14227.0 14233.7 14241.6 10518.7 10613.6 10710.3 10852.7 10859.5 7624.1 7847.8 7851.3 7883.9 8037.4 12982.2 13151.1 13188.6 13264.4 13331.1 13416.2 13458.9 13467.5 13490.1 13551.3 8399.0 8681.1 9784.7 9973.1 10015.8 11200.3 11230.9 11312.0 11333.0 11481.1 9017.7 9105.0 9150.1 9317.1 9506.4 9256.4 9312.6 9333.5 9367.3 9466.7 8520.8 8662.2 8677.2 8682.8 8686.9 6516.3 6551.5 6553.5 6565.1 6623.3 6371.4 6419.9 6454.2 6543.0 6556.9 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | [#4] prototype.html Cycles:180 Average:4931.23 Median:5374.42 stddev:1265.51 (23.5%) stddev-sans-first:1222.55 09:40:29 INFO - PROCESS | 22205 | Values: 402.0 407.7 409.8 410.3 434.4 5300.9 5346.5 5359.2 5384.2 5391.4 4942.3 5194.8 5249.0 5277.4 5298.7 4620.6 4638.7 4644.2 4660.6 4663.6 4680.8 4712.7 4738.8 4742.0 4784.9 4617.1 4647.9 4663.0 4663.7 4678.3 4618.8 4630.6 4638.9 4660.5 4669.5 4458.3 4459.6 4473.2 4514.8 4519.6 5101.2 5102.1 5127.3 5177.3 5216.4 5345.5 5361.0 5378.8 5381.4 5392.4 5360.1 5561.6 5629.5 5634.6 5692.9 4896.4 4908.4 4920.2 4928.5 4928.9 5532.7 5713.5 5816.7 5829.2 5829.7 4532.3 4891.0 4917.6 4934.7 4946.9 5115.6 5136.6 5137.0 5164.9 5181.5 5307.7 5320.7 5338.7 5360.6 5370.0 5346.7 5552.4 5601.0 5676.3 5682.6 1776.9 1786.9 1787.8 1794.4 1804.3 1376.5 1394.9 1407.7 1426.3 1431.7 5328.4 5357.8 5426.0 5436.9 5477.4 5696.4 5698.8 5708.7 5755.0 5764.8 5246.8 5391.5 5410.2 5463.3 5489.0 3017.8 3021.4 3022.5 3075.7 3076.8 5928.7 5947.0 5967.4 5968.0 5983.4 5606.5 5803.7 5820.0 5838.1 5867.2 5688.7 5745.5 5942.3 5954.2 5959.9 5603.0 5702.3 5709.9 5711.3 5740.6 5632.7 5648.9 5652.9 5731.7 5736.7 5855.8 5893.3 5916.5 5926.3 6045.5 4764.6 4764.7 4769.3 4773.0 4777.6 5572.3 5738.8 5767.5 5773.4 5774.7 5520.6 5547.3 5559.6 5561.8 5570.6 5535.9 5541.6 5552.4 5557.8 5580.4 5382.4 5439.5 5474.4 5486.3 5561.7 5560.6 5566.9 5583.3 5602.8 5642.0 5238.7 5425.9 5449.4 5458.6 5474.3 09:40:29 INFO - PROCESS | 22205 | 09:40:29 INFO - PROCESS | 22205 | [#5] yui.html Cycles:180 Average:18267.74 Median:43851.45 stddev:21917.53 (50.0%) stddev-sans-first:21940.11 09:40:29 INFO - PROCESS | 22205 | Values: 878.9 892.9 916.8 920.8 958.9 17884.6 18281.6 18310.5 18346.7 18369.4 16201.9 16509.1 16958.0 16982.2 16985.8 788.2 900.2 909.2 915.4 916.3 904.5 906.0 906.6 906.7 906.8 978.3 979.1 980.7 981.6 983.2 979.5 980.3 980.4 981.4 981.9 714.2 716.2 717.4 717.8 718.5 15238.4 15347.6 15431.7 15865.4 16008.9 12218.1 12486.2 13028.2 13151.0 14563.2 12821.6 13453.5 13561.1 13745.4 13948.8 4081.5 4112.1 4121.3 4162.7 4167.4 55254.4 56590.4 71640.9 71948.0 82275.2 13342.9 13419.9 13447.8 13855.4 14024.6 24624.5 25091.0 25097.7 25136.0 25153.1 9263.9 9275.9 9484.3 9604.0 9844.7 4978.1 5051.5 5081.4 5113.8 5215.9 3993.5 4074.1 4076.3 4098.3 4101.7 676.5 680.1 713.6 724.8 728.9 836.9 854.4 861.6 863.6 867.6 11535.5 11606.9 11681.6 11769.3 11896.4 776.4 788.7 790.5 802.4 809.3 5341.1 5500.4 5532.2 5552.4 5575.9 80146.6 80587.6 81156.6 82510.0 85760.0 71788.6 72992.6 74939.3 76653.0 77913.6 43306.4 55930.2 64017.4 64390.4 64408.0 11923.3 12389.3 12389.4 12527.9 12540.4 44396.6 45030.1 45511.0 45697.3 46056.0 50049.2 50370.5 52155.7 52383.0 53307.7 14739.9 14842.2 14898.6 14920.1 15134.3 15455.5 15720.4 15745.9 15751.4 15769.0 14504.1 14541.9 14839.7 14854.3 15623.5 14992.6 15125.2 15442.2 15639.9 15669.0 15901.5 16048.5 16060.6 16114.9 16351.8 11408.4 11500.7 11704.4 11766.6 11785.9 13244.2 13300.9 13344.6 13497.4 13535.7 09:40:29 INFO - PROCESS | 22205 | -------- Summary: end -------- 09:40:29 INFO - PROCESS | 22205 | 09:40:30 INFO - TEST-INFO | 22205: exit 0 09:40:30 INFO - TEST-OK | dromaeo_css | took 1115527ms 09:40:30 INFO - TEST-START | kraken 09:40:30 INFO - operating with platform_type : linux_ 09:40:30 INFO - Initialising browser for kraken test... 09:40:30 INFO - TEST-INFO | started process 22513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo1JVmS/profile http://localhost:56896/getInfo.html) 09:40:34 INFO - PROCESS | 22513 | __metrics Screen width/height:1600/1200 09:40:34 INFO - PROCESS | 22513 | colorDepth:24 09:40:34 INFO - PROCESS | 22513 | Browser inner width/height: 1024/697 09:40:34 INFO - PROCESS | 22513 | __metrics 09:40:34 INFO - PROCESS | 22513 | 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" 09:40:34 INFO - PROCESS | 22513 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:40:35 INFO - PROCESS | 22513 | console.error: PushService: 09:40:35 INFO - PROCESS | 22513 | stateChangeProcessEnqueue: Error transitioning state 09:40:35 INFO - PROCESS | 22513 | UnknownError 09:40:35 INFO - TEST-INFO | 22513: exit 0 09:40:35 INFO - Browser initialized. 09:40:35 INFO - Running cycle 1/1 for kraken test... 09:40:35 INFO - TEST-INFO | started process 22609 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo1JVmS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:40:47 INFO - PROCESS | 22609 | RSS: Main: 141684736 09:40:47 INFO - PROCESS | 22609 | 09:40:50 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-beat-detection) 09:40:51 INFO - PROCESS | 22609 | RSS: Main: 184115200 09:40:51 INFO - PROCESS | 22609 | 09:40:53 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-dft) 09:40:54 INFO - PROCESS | 22609 | RSS: Main: 174080000 09:40:54 INFO - PROCESS | 22609 | 09:40:56 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-fft) 09:40:57 INFO - PROCESS | 22609 | RSS: Main: 265707520 09:40:57 INFO - PROCESS | 22609 | 09:40:59 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-oscillator) 09:41:00 INFO - PROCESS | 22609 | RSS: Main: 172285952 09:41:00 INFO - PROCESS | 22609 | 09:41:02 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56896/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:41:02 INFO - PROCESS | 22609 | RSS: Main: 174878720 09:41:02 INFO - PROCESS | 22609 | 09:41:05 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56896/tests/kraken/driver.html?testName=imaging-darkroom) 09:41:06 INFO - PROCESS | 22609 | RSS: Main: 212987904 09:41:06 INFO - PROCESS | 22609 | 09:41:09 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56896/tests/kraken/driver.html?testName=imaging-desaturate) 09:41:10 INFO - PROCESS | 22609 | RSS: Main: 213286912 09:41:10 INFO - PROCESS | 22609 | 09:41:13 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56896/tests/kraken/driver.html?testName=json-parse-financial) 09:41:13 INFO - PROCESS | 22609 | RSS: Main: 212574208 09:41:13 INFO - PROCESS | 22609 | 09:41:15 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56896/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:41:15 INFO - PROCESS | 22609 | RSS: Main: 173924352 09:41:15 INFO - PROCESS | 22609 | 09:41:17 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:41:18 INFO - PROCESS | 22609 | RSS: Main: 180903936 09:41:18 INFO - PROCESS | 22609 | 09:41:19 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:41:20 INFO - PROCESS | 22609 | RSS: Main: 190656512 09:41:20 INFO - PROCESS | 22609 | 09:41:23 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:41:23 INFO - PROCESS | 22609 | RSS: Main: 199970816 09:41:23 INFO - PROCESS | 22609 | 09:41:26 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:41:27 INFO - PROCESS | 22609 | RSS: Main: 196259840 09:41:27 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56896/tests/kraken/driver.html?testName=ai-astar) 09:41:28 INFO - PROCESS | 22609 | RSS: Main: 247685120 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | __start_tp_report 09:41:28 INFO - PROCESS | 22609 | _x_x_mozilla_page_load 09:41:28 INFO - PROCESS | 22609 | _x_x_mozilla_page_load_details 09:41:28 INFO - PROCESS | 22609 | |i|pagename|runs| 09:41:28 INFO - PROCESS | 22609 | |0;ai-astar;102;111;112;111;111;111;111;112;111;113 09:41:28 INFO - PROCESS | 22609 | |1;audio-beat-detection;138;116;118;151;120;115;114;116;132;115 09:41:28 INFO - PROCESS | 22609 | |2;audio-dft;161;162;166;162;161;161;161;160;161;161 09:41:28 INFO - PROCESS | 22609 | |3;audio-fft;74;75;75;106;76;76;76;76;90;75 09:41:28 INFO - PROCESS | 22609 | |4;audio-oscillator;82;82;106;82;82;82;96;82;81;103 09:41:28 INFO - PROCESS | 22609 | |5;imaging-gaussian-blur;113;113;113;113;114;115;113;113;113;113 09:41:28 INFO - PROCESS | 22609 | |6;imaging-darkroom;146;145;146;146;146;146;146;146;146;146 09:41:28 INFO - PROCESS | 22609 | |7;imaging-desaturate;94;94;93;94;94;94;94;93;93;93 09:41:28 INFO - PROCESS | 22609 | |8;json-parse-financial;54;54;54;53;55;53;54;54;53;54 09:41:28 INFO - PROCESS | 22609 | |9;json-stringify-tinderbox;54;53;54;53;53;54;54;54;54;55 09:41:28 INFO - PROCESS | 22609 | |10;stanford-crypto-aes;81;75;75;80;79;76;75;73;79;75 09:41:28 INFO - PROCESS | 22609 | |11;stanford-crypto-ccm;128;119;121;119;124;119;118;118;167;119 09:41:28 INFO - PROCESS | 22609 | |12;stanford-crypto-pbkdf2;167;159;163;159;158;159;157;161;156;156 09:41:28 INFO - PROCESS | 22609 | |13;stanford-crypto-sha256-iterative;62;60;60;59;60;63;60;58;59;58 09:41:28 INFO - PROCESS | 22609 | __end_tp_report 09:41:28 INFO - PROCESS | 22609 | __start_cc_report 09:41:28 INFO - PROCESS | 22609 | _x_x_mozilla_cycle_collect,1698 09:41:28 INFO - PROCESS | 22609 | __end_cc_report 09:41:28 INFO - PROCESS | 22609 | __startTimestamp1495730488968__endTimestamp 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | ------- Summary: start ------- 09:41:28 INFO - PROCESS | 22609 | Number of tests: 14 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:3.06 (2.8%) stddev-sans-first:0.73 09:41:28 INFO - PROCESS | 22609 | Values: 102.0 111.0 112.0 111.0 111.0 111.0 111.0 112.0 111.0 113.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:119.00 stddev:12.60 (10.6%) stddev-sans-first:12.22 09:41:28 INFO - PROCESS | 22609 | Values: 138.0 116.0 118.0 151.0 120.0 115.0 114.0 116.0 132.0 115.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.00 stddev:1.65 (1.0%) stddev-sans-first:1.73 09:41:28 INFO - PROCESS | 22609 | Values: 161.0 162.0 166.0 162.0 161.0 161.0 161.0 160.0 161.0 161.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#3] audio-fft Cycles:10 Average:79.90 Median:76.00 stddev:10.28 (13.5%) stddev-sans-first:10.68 09:41:28 INFO - PROCESS | 22609 | Values: 74.0 75.0 75.0 106.0 76.0 76.0 76.0 76.0 90.0 75.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:82.00 stddev:9.87 (12.0%) stddev-sans-first:10.25 09:41:28 INFO - PROCESS | 22609 | Values: 82.0 82.0 106.0 82.0 82.0 82.0 96.0 82.0 81.0 103.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:41:28 INFO - PROCESS | 22609 | Values: 113.0 113.0 113.0 113.0 114.0 115.0 113.0 113.0 113.0 113.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 09:41:28 INFO - PROCESS | 22609 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:41:28 INFO - PROCESS | 22609 | Values: 94.0 94.0 93.0 94.0 94.0 94.0 94.0 93.0 93.0 93.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 09:41:28 INFO - PROCESS | 22609 | Values: 54.0 54.0 54.0 53.0 55.0 53.0 54.0 54.0 53.0 54.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 09:41:28 INFO - PROCESS | 22609 | Values: 54.0 53.0 54.0 53.0 53.0 54.0 54.0 54.0 54.0 55.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:77.50 stddev:2.70 (3.5%) stddev-sans-first:2.40 09:41:28 INFO - PROCESS | 22609 | Values: 81.0 75.0 75.0 80.0 79.0 76.0 75.0 73.0 79.0 75.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:120.00 stddev:15.02 (12.5%) stddev-sans-first:15.90 09:41:28 INFO - PROCESS | 22609 | Values: 128.0 119.0 121.0 119.0 124.0 119.0 118.0 118.0 167.0 119.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.50 Median:159.00 stddev:3.41 (2.1%) stddev-sans-first:2.29 09:41:28 INFO - PROCESS | 22609 | Values: 167.0 159.0 163.0 159.0 158.0 159.0 157.0 161.0 156.0 156.0 09:41:28 INFO - PROCESS | 22609 | 09:41:28 INFO - PROCESS | 22609 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.90 Median:60.00 stddev:1.60 (2.7%) stddev-sans-first:1.50 09:41:28 INFO - PROCESS | 22609 | Values: 62.0 60.0 60.0 59.0 60.0 63.0 60.0 58.0 59.0 58.0 09:41:28 INFO - PROCESS | 22609 | -------- Summary: end -------- 09:41:28 INFO - PROCESS | 22609 | 09:41:29 INFO - TEST-INFO | 22609: exit 0 09:41:30 INFO - TEST-OK | kraken | took 59947ms 09:41:30 INFO - SUITE-END | took 1175s 09:41:30 INFO - Completed test suite (00:19:35) 09:41:30 INFO - javascript benchmark 09:41:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7966.266632010287, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3366.2089408988886, 3425.7430692698044, 3430.7255419566436, 3447.207142357256, 3473.235888104616, 5728.598998070137, 5803.158542011411, 5814.389489103647, 5817.272840894888, 5830.679312637803, 5804.187413762066, 5820.767169313233, 5881.0, 5881.088431293146, 5886.440788125132, 5291.206319052144, 5330.093884039723, 5336.466353364672, 5349.277847490578, 5393.892122157555, 5160.122779127539, 5162.432132465426, 5414.40441551431, 5417.133258678612, 5420.403755586883, 5256.500632439931, 5263.815766448175, 5265.341832270966, 5267.631265811395, 5277.947220527803, 5311.256424100629, 5311.309529761145, 5315.149576067811, 5316.096263635152, 5351.117065684158, 5191.792328306901, 5231.95360927816, 5279.683219006872, 5353.732313384316, 5355.009323275207, 5673.0, 5693.146028095777, 5698.51562617178, 5712.2574065371655, 5730.283714535683, 6166.36708607948, 6193.876122477525, 6235.594686345373, 6275.623462592213, 6276.686165691697, 6095.177151084591, 6234.657093859836, 6244.500439964792, 6245.156903817971, 6286.937130628681, 5759.107338362561, 5796.768129274824, 5828.79599214034, 5834.3290521589715, 5841.32824725162, 6375.458086062737, 6386.16979792624, 6409.166808314889, 6410.038494225811, 6415.166028416276, 6068.605540639933, 6167.722452489649, 6171.290301615282, 6174.104754810617, 6177.1969643945995, 6068.666223357669, 6077.392260773887, 6088.756449741961, 6131.651934473353, 6152.446279834837, 6165.10605962133, 6171.537134714914, 6176.536759743038, 6177.62934223948, 6184.752609895644, 5989.52083833301, 6026.849328766816, 6075.453209211104, 6125.20492926512, 6177.444030037318, 4691.319758634979, 4729.668923175344, 4755.239161734106, 4760.238361862154, 4780.784864681098, 4115.032967252753, 4131.235721391552, 4135.421041054255, 4142.502899652061, 4202.159568086395, 5940.34656187819, 5975.372585878507, 5993.730282137314, 6006.579539432285, 6020.337762846083, 6246.187995560548, 6275.811725648238, 6284.654344011124, 6287.339829317947, 6287.654179020198, 5834.241548598655, 5869.148973398833, 5938.524918006636, 5978.581499295007, 6010.699465026732, 5299.125644268653, 5305.840824775263, 5306.787728490895, 5309.707966061904, 5311.49540793624, 6364.554481186365, 6385.904211436832, 6390.360963903572, 6417.165768450165, 6441.098246245436, 6242.656653883898, 6276.937230627818, 6301.6534090625455, 6302.0, 6305.464035556845, 6530.575512591667, 6539.869202616016, 6542.869142617026, 6544.901826472512, 6545.803625891231, 5946.613470124428, 5985.371535988658, 6103.908441373294, 6122.663253521099, 6209.161763161916, 6286.842828929313, 6299.716512756845, 6302.1492098566105, 6305.716242768995, 6311.116443697978, 6106.7862624809695, 6107.5724699272405, 6116.082587611894, 6117.663528505975, 6141.0, 5946.970265148647, 5983.820485385445, 5999.790007349722, 6134.141220229082, 6139.570230083851, 5906.645601263938, 5932.584719069624, 6068.9089663654195, 6105.419985101408, 6109.0, 6065.272167339947, 6065.5754097212775, 6072.0, 6114.113453549299, 6117.021276595723, 5936.1095835625, 5948.702564871825, 5974.4324289192455, 5992.310884248367, 6011.458968692838], "value": 5734.590780959731, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.6643143448315, 827.6606591297567, 843.4644001059329, 862.0775769926178, 877.8814859993898, 31824.68175318246, 47226.2916056259, 48407.51592484074, 48425.273620895714, 48430.273545896795, 46352.523560732996, 46621.0, 46710.76644616781, 46759.29861052087, 46774.0, 7744.883826742575, 7922.881156782653, 9173.220276276508, 9306.022867598906, 9307.301952353566, 10416.63541776038, 10642.414667193301, 10929.071028962548, 10932.0, 10935.070519005894, 10706.78586428271, 10741.570337186504, 10755.516001779899, 10755.83866242007, 10758.193135514828, 10672.199585031154, 10672.733181670443, 10706.197035222389, 10708.892911070945, 10711.732206694816, 10802.5678972841, 10876.510557024953, 10880.292780969212, 10884.564617415293, 10885.346879187275, 43459.78270108661, 43672.3449148263, 43680.0, 43806.0, 43834.0, 4262.232798096342, 4319.287317592625, 4322.589354011396, 4385.035292235703, 4437.0, 30850.0, 31204.531932021036, 31213.8439307802, 31701.0, 32649.020529383917, 4046.271671099201, 4106.507219133693, 4131.215069136854, 4240.300350442173, 4365.759883206422, 257108.0, 259046.0, 292880.0, 297334.0, 379209.1039544812, 46950.0, 47215.0, 47284.52715472801, 47556.5244347559, 47620.52379476161, 50005.49994500081, 50356.244656330186, 50655.0, 50750.0, 50785.492145078075, 14937.551873443814, 15035.398584056515, 15054.548363549111, 15069.171195584348, 15072.623184420478, 12157.574484892986, 12379.876201238052, 13141.408636611375, 13233.338333083308, 13595.660108497168, 13347.265900375573, 13377.063605547713, 13444.193348399127, 13545.0, 13570.253636049914, 5359.651622644516, 5391.406945236021, 5439.4288599696465, 5481.123020316731, 5526.557875369961, 1028.5937054863286, 1038.9688309350731, 1044.2690116918188, 1061.0132576703586, 1064.1805809526777, 28362.85818570894, 29519.55720664192, 29630.0, 29880.55179172314, 30024.24939376533, 1093.136422226448, 1095.64939219449, 1117.391021893054, 1134.3024040215207, 1138.7836311100864, 14955.700885982436, 15738.370465181493, 15855.841441585437, 15970.441034563735, 16103.19484025794, 345163.0, 345624.0, 345972.0, 346377.0, 346789.0, 58315.708421457624, 58627.41372586219, 58963.705181473815, 59158.0, 59222.0, 1350.0751984890383, 1532.0654400815495, 1541.1446647110738, 1570.214892553723, 1571.324330537857, 30551.54172687456, 34104.65895341015, 34379.14052148716, 34455.0, 34478.65521344855, 44885.551144489444, 47762.76118619524, 48695.51304487052, 53472.465275346745, 53533.0, 357462.0, 358476.0, 359028.0, 359092.0, 359722.0, 27790.166295011182, 28285.292867678472, 33954.49068264027, 34580.135496612784, 34940.47589286214, 39626.0, 39669.0, 39711.81585012852, 39790.80104599459, 39825.80087099546, 31242.843785780926, 31577.842110789297, 31591.84204078965, 31689.20776980594, 32094.67905320917, 31414.52878206783, 31620.0, 31774.841125794224, 31933.84033079913, 31978.84010579932, 35784.46323305101, 35832.28335433225, 36056.819715901256, 36059.45910811392, 36186.638133618326, 30709.846450767604, 30995.845020774752, 31049.8447507761, 31134.68865311318, 31397.686023139475, 23947.0, 24987.50024999526, 25007.0, 25116.0, 25441.0], "value": 19280.318211114125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.9791209395577, 470.4448750474441, 475.355892765303, 486.79797883878194, 493.7679290733355, 20801.479963000915, 22042.228522001733, 22119.0, 22429.55140897181, 22473.10107595696, 22872.428189295275, 22978.08087676495, 23347.299581012598, 23785.40536486589, 23868.880655596742, 4993.400791904976, 6719.227288861783, 6832.965835170818, 6880.036794848726, 6886.896696549556, 6639.0, 6832.59004459734, 6850.7602233921825, 6853.28040555742, 6908.896366554506, 6777.830554236159, 6786.049953006558, 6805.319468053204, 6812.216595091553, 6813.0, 6548.246951600579, 6738.898916516257, 6748.898766518506, 6754.628495432774, 6782.423494003016, 6546.410823025951, 6592.241892182413, 6601.339866013408, 6602.405783479462, 6603.174603174603, 21427.035783389783, 21489.46276343088, 21729.565408691895, 25931.0, 26331.73668263312, 3665.376885929372, 3692.4053709518234, 3723.1250656095795, 3767.5102236709326, 3781.149241420686, 17055.91472042644, 17086.82913170877, 17781.288748450046, 17811.643767124715, 18196.272149114153, 2394.0902457066377, 2459.0655550890547, 2466.469709012553, 2480.6899137607797, 2535.8605276709773, 97051.0, 99401.5029924853, 99643.50178249135, 99753.99999999927, 101563.0, 19616.0, 19973.0, 20001.299954501523, 20036.298729544396, 20273.391798246077, 35730.28539429249, 35758.64241357553, 35823.0, 36017.8199109008, 36237.45643815345, 11208.551657933755, 11322.716932076599, 11412.315261084195, 11420.942895285636, 11554.711132221593, 9392.201662858733, 9455.952720236355, 9497.097775711296, 9651.90348096524, 9681.322307438553, 10329.605503257115, 10555.208359373079, 10565.471726413649, 10732.195085368628, 10807.67576972692, 3664.72514561409, 3703.5370578677666, 3805.372113601225, 3975.0, 4080.9797550612348, 783.7766236622649, 887.1217494680349, 897.4121950122681, 968.660968660963, 1025.3847691385258, 22229.221977230718, 22373.55252894933, 22400.215992440186, 22540.32379028632, 22660.206892758673, 990.7275499237624, 1004.3973615830444, 1012.4785735346303, 1017.4302390661253, 1030.8505266736431, 12054.216475929037, 12602.81095783564, 12911.289879056741, 12933.417996190194, 12982.0, 609733.0, 609877.9506102441, 612099.0, 621472.0, 651286.0, 71265.81456563273, 84469.57765211134, 91571.5421422902, 94163.52918235365, 95954.33307763498, 1332.573576455525, 1346.6094832498661, 1356.7829147336379, 1358.3751474321725, 1363.7954306853853, 18516.537086572403, 19168.71246931325, 19205.90397048006, 19477.902610486857, 19587.412377628123, 45401.0, 45787.54212457966, 45976.540234597225, 46040.165782593685, 47174.52825471701, 55735.0, 56212.64642600933, 57055.71472142779, 57437.425625743206, 58460.70769646124, 12893.48426062947, 13525.18848869071, 13536.796948045983, 13696.452141914211, 14095.365708542935, 14368.13791172533, 14428.567142985727, 14471.348789304717, 14498.63753406131, 14558.980871338905, 12576.685582860502, 12610.432530535965, 12628.936855315664, 12652.746945061232, 12738.108332416641, 11974.521019159485, 12151.81772273399, 12310.507579696712, 12338.198017128858, 12478.18891772032, 12723.109382343147, 12728.87271127314, 12775.169613975062, 12815.679608009874, 13114.672133196746, 13354.332283385986, 13419.329033548478, 13438.059335846398, 13544.79682804739, 13546.458141674222, 12315.076369272161, 12507.061970352443, 12528.624141275777, 12550.435230414836, 12893.355332233163], "value": 11657.476142196585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1536.162791278753, 1537.7001484710472, 1564.327339244125, 1564.6479542103025, 1577.5977125832903, 10733.624323148691, 11441.084713222932, 11455.828162577567, 11467.254628449165, 11469.713257168565, 11096.167787415936, 11254.09967202624, 11402.429878506062, 11417.600383986563, 11433.028192603619, 2475.3316604516813, 2508.49830033993, 2512.5854234051358, 2514.899404023846, 2515.0819950717946, 2431.8662473563945, 2446.1805295226122, 2448.24104527597, 2456.1403508771964, 2458.766417190364, 2429.137656132074, 2447.779699827015, 2462.384403899025, 2463.359526523108, 2469.2468797016904, 2381.583222935988, 2391.4021494626345, 2396.113438027923, 2404.831661783676, 2446.3150317911013, 2332.5684748321446, 2370.3363058343693, 2373.062640257089, 2380.6310021946624, 2381.8570885746735, 10818.404987725671, 10980.505877235464, 11000.559977600886, 11005.119590432747, 11013.0, 5772.0, 5914.645121292736, 5916.053431450947, 5921.940780592225, 5925.2889653241455, 3422.6064002639764, 3463.341965026662, 3544.9113772155642, 3592.5329707138167, 3626.637336266352, 1263.684078980255, 1270.9110362274644, 1307.901907356952, 1328.8471825740066, 1339.7253563019558, 14830.110193388324, 15088.924555377153, 15230.619234519116, 16917.407890723767, 17153.228104735317, 4585.128825523207, 4635.768211589474, 4668.206404911173, 4677.742724150136, 4709.0, 11010.944945275221, 11273.154513411528, 11907.464164112636, 13073.346332683328, 13079.869201308056, 5825.0, 5897.085951677497, 5905.498032667186, 5916.290045194604, 5916.526677865761, 5457.5633949283965, 5482.890342193214, 5517.531009864127, 5523.0, 5551.195076713854, 3990.880273591797, 4079.204555111725, 4102.179564087135, 4140.068484591003, 4159.334506478949, 1034.8447732840134, 1041.9114375278034, 1048.3081166430272, 1048.6120135549895, 1061.4533515239657, 1084.2301965604352, 1091.8908109189176, 1094.7974624694298, 1100.1748688483638, 1103.2608152537819, 7274.418046556263, 7342.596157211405, 7368.226336234726, 7428.665710043061, 7528.322450979329, 1022.99488502557, 1053.578568572577, 1064.9094826939797, 1107.1806862921542, 1108.1024370259954, 4853.80584776605, 4886.462489126193, 4976.278439626234, 5065.645404821627, 5070.670406423645, 13412.731745365234, 13695.520656777164, 13741.519046833313, 14203.431862725582, 14370.209638469776, 14208.502702405367, 14223.217723025125, 14227.0, 14233.715325693429, 14241.572752817432, 10518.684439466828, 10613.575456981634, 10710.250282480152, 10852.67441976742, 10859.511321990529, 7624.123225828879, 7847.764567062997, 7851.293383595275, 7883.921160788319, 8037.437379383387, 12982.15615984958, 13151.079424440197, 13188.604341869759, 13264.403101860633, 13331.133476324008, 13416.195028298333, 13458.93270533641, 13467.461301548221, 13490.123141995738, 13551.322433878464, 8399.0, 8681.08848570891, 9784.706458806248, 9973.052560006789, 10015.849762253427, 11200.271982321448, 11230.94384528072, 11312.0, 11333.036691881283, 11481.138914581607, 9017.729468115967, 9104.954475227581, 9150.130737579919, 9317.11487408695, 9506.382085164441, 9256.398334108262, 9312.627494900127, 9333.486658233862, 9367.250619950524, 9466.715998520056, 8520.829583408173, 8662.220400164015, 8677.1756683115, 8682.826343473222, 8686.91313086861, 6516.315786842313, 6551.541392102507, 6553.5084868634085, 6565.146530950946, 6623.337666233531, 6371.362863713592, 6419.8878578872, 6454.160959075478, 6542.967285163544, 6556.934430655632], "value": 5421.572790464656, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.9517657881055, 407.6901554818337, 409.77257622019766, 410.3045338151834, 434.42438768631564, 5300.867478313044, 5346.465353464651, 5359.19612058191, 5384.1923711443305, 5391.433899440552, 4942.3080768692325, 5194.77922688168, 5248.973755131228, 5277.366715994086, 5298.7350632468315, 4620.584147426731, 4638.67529272951, 4644.210484217674, 4660.603848672867, 4663.580277775, 4680.765961701918, 4712.670113092085, 4738.810447582093, 4741.976290118562, 4784.856454306377, 4617.122746678137, 4647.861273987878, 4662.97668511657, 4663.720176789387, 4678.274867395559, 4618.8152473901, 4630.606398456135, 4638.930416043762, 4660.510646382132, 4669.501816528141, 4458.264386376244, 4459.550646040017, 4473.194824931511, 4514.774261286922, 4519.615832654228, 5101.234814777776, 5102.061216737653, 5127.307988363831, 5177.301064356301, 5216.426193118754, 5345.529979255697, 5361.0, 5378.838634840922, 5381.354237491526, 5392.414044660296, 5360.078757077402, 5561.63849349791, 5629.493345598916, 5634.57713183331, 5692.9146062809095, 4896.412430508326, 4908.435529914034, 4920.204125494205, 4928.457869634337, 4928.876778080541, 5532.723363831833, 5713.542916566665, 5816.738246778905, 5829.242198514166, 5829.708514574297, 4532.32015197723, 4890.975545122252, 4917.6311776616185, 4934.6545741798445, 4946.886950436195, 5115.616328775282, 5136.5890728741615, 5137.0, 5164.870878227999, 5181.455947125498, 5307.691154347794, 5320.707361095177, 5338.706371149624, 5360.597955153379, 5370.0, 5346.705931173745, 5552.3892371839065, 5601.0, 5676.318841739017, 5682.602217844711, 1776.9289228430719, 1786.9106544672713, 1787.7765279340083, 1794.425783749214, 1804.2602532961423, 1376.4700590272816, 1394.8884089272833, 1407.7395681798696, 1426.3296250762126, 1431.7208144411948, 5328.413874473805, 5357.8392648220615, 5425.972870135703, 5436.945630543723, 5477.424870388551, 5696.401877802854, 5698.772049118072, 5708.7155390036905, 5754.971225143848, 5764.8205051337445, 5246.842594722164, 5391.541718953933, 5410.161424979135, 5463.344398672105, 5489.0, 3017.7887547871437, 3021.3655132422437, 3022.455957927594, 3075.6616772155044, 3076.769242306791, 5928.703564821742, 5946.959282125611, 5967.433093856077, 5968.015992003998, 5983.37174596661, 5606.523445507178, 5803.738831752581, 5820.0, 5838.1242813577455, 5867.213073789916, 5688.715564221772, 5745.523958005347, 5942.346341902387, 5954.19618351517, 5959.880802383928, 5603.0, 5702.344230413555, 5709.943660422835, 5711.314642242957, 5740.598158128891, 5632.746526406403, 5648.943510564842, 5652.85867853307, 5731.742071606871, 5736.6558006519745, 5855.765769369348, 5893.292804863444, 5916.526677865674, 5926.259217597801, 6045.458408105905, 4764.618830493483, 4764.666473346832, 4769.30845027476, 4773.0, 4777.617790576815, 5572.275604171594, 5738.7948530807325, 5767.538596912321, 5773.422657734193, 5774.653520788766, 5520.585956053232, 5547.306586676666, 5559.58303127259, 5561.833145005657, 5570.637908535932, 5535.861603459945, 5541.6120871540215, 5552.361478429871, 5557.805476808292, 5580.38615752275, 5382.3541175059245, 5439.48324909133, 5474.397816240131, 5486.259354987025, 5561.721913904369, 5560.555155587463, 5566.944330556643, 5583.269186552182, 5602.831915042555, 5641.965994314085, 5238.71187084714, 5425.911024255769, 5449.400565937823, 5458.645188062763, 5474.315710536183], "value": 4564.435017645147, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.938474306798, 892.9062448442913, 916.8487199612065, 920.7513971227769, 958.856171574264, 17884.55288617785, 18281.63436731268, 18310.542236444096, 18346.724799128006, 18369.448916532492, 16201.918990405062, 16509.09199994, 16958.0, 16982.235799389025, 16985.830141698578, 788.2039140468125, 900.1853322742928, 909.2135302962955, 915.3683958068945, 916.2852974679761, 904.4844438669961, 906.0305473143706, 906.5829718329543, 906.714384968736, 906.7642412972641, 978.2858513285306, 979.0894468144659, 980.7401038724744, 981.617169303972, 983.2429029647168, 979.4661909259471, 980.2599037726507, 980.3627642032736, 981.40134517944, 981.926355523335, 714.1822613107962, 716.151360637646, 717.4117223876424, 717.7810767715845, 718.5473151914313, 15238.390464381411, 15347.616309592238, 15431.691366172727, 15865.444709435229, 16008.919955400264, 12218.144729868913, 12486.188397754117, 13028.218306901616, 13151.0, 14563.199024053672, 12821.551245706449, 13453.529126480526, 13561.07146756116, 13745.38145783442, 13948.790768138486, 4081.5306239782212, 4112.09533902541, 4121.258173528764, 4162.687798415101, 4167.374893765929, 55254.44745552573, 56590.43409565893, 71640.92538611926, 71948.0, 82275.17724822795, 13342.866571334356, 13419.865801342057, 13447.798283025762, 13855.445782168801, 14024.579262622137, 24624.50750985006, 25091.0, 25097.749022509906, 25136.0, 25153.119640812485, 9263.861042084374, 9275.860862087075, 9484.336096473318, 9603.951980240054, 9844.704658860246, 4978.103941290602, 5051.520105589963, 5081.364829396325, 5113.795448182031, 5215.947840521622, 3993.5407428145554, 4074.103697186614, 4076.286649836267, 4098.282800509899, 4101.712880098365, 676.5061505101304, 680.0954730208811, 713.593251846453, 724.836911694875, 728.8505856299446, 836.9163083691582, 854.3634991931051, 861.5562985062697, 863.5941107679381, 867.6139118092389, 11535.542625853788, 11606.88393116075, 11681.649550513499, 11769.293842369485, 11896.405179740977, 776.444841938017, 788.7239466186788, 790.5335851847437, 802.438293194766, 809.3282575462352, 5341.056626385683, 5500.3949565548555, 5532.225488431623, 5552.361478429952, 5575.860603484945, 80146.59926700329, 80587.59706201548, 81156.59421702854, 82510.0, 85760.0, 71788.64105679438, 72992.63503682447, 74939.25060749323, 76653.0, 77913.61043194862, 43306.35040474396, 55930.16104758432, 64017.35982640207, 64390.35609643844, 64408.0, 11923.344216068199, 12389.318587477415, 12389.442475088463, 12527.895267869182, 12540.372981351078, 44396.55818929861, 45030.0993980112, 45511.0, 45697.31454028259, 46056.0, 50049.24926126184, 50370.49629503658, 52155.73922130365, 52382.95234095373, 53307.73346133244, 14739.92630036843, 14842.18367989771, 14898.627534311296, 14920.104793712411, 15134.318955646802, 15455.459058933333, 15720.449784257497, 15745.921270393575, 15751.427137725932, 15769.0, 14504.129752214902, 14541.927290363903, 14839.703205936037, 14854.257287135817, 15623.531294061197, 14992.625184370478, 15125.243737813285, 15442.150681712614, 15639.921800390925, 15669.0, 15901.52295431139, 16048.518544443685, 16060.598485037968, 16114.919425403266, 16351.754723678916, 11408.372539510407, 11500.654980350602, 11704.356260405759, 11766.58816941403, 11785.941070294593, 13244.197654725236, 13300.933495332461, 13344.5811037458, 13497.39261733244, 13535.72928541404], "value": 8012.3088981710525, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1445.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 111.0, 111.0, 111.0, 111.0, 112.0, 111.0, 113.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 116.0, 118.0, 151.0, 120.0, 115.0, 114.0, 116.0, 132.0, 115.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 166.0, 162.0, 161.0, 161.0, 161.0, 160.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 106.0, 76.0, 76.0, 76.0, 76.0, 90.0, 75.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 106.0, 82.0, 82.0, 82.0, 96.0, 82.0, 81.0, 103.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 114.0, 115.0, 113.0, 113.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 53.0, 55.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 53.0, 54.0, 54.0, 54.0, 54.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 75.0, 80.0, 79.0, 76.0, 75.0, 73.0, 79.0, 75.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 119.0, 121.0, 119.0, 124.0, 119.0, 118.0, 118.0, 167.0, 119.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 159.0, 163.0, 159.0, 158.0, 159.0, 157.0, 161.0, 156.0, 156.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 59.0, 60.0, 63.0, 60.0, 58.0, 59.0, 58.0], "value": 59.9, "unit": "score"}]}]} 09:41:30 INFO - Return code: 0 09:41:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:41:30 INFO - # TBPL SUCCESS # 09:41:30 INFO - Running post-action listener: _resource_record_post_action 09:41:30 INFO - [mozharness: 2017-05-25 16:41:30.292228Z] Finished run-tests step (success) 09:41:30 INFO - Running post-run listener: _resource_record_post_run 09:41:30 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 307200; Write bytes: 326004736; Read time: 28; Write time: 130820 09:41:30 INFO - TinderboxPrint: CPU usage
13.1% 09:41:30 INFO - TinderboxPrint: I/O read bytes / time
307,200 / 28 09:41:30 INFO - TinderboxPrint: I/O write bytes / time
326,004,736 / 130,820 09:41:30 INFO - TinderboxPrint: CPU idle
8,252.1 (86.9%) 09:41:30 INFO - TinderboxPrint: CPU user
1,202.7 (12.7%) 09:41:30 INFO - TinderboxPrint: Swap in / out
0 / 0 09:41:30 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 90517504; Read time: 0; Write time: 50276 09:41:30 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 303104; Write bytes: 235487232; Read time: 16; Write time: 80544 09:41:30 INFO - Running post-run listener: _upload_blobber_files 09:41:30 INFO - Blob upload gear active. 09:41:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:41:30 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 09:41:30 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'] 09:41:30 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 09:41:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:41:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:41:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:41:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:32 INFO - (blobuploader) - INFO - Done attempting. 09:41:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:41:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:41:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:33 INFO - (blobuploader) - INFO - Done attempting. 09:41:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:41:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:41:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:37 INFO - (blobuploader) - INFO - Done attempting. 09:41:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:41:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:41:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:41:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:41:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:41:38 INFO - (blobuploader) - INFO - Done attempting. 09:41:38 INFO - (blobuploader) - INFO - Iteration through files over. 09:41:38 INFO - Return code: 0 09:41:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:41:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:41:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65a67c8e4302d1048eec8e18edd1fc018c18dc943629c9f6370c9751bbabbc7da922038e19c4949c1aea5e9aad7bacf18b9045425d7d7a6b7d9221dd775272f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3441b0e645186dce09fb8747d6df4aca91901a4095f952c8408b615aab82e1780bc6e88ea81028460f714c8cd980b15081ba7b7ef5576e92100b9ee5adff9362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/908c9f1b2095ac07f2600c3ebdfa8e16a07b3a52a482b0dbb8ff2ba1bc73a2c1624b4272039d1faca1348400dfeec5e4f4193a62cc6382aa61814903f55f6405", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e1fcd4847b5dc81c87aaf1c3712913ce846cd4762ba8cf52957c0566ba5cb4744f563186cf659eec521a0d0ba56d57229e7efda1eba175ff8eb8e3ed3cbe31c4"} 09:41:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:41:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:41:38 INFO - Contents: 09:41:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65a67c8e4302d1048eec8e18edd1fc018c18dc943629c9f6370c9751bbabbc7da922038e19c4949c1aea5e9aad7bacf18b9045425d7d7a6b7d9221dd775272f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3441b0e645186dce09fb8747d6df4aca91901a4095f952c8408b615aab82e1780bc6e88ea81028460f714c8cd980b15081ba7b7ef5576e92100b9ee5adff9362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/908c9f1b2095ac07f2600c3ebdfa8e16a07b3a52a482b0dbb8ff2ba1bc73a2c1624b4272039d1faca1348400dfeec5e4f4193a62cc6382aa61814903f55f6405", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e1fcd4847b5dc81c87aaf1c3712913ce846cd4762ba8cf52957c0566ba5cb4744f563186cf659eec521a0d0ba56d57229e7efda1eba175ff8eb8e3ed3cbe31c4"} 09:41:38 INFO - Running post-run listener: copy_logs_to_upload_dir 09:41:38 INFO - Copying logs to upload dir... 09:41:38 INFO - mkdir: /builds/slave/test/build/upload/logs 09:41:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.036873 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-25 09:41:38.094478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 09:41:38.102303) ========= 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=483e410453545d07cca6935c000001ab-1495728901.29347-956784776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65a67c8e4302d1048eec8e18edd1fc018c18dc943629c9f6370c9751bbabbc7da922038e19c4949c1aea5e9aad7bacf18b9045425d7d7a6b7d9221dd775272f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3441b0e645186dce09fb8747d6df4aca91901a4095f952c8408b615aab82e1780bc6e88ea81028460f714c8cd980b15081ba7b7ef5576e92100b9ee5adff9362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/908c9f1b2095ac07f2600c3ebdfa8e16a07b3a52a482b0dbb8ff2ba1bc73a2c1624b4272039d1faca1348400dfeec5e4f4193a62cc6382aa61814903f55f6405", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e1fcd4847b5dc81c87aaf1c3712913ce846cd4762ba8cf52957c0566ba5cb4744f563186cf659eec521a0d0ba56d57229e7efda1eba175ff8eb8e3ed3cbe31c4"} build_url:https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/VHgymqa8SV-Z-CZNTXU_TA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/65a67c8e4302d1048eec8e18edd1fc018c18dc943629c9f6370c9751bbabbc7da922038e19c4949c1aea5e9aad7bacf18b9045425d7d7a6b7d9221dd775272f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3441b0e645186dce09fb8747d6df4aca91901a4095f952c8408b615aab82e1780bc6e88ea81028460f714c8cd980b15081ba7b7ef5576e92100b9ee5adff9362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/908c9f1b2095ac07f2600c3ebdfa8e16a07b3a52a482b0dbb8ff2ba1bc73a2c1624b4272039d1faca1348400dfeec5e4f4193a62cc6382aa61814903f55f6405", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e1fcd4847b5dc81c87aaf1c3712913ce846cd4762ba8cf52957c0566ba5cb4744f563186cf659eec521a0d0ba56d57229e7efda1eba175ff8eb8e3ed3cbe31c4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 09:41:38.133991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:41:38.134335) ========= 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=483e410453545d07cca6935c000001ab-1495728901.29347-956784776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:41:38.214604) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 09:41:38.217955) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 09:41:38.218262) ========= ========= Total master_lag: 0.45 =========