builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1496347848.74 results: success (0) revision: a85ff8f8399de4eea50c9f3aece921814c98d016 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.740402) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.740913) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.741224) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.757420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.757721) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-01 13:10:48-- 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% 19.8M=0.001s 2017-06-01 13:10:48 (19.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108816 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.880165) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.883511) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015709 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:48.933262) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 13:10:48.936634) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a85ff8f8399de4eea50c9f3aece921814c98d016 --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 a85ff8f8399de4eea50c9f3aece921814c98d016 --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=56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-01 13:10:49,048 truncating revision to first 12 chars 2017-06-01 13:10:49,048 Setting DEBUG logging. 2017-06-01 13:10:49,048 attempt 1/10 2017-06-01 13:10:49,048 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a85ff8f8399d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 13:10:50,295 unpacking tar archive at: mozilla-beta-a85ff8f8399d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.556688 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 13:10:50.506147) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:50.509546) ========= script_repo_revision: a85ff8f8399de4eea50c9f3aece921814c98d016 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:50.509959) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:50.510242) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 13:10:50.519057) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-06-01 13:10:50.519392) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:10:50 INFO - MultiFileLogger online at 20170601 13:10:50 in /builds/slave/test 13:10:50 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 13:10:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:10:50 INFO - {'append_to_log': False, 13:10:50 INFO - 'base_work_dir': '/builds/slave/test', 13:10:50 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 13:10:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:10:50 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 13:10:50 INFO - 'buildbot_json_path': 'buildprops.json', 13:10:50 INFO - 'config_files': ('talos/linux_config.py',), 13:10:50 INFO - 'default_actions': ('clobber', 13:10:50 INFO - 'read-buildbot-config', 13:10:50 INFO - 'download-and-extract', 13:10:50 INFO - 'populate-webroot', 13:10:50 INFO - 'create-virtualenv', 13:10:50 INFO - 'install', 13:10:50 INFO - 'run-tests'), 13:10:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:10:50 INFO - 'download_minidump_stackwalk': True, 13:10:50 INFO - 'download_symbols': 'ondemand', 13:10:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:10:50 INFO - 'tooltool.py': '/tools/tooltool.py', 13:10:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:10:50 INFO - '/tools/misc-python/virtualenv.py')}, 13:10:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:10:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:10:50 INFO - 'gecko_profile': False, 13:10:50 INFO - 'gecko_profile_interval': 0, 13:10:50 INFO - 'installer_path': 'installer.exe', 13:10:50 INFO - 'log_level': 'info', 13:10:50 INFO - 'log_name': 'talos', 13:10:50 INFO - 'log_to_console': True, 13:10:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:10:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:10:50 INFO - 'opt_config_files': (), 13:10:50 INFO - 'pip_index': False, 13:10:50 INFO - 'suite': 'dromaeojs', 13:10:50 INFO - 'system_bits': '32', 13:10:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:10:50 INFO - 'title': 'talos-linux64-ix-037', 13:10:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:10:50 INFO - 'use_talos_json': True, 13:10:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:10:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:10:50 INFO - 'work_dir': 'build'} 13:10:50 INFO - [mozharness: 2017-06-01 20:10:50.730495Z] Running clobber step. 13:10:50 INFO - Running pre-action listener: _resource_record_pre_action 13:10:50 INFO - Running main action method: clobber 13:10:50 INFO - rmtree: /builds/slave/test/build 13:10:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:10:51 INFO - Running post-action listener: _resource_record_post_action 13:10:51 INFO - [mozharness: 2017-06-01 20:10:51.656266Z] Finished clobber step (success) 13:10:51 INFO - [mozharness: 2017-06-01 20:10:51.656366Z] Running read-buildbot-config step. 13:10:51 INFO - Running pre-action listener: _resource_record_pre_action 13:10:51 INFO - Running main action method: read_buildbot_config 13:10:51 INFO - Using buildbot properties: 13:10:51 INFO - { 13:10:51 INFO - "project": "", 13:10:51 INFO - "product": "firefox", 13:10:51 INFO - "who": "ryanvm@gmail.com", 13:10:51 INFO - "installer_path": "public/build/target.tar.bz2", 13:10:51 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:10:51 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 13:10:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 13:10:51 INFO - "stage_platform": "linux64", 13:10:51 INFO - "basedir": "/builds/slave/test", 13:10:51 INFO - "buildnumber": 66, 13:10:51 INFO - "platform": "ubuntu64_hw", 13:10:51 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:10:51 INFO - "slavebuilddir": "test", 13:10:51 INFO - "taskId": "Dn8xMUoxTPCpQwMziM5x8Q", 13:10:51 INFO - "branch": "mozilla-beta", 13:10:51 INFO - "script_repo_revision": "production", 13:10:51 INFO - "revision": "a85ff8f8399de4eea50c9f3aece921814c98d016", 13:10:51 INFO - "slavename": "talos-linux64-ix-037", 13:10:51 INFO - "repo_path": "releases/mozilla-beta" 13:10:51 INFO - } 13:10:51 INFO - Finding installer, test and symbols from parent task. 13:10:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dn8xMUoxTPCpQwMziM5x8Q'}, attempt #1 13:10:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dn8xMUoxTPCpQwMziM5x8Q'}, attempt #1 13:10:52 INFO - Task dependencies: E9WRSt1oSNuSlqUemC7gpg 13:10:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg'}, attempt #1 13:10:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2 13:10:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/test_packages.json 13:10:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.crashreporter-symbols.zip 13:10:53 INFO - Running post-action listener: _resource_record_post_action 13:10:53 INFO - [mozharness: 2017-06-01 20:10:53.074261Z] Finished read-buildbot-config step (success) 13:10:53 INFO - [mozharness: 2017-06-01 20:10:53.074479Z] Running download-and-extract step. 13:10:53 INFO - Running pre-action listener: _resource_record_pre_action 13:10:53 INFO - Running main action method: download_and_extract 13:10:53 INFO - mkdir: /builds/slave/test/build/tests 13:10:53 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')]} 13:10:53 INFO - trying https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.test_packages.json 13:10:53 INFO - Downloading https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:10:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:10:53 INFO - Downloaded 986 bytes. 13:10:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:10:53 INFO - Using the following test package requirements: 13:10:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:10:53 INFO - u'common': [u'target.common.tests.zip'], 13:10:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:10:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:10:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:10:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:10:53 INFO - u'mozbase': [u'target.common.tests.zip'], 13:10:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:10:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:10:53 INFO - u'web-platform': [u'target.common.tests.zip', 13:10:53 INFO - u'target.web-platform.tests.zip'], 13:10:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:10:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:10:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.common.tests.zip 13:10:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:10:53 INFO - Fetch https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.common.tests.zip into memory 13:10:54 INFO - Expected file size: 59712864 13:10:54 INFO - Obtained file size: 59712864 13:11:04 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:11:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.common.tests.zip 13:11:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:11:04 INFO - Fetch https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.common.tests.zip into memory 13:11:05 INFO - Expected file size: 59712864 13:11:05 INFO - Obtained file size: 59712864 13:11:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.talos.tests.zip 13:11:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:11:16 INFO - Fetch https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.talos.tests.zip into memory 13:11:17 INFO - Expected file size: 14404461 13:11:17 INFO - Obtained file size: 14404461 13:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:17 INFO - trying https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2 13:11:17 INFO - Downloading https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:11:18 INFO - Downloaded 59738551 bytes. 13:11:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2 13:11:18 INFO - mkdir: /builds/slave/test/properties 13:11:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:11:18 INFO - Writing to file /builds/slave/test/properties/build_url 13:11:18 INFO - Contents: 13:11:18 INFO - build_url:https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2 13:11:18 INFO - Running post-action listener: _resource_record_post_action 13:11:18 INFO - Running post-action listener: set_extra_try_arguments 13:11:18 INFO - [mozharness: 2017-06-01 20:11:18.379019Z] Finished download-and-extract step (success) 13:11:18 INFO - [mozharness: 2017-06-01 20:11:18.379115Z] Running populate-webroot step. 13:11:18 INFO - Running pre-action listener: _resource_record_pre_action 13:11:18 INFO - Running main action method: populate_webroot 13:11:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:11:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:11:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:11:18 INFO - '--tppagecycles', 13:11:18 INFO - '7'], 13:11:18 INFO - 'tests': ['tsvgm']}}, 13:11:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:11:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:11:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:11:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:11:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:11:18 INFO - 'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:11:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:11:18 INFO - 'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['damp', 'tps']}, 13:11:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:11:18 INFO - 'tests': ['damp', 'tps']}, 13:11:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['dromaeo_dom']}, 13:11:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:11:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['basic_compositor_video']}, 13:11:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:11:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['a11yr', 13:11:18 INFO - 'ts_paint', 13:11:18 INFO - 'tpaint', 13:11:18 INFO - 'sessionrestore', 13:11:18 INFO - 'sessionrestore_no_auto_restore', 13:11:18 INFO - 'tabpaint']}, 13:11:18 INFO - 'other-e10s': {'tests': ['a11yr', 13:11:18 INFO - 'ts_paint', 13:11:18 INFO - 'tpaint', 13:11:18 INFO - 'sessionrestore', 13:11:18 INFO - 'sessionrestore_no_auto_restore', 13:11:18 INFO - 'tabpaint']}, 13:11:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:11:18 INFO - 'ts_paint', 13:11:18 INFO - 'tpaint', 13:11:18 INFO - 'sessionrestore', 13:11:18 INFO - 'sessionrestore_no_auto_restore', 13:11:18 INFO - 'tabpaint']}, 13:11:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:11:18 INFO - 'ts_paint', 13:11:18 INFO - 'tpaint', 13:11:18 INFO - 'sessionrestore', 13:11:18 INFO - 'sessionrestore_no_auto_restore', 13:11:18 INFO - 'tabpaint']}, 13:11:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['a11yr', 13:11:18 INFO - 'ts_paint', 13:11:18 INFO - 'tpaint', 13:11:18 INFO - 'sessionrestore', 13:11:18 INFO - 'sessionrestore_no_auto_restore', 13:11:18 INFO - 'tabpaint']}, 13:11:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['a11yr', 13:11:18 INFO - 'ts_paint', 13:11:18 INFO - 'tpaint', 13:11:18 INFO - 'sessionrestore', 13:11:18 INFO - 'sessionrestore_no_auto_restore', 13:11:18 INFO - 'tabpaint']}, 13:11:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['tsvgx', 13:11:18 INFO - 'tsvgr_opacity', 13:11:18 INFO - 'tart', 13:11:18 INFO - 'tscrollx', 13:11:18 INFO - 'cart', 13:11:18 INFO - 'tsvg_static']}, 13:11:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:11:18 INFO - 'tsvgr_opacity', 13:11:18 INFO - 'tart', 13:11:18 INFO - 'tscrollx', 13:11:18 INFO - 'cart', 13:11:18 INFO - 'tsvg_static']}, 13:11:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:11:18 INFO - 'talos_options': ['--disable-e10s'], 13:11:18 INFO - 'tests': ['tp5o']}, 13:11:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:11:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:11:18 INFO - 'talos_options': ['--disable-e10s', 13:11:18 INFO - '--xperf_path', 13:11:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:18 INFO - 'tests': ['tp5n']}, 13:11:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:11:18 INFO - 'talos_options': ['--xperf_path', 13:11:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:18 INFO - 'tests': ['tp5n']}}, 13:11:18 INFO - 'talos.zip': {'path': '', 13:11:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:11:18 INFO - Running post-action listener: _resource_record_post_action 13:11:18 INFO - [mozharness: 2017-06-01 20:11:18.392231Z] Finished populate-webroot step (success) 13:11:18 INFO - [mozharness: 2017-06-01 20:11:18.392321Z] Running create-virtualenv step. 13:11:18 INFO - Running pre-action listener: _resource_record_pre_action 13:11:18 INFO - Running main action method: create_virtualenv 13:11:18 INFO - Creating virtualenv /builds/slave/test/build/venv 13:11:18 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 13:11:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:11:18 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:11:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:11:18 INFO - Using real prefix '/usr' 13:11:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:11:19 INFO - Installing distribute.............................................................................................................................................................................................done. 13:11:20 INFO - Installing pip.................done. 13:11:20 INFO - Return code: 0 13:11:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:11:21 INFO - Reading from file tmpfile_stdout 13:11:21 INFO - Output received: 13:11:21 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) 13:11:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:11:21 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')]} 13:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:21 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')]} 13:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:21 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:21 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 13:11:21 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 13:11:21 INFO - Using env: {'DISPLAY': ':0', 13:11:21 INFO - 'HOME': '/home/cltbld', 13:11:21 INFO - 'LANG': 'en_US.UTF-8', 13:11:21 INFO - 'LANGUAGE': 'en_US:en', 13:11:21 INFO - 'LOGNAME': 'cltbld', 13:11:21 INFO - 'MAIL': '/var/mail/cltbld', 13:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:21 INFO - 'MOZ_NO_REMOTE': '1', 13:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:21 INFO - 'NO_EM_RESTART': '1', 13:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:21 INFO - 'PWD': '/builds/slave/test', 13:11:21 INFO - 'SHELL': '/bin/bash', 13:11:21 INFO - 'SHLVL': '1', 13:11:21 INFO - 'TERM': 'linux', 13:11:21 INFO - 'TMOUT': '86400', 13:11:21 INFO - 'USER': 'cltbld', 13:11:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:21 INFO - '_': '/tools/buildbot/bin/python'} 13:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:21 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 13:11:21 INFO - Cleaning up... 13:11:21 INFO - Return code: 0 13:11:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:11:21 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')]} 13:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:21 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')]} 13:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:21 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:21 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 13:11:21 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 13:11:21 INFO - Using env: {'DISPLAY': ':0', 13:11:21 INFO - 'HOME': '/home/cltbld', 13:11:21 INFO - 'LANG': 'en_US.UTF-8', 13:11:21 INFO - 'LANGUAGE': 'en_US:en', 13:11:21 INFO - 'LOGNAME': 'cltbld', 13:11:21 INFO - 'MAIL': '/var/mail/cltbld', 13:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:21 INFO - 'MOZ_NO_REMOTE': '1', 13:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:21 INFO - 'NO_EM_RESTART': '1', 13:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:21 INFO - 'PWD': '/builds/slave/test', 13:11:21 INFO - 'SHELL': '/bin/bash', 13:11:21 INFO - 'SHLVL': '1', 13:11:21 INFO - 'TERM': 'linux', 13:11:21 INFO - 'TMOUT': '86400', 13:11:21 INFO - 'USER': 'cltbld', 13:11:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:21 INFO - '_': '/tools/buildbot/bin/python'} 13:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:21 INFO - Downloading/unpacking psutil>=3.1.1 13:11:21 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 13:11:21 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 13:11:21 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 13:11:21 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 13:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:11:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:22 INFO - Installing collected packages: psutil 13:11:22 INFO - Running setup.py install for psutil 13:11:22 INFO - building 'psutil._psutil_linux' extension 13:11:22 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 13:11:23 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 13:11:23 INFO - building 'psutil._psutil_posix' extension 13:11:23 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 13:11:23 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 13:11:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:23 INFO - Successfully installed psutil 13:11:23 INFO - Cleaning up... 13:11:23 INFO - Return code: 0 13:11:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:23 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:23 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 13:11:23 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 13:11:23 INFO - Using env: {'DISPLAY': ':0', 13:11:23 INFO - 'HOME': '/home/cltbld', 13:11:23 INFO - 'LANG': 'en_US.UTF-8', 13:11:23 INFO - 'LANGUAGE': 'en_US:en', 13:11:23 INFO - 'LOGNAME': 'cltbld', 13:11:23 INFO - 'MAIL': '/var/mail/cltbld', 13:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:23 INFO - 'MOZ_NO_REMOTE': '1', 13:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:23 INFO - 'NO_EM_RESTART': '1', 13:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:23 INFO - 'PWD': '/builds/slave/test', 13:11:23 INFO - 'SHELL': '/bin/bash', 13:11:23 INFO - 'SHLVL': '1', 13:11:23 INFO - 'TERM': 'linux', 13:11:23 INFO - 'TMOUT': '86400', 13:11:23 INFO - 'USER': 'cltbld', 13:11:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:23 INFO - '_': '/tools/buildbot/bin/python'} 13:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:11:23 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 13:11:23 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 13:11:23 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 13:11:23 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 13:11:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:11:24 INFO - Installing collected packages: mozsystemmonitor 13:11:24 INFO - Running setup.py install for mozsystemmonitor 13:11:24 INFO - Successfully installed mozsystemmonitor 13:11:24 INFO - Cleaning up... 13:11:24 INFO - Return code: 0 13:11:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:24 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:24 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 13:11:24 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 13:11:24 INFO - Using env: {'DISPLAY': ':0', 13:11:24 INFO - 'HOME': '/home/cltbld', 13:11:24 INFO - 'LANG': 'en_US.UTF-8', 13:11:24 INFO - 'LANGUAGE': 'en_US:en', 13:11:24 INFO - 'LOGNAME': 'cltbld', 13:11:24 INFO - 'MAIL': '/var/mail/cltbld', 13:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:24 INFO - 'MOZ_NO_REMOTE': '1', 13:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:24 INFO - 'NO_EM_RESTART': '1', 13:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:24 INFO - 'PWD': '/builds/slave/test', 13:11:24 INFO - 'SHELL': '/bin/bash', 13:11:24 INFO - 'SHLVL': '1', 13:11:24 INFO - 'TERM': 'linux', 13:11:24 INFO - 'TMOUT': '86400', 13:11:24 INFO - 'USER': 'cltbld', 13:11:24 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:24 INFO - '_': '/tools/buildbot/bin/python'} 13:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:25 INFO - Downloading/unpacking jsonschema==2.5.1 13:11: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 13:11: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 13:11: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 13:11: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 13:11:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:11:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:11:26 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 13:11:26 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 13:11:26 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 13:11:26 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 13:11:26 INFO - Downloading functools32-3.2.3-2.tar.gz 13:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:11:26 INFO - warning: no files found matching '*.txt' 13:11:26 INFO - no previously-included directories found matching 'build' 13:11:26 INFO - no previously-included directories found matching 'dist' 13:11:26 INFO - no previously-included directories found matching '.git*' 13:11:26 INFO - Installing collected packages: jsonschema, functools32 13:11:26 INFO - Running setup.py install for functools32 13:11:26 INFO - warning: no files found matching '*.txt' 13:11:26 INFO - no previously-included directories found matching 'build' 13:11:26 INFO - no previously-included directories found matching 'dist' 13:11:26 INFO - no previously-included directories found matching '.git*' 13:11:26 INFO - Successfully installed jsonschema functools32 13:11:26 INFO - Cleaning up... 13:11:26 INFO - Return code: 0 13:11:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:26 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:26 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 13:11:26 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 13:11:26 INFO - Using env: {'DISPLAY': ':0', 13:11:26 INFO - 'HOME': '/home/cltbld', 13:11:26 INFO - 'LANG': 'en_US.UTF-8', 13:11:26 INFO - 'LANGUAGE': 'en_US:en', 13:11:26 INFO - 'LOGNAME': 'cltbld', 13:11:26 INFO - 'MAIL': '/var/mail/cltbld', 13:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:26 INFO - 'MOZ_NO_REMOTE': '1', 13:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:26 INFO - 'NO_EM_RESTART': '1', 13:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:26 INFO - 'PWD': '/builds/slave/test', 13:11:26 INFO - 'SHELL': '/bin/bash', 13:11:26 INFO - 'SHLVL': '1', 13:11:26 INFO - 'TERM': 'linux', 13:11:26 INFO - 'TMOUT': '86400', 13:11:26 INFO - 'USER': 'cltbld', 13:11:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:26 INFO - '_': '/tools/buildbot/bin/python'} 13:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:11:26 INFO - Cleaning up... 13:11:26 INFO - Return code: 0 13:11:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:26 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:26 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 13:11:26 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 13:11:26 INFO - Using env: {'DISPLAY': ':0', 13:11:26 INFO - 'HOME': '/home/cltbld', 13:11:26 INFO - 'LANG': 'en_US.UTF-8', 13:11:26 INFO - 'LANGUAGE': 'en_US:en', 13:11:26 INFO - 'LOGNAME': 'cltbld', 13:11:26 INFO - 'MAIL': '/var/mail/cltbld', 13:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:26 INFO - 'MOZ_NO_REMOTE': '1', 13:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:26 INFO - 'NO_EM_RESTART': '1', 13:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:26 INFO - 'PWD': '/builds/slave/test', 13:11:26 INFO - 'SHELL': '/bin/bash', 13:11:26 INFO - 'SHLVL': '1', 13:11:26 INFO - 'TERM': 'linux', 13:11:26 INFO - 'TMOUT': '86400', 13:11:26 INFO - 'USER': 'cltbld', 13:11:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:26 INFO - '_': '/tools/buildbot/bin/python'} 13:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:26 INFO - Downloading/unpacking blobuploader==1.2.4 13:11:26 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 13:11:26 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 13:11:26 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 13:11:26 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 13:11:28 INFO - Downloading blobuploader-1.2.4.tar.gz 13:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:11:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:11: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 13:11: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 13:11: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 13:11: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 13:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:11:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:11: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 13:11: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 13:11: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 13:11: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 13:11:28 INFO - Downloading docopt-0.6.1.tar.gz 13:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:11:28 INFO - Installing collected packages: blobuploader, requests, docopt 13:11:28 INFO - Running setup.py install for blobuploader 13:11:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:11:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:11:28 INFO - Running setup.py install for requests 13:11:29 INFO - Running setup.py install for docopt 13:11:29 INFO - Successfully installed blobuploader requests docopt 13:11:29 INFO - Cleaning up... 13:11:29 INFO - Return code: 0 13:11:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:29 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')]} 13:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:29 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')]} 13:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:29 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:29 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 13:11:29 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 13:11:29 INFO - Using env: {'DISPLAY': ':0', 13:11:29 INFO - 'HOME': '/home/cltbld', 13:11:29 INFO - 'LANG': 'en_US.UTF-8', 13:11:29 INFO - 'LANGUAGE': 'en_US:en', 13:11:29 INFO - 'LOGNAME': 'cltbld', 13:11:29 INFO - 'MAIL': '/var/mail/cltbld', 13:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:29 INFO - 'MOZ_NO_REMOTE': '1', 13:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:29 INFO - 'NO_EM_RESTART': '1', 13:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:29 INFO - 'PWD': '/builds/slave/test', 13:11:29 INFO - 'SHELL': '/bin/bash', 13:11:29 INFO - 'SHLVL': '1', 13:11:29 INFO - 'TERM': 'linux', 13:11:29 INFO - 'TMOUT': '86400', 13:11:29 INFO - 'USER': 'cltbld', 13:11:29 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:29 INFO - '_': '/tools/buildbot/bin/python'} 13:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:29 INFO - Running setup.py (path:/tmp/pip-Askz1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:29 INFO - Running setup.py (path:/tmp/pip-kfup6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:29 INFO - Running setup.py (path:/tmp/pip-GXrcdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:29 INFO - Running setup.py (path:/tmp/pip-e99K6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:29 INFO - Running setup.py (path:/tmp/pip-J8_X2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:29 INFO - Running setup.py (path:/tmp/pip-mFHmGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:29 INFO - Running setup.py (path:/tmp/pip-IkrPn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:30 INFO - Running setup.py (path:/tmp/pip-WpdNqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:30 INFO - Running setup.py (path:/tmp/pip-BYv11C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:30 INFO - Running setup.py (path:/tmp/pip-dUQcld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:30 INFO - Running setup.py (path:/tmp/pip-xJwtkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:30 INFO - Running setup.py (path:/tmp/pip-HB23s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:30 INFO - Running setup.py (path:/tmp/pip-jz91Im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:30 INFO - Running setup.py (path:/tmp/pip-wU8gP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:30 INFO - Running setup.py (path:/tmp/pip-Fdw5Zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:30 INFO - Running setup.py (path:/tmp/pip-kbah18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:30 INFO - Running setup.py (path:/tmp/pip-nYhbfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:11:30 INFO - Running setup.py install for manifestparser 13:11:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:11:30 INFO - Running setup.py install for mozcrash 13:11:30 INFO - Running setup.py install for mozdebug 13:11:31 INFO - Running setup.py install for mozdevice 13:11:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Running setup.py install for mozfile 13:11:31 INFO - Running setup.py install for mozhttpd 13:11:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Running setup.py install for mozinfo 13:11:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Running setup.py install for mozInstall 13:11:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Running setup.py install for mozleak 13:11:31 INFO - Running setup.py install for mozlog 13:11:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Running setup.py install for moznetwork 13:11:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:11:31 INFO - Running setup.py install for mozprocess 13:11:31 INFO - Running setup.py install for mozprofile 13:11:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:11:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:11:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:11:32 INFO - Running setup.py install for mozrunner 13:11:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:11:32 INFO - Running setup.py install for mozscreenshot 13:11:32 INFO - Running setup.py install for moztest 13:11:32 INFO - Running setup.py install for mozversion 13:11:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:11:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:11:32 INFO - Cleaning up... 13:11:32 INFO - Return code: 0 13:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:32 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:32 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 13:11:32 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 13:11:32 INFO - Using env: {'DISPLAY': ':0', 13:11:32 INFO - 'HOME': '/home/cltbld', 13:11:32 INFO - 'LANG': 'en_US.UTF-8', 13:11:32 INFO - 'LANGUAGE': 'en_US:en', 13:11:32 INFO - 'LOGNAME': 'cltbld', 13:11:32 INFO - 'MAIL': '/var/mail/cltbld', 13:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:32 INFO - 'MOZ_NO_REMOTE': '1', 13:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:32 INFO - 'NO_EM_RESTART': '1', 13:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:32 INFO - 'PWD': '/builds/slave/test', 13:11:32 INFO - 'SHELL': '/bin/bash', 13:11:32 INFO - 'SHLVL': '1', 13:11:32 INFO - 'TERM': 'linux', 13:11:32 INFO - 'TMOUT': '86400', 13:11:32 INFO - 'USER': 'cltbld', 13:11:32 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:32 INFO - '_': '/tools/buildbot/bin/python'} 13:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:32 INFO - Running setup.py (path:/tmp/pip-DAVKnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:32 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)) 13:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:32 INFO - Running setup.py (path:/tmp/pip-TCT3b2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:32 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)) 13:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:32 INFO - Running setup.py (path:/tmp/pip-nj4VzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:32 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)) 13:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:32 INFO - Running setup.py (path:/tmp/pip-HbLGMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:33 INFO - Running setup.py (path:/tmp/pip-jZD9lU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:33 INFO - Running setup.py (path:/tmp/pip-FhcZe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:33 INFO - Running setup.py (path:/tmp/pip-h6bISp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:33 INFO - Running setup.py (path:/tmp/pip-rwl6y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:33 INFO - Running setup.py (path:/tmp/pip-9mbs0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:33 INFO - Running setup.py (path:/tmp/pip-kWcvYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:33 INFO - Running setup.py (path:/tmp/pip-_KFtyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:33 INFO - Running setup.py (path:/tmp/pip-m4cdgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:33 INFO - Running setup.py (path:/tmp/pip-XKjvwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:33 INFO - Running setup.py (path:/tmp/pip-tiVbFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:33 INFO - Running setup.py (path:/tmp/pip-QmIb35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:33 INFO - Running setup.py (path:/tmp/pip-odrOy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:33 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)) 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:33 INFO - Running setup.py (path:/tmp/pip-5DYN_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:33 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)) 13:11:33 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)) 13:11:33 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)) 13:11:33 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)) 13:11:33 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)) 13:11:33 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)) 13:11:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:35 INFO - Downloading blessings-1.6.tar.gz 13:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:11:35 INFO - Installing collected packages: blessings 13:11:35 INFO - Running setup.py install for blessings 13:11:35 INFO - Successfully installed blessings 13:11:35 INFO - Cleaning up... 13:11:35 INFO - Return code: 0 13:11:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:35 INFO - Reading from file tmpfile_stdout 13:11:35 INFO - Current package versions: 13:11:35 INFO - argparse == 1.2.1 13:11:35 INFO - blessings == 1.6 13:11:35 INFO - blobuploader == 1.2.4 13:11:35 INFO - docopt == 0.6.1 13:11:35 INFO - functools32 == 3.2.3-2 13:11:35 INFO - jsonschema == 2.5.1 13:11:35 INFO - manifestparser == 1.1 13:11:35 INFO - mozInstall == 1.12 13:11:35 INFO - mozcrash == 1.0 13:11:35 INFO - mozdebug == 0.1 13:11:35 INFO - mozdevice == 0.49 13:11:35 INFO - mozfile == 1.2 13:11:35 INFO - mozhttpd == 0.7 13:11:35 INFO - mozinfo == 0.9 13:11:35 INFO - mozleak == 0.1 13:11:35 INFO - mozlog == 3.4 13:11:35 INFO - moznetwork == 0.27 13:11:35 INFO - mozprocess == 0.25 13:11:35 INFO - mozprofile == 0.28 13:11:35 INFO - mozrunner == 6.13 13:11:35 INFO - mozscreenshot == 0.1 13:11:35 INFO - mozsystemmonitor == 0.3 13:11:35 INFO - moztest == 0.8 13:11:35 INFO - mozversion == 1.4 13:11:35 INFO - psutil == 3.1.1 13:11:35 INFO - requests == 1.2.3 13:11:35 INFO - wsgiref == 0.1.2 13:11:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:35 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 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:35 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 13:11:35 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 13:11:35 INFO - Using env: {'DISPLAY': ':0', 13:11:35 INFO - 'HOME': '/home/cltbld', 13:11:35 INFO - 'LANG': 'en_US.UTF-8', 13:11:35 INFO - 'LANGUAGE': 'en_US:en', 13:11:35 INFO - 'LOGNAME': 'cltbld', 13:11:35 INFO - 'MAIL': '/var/mail/cltbld', 13:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:35 INFO - 'MOZ_NO_REMOTE': '1', 13:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:35 INFO - 'NO_EM_RESTART': '1', 13:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:35 INFO - 'PWD': '/builds/slave/test', 13:11:35 INFO - 'SHELL': '/bin/bash', 13:11:35 INFO - 'SHLVL': '1', 13:11:35 INFO - 'TERM': 'linux', 13:11:35 INFO - 'TMOUT': '86400', 13:11:35 INFO - 'USER': 'cltbld', 13:11:35 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:35 INFO - '_': '/tools/buildbot/bin/python'} 13:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 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)) 13:11:35 INFO - Cleaning up... 13:11:35 INFO - Return code: 0 13:11:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2150310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', '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 13:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:11:35 INFO - Using env: {'DISPLAY': ':0', 13:11:35 INFO - 'HOME': '/home/cltbld', 13:11:35 INFO - 'LANG': 'en_US.UTF-8', 13:11:35 INFO - 'LANGUAGE': 'en_US:en', 13:11:35 INFO - 'LOGNAME': 'cltbld', 13:11:35 INFO - 'MAIL': '/var/mail/cltbld', 13:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:35 INFO - 'MOZ_NO_REMOTE': '1', 13:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:35 INFO - 'NO_EM_RESTART': '1', 13:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:35 INFO - 'PWD': '/builds/slave/test', 13:11:35 INFO - 'SHELL': '/bin/bash', 13:11:35 INFO - 'SHLVL': '1', 13:11:35 INFO - 'TERM': 'linux', 13:11:35 INFO - 'TMOUT': '86400', 13:11:35 INFO - 'USER': 'cltbld', 13:11:35 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:35 INFO - '_': '/tools/buildbot/bin/python'} 13:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:11:35 INFO - Cleaning up... 13:11:35 INFO - Return code: 0 13:11:35 INFO - Running post-action listener: _resource_record_post_action 13:11:35 INFO - Running post-action listener: _start_resource_monitoring 13:11:36 INFO - Starting resource monitoring. 13:11:36 INFO - [mozharness: 2017-06-01 20:11:36.016394Z] Finished create-virtualenv step (success) 13:11:36 INFO - [mozharness: 2017-06-01 20:11:36.016903Z] Running install step. 13:11:36 INFO - Running pre-action listener: _resource_record_pre_action 13:11:36 INFO - Running main action method: install 13:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:36 INFO - Reading from file tmpfile_stdout 13:11:36 INFO - Detecting whether we're running mozinstall >=1.0... 13:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:11:36 INFO - Reading from file tmpfile_stdout 13:11:36 INFO - Output received: 13:11:36 INFO - Usage: mozinstall [options] installer 13:11:36 INFO - Options: 13:11:36 INFO - -h, --help show this help message and exit 13:11:36 INFO - -d DEST, --destination=DEST 13:11:36 INFO - Directory to install application into. [default: 13:11:36 INFO - "/builds/slave/test"] 13:11:36 INFO - --app=APP Application being installed. [default: firefox] 13:11:36 INFO - mkdir: /builds/slave/test/build/application 13:11:36 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'] 13:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:11:50 INFO - Reading from file tmpfile_stdout 13:11:50 INFO - Output received: 13:11:50 INFO - /builds/slave/test/build/application/firefox/firefox 13:11:50 INFO - Running post-action listener: _resource_record_post_action 13:11:50 INFO - [mozharness: 2017-06-01 20:11:50.409414Z] Finished install step (success) 13:11:50 INFO - [mozharness: 2017-06-01 20:11:50.409657Z] Running run-tests step. 13:11:50 INFO - Running pre-action listener: _resource_record_pre_action 13:11:50 INFO - Running main action method: run_tests 13:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:11:50 INFO - Python 2.7.3 13:11:50 INFO - Return code: 0 13:11:50 INFO - grabbing minidump binary from tooltool 13:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:50 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 0x214d400>, '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 0x214e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214e840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:11:50 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 13:11:50 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 13:11:50 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 13:11:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:11:50 INFO - Return code: 0 13:11:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:11:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:11:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:11:50 INFO - ENV: RUST_BACKTRACE is now 1 13:11:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:11:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:11:50 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/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 13:11:50 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/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 13:11:50 INFO - Using env: {'DISPLAY': ':0', 13:11:50 INFO - 'HOME': '/home/cltbld', 13:11:50 INFO - 'LANG': 'en_US.UTF-8', 13:11:50 INFO - 'LANGUAGE': 'en_US:en', 13:11:50 INFO - 'LOGNAME': 'cltbld', 13:11:50 INFO - 'MAIL': '/var/mail/cltbld', 13:11:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:11:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:50 INFO - 'MOZ_NO_REMOTE': '1', 13:11:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:11:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:50 INFO - 'NO_EM_RESTART': '1', 13:11:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:50 INFO - 'PWD': '/builds/slave/test', 13:11:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:11:50 INFO - 'RUST_BACKTRACE': '1', 13:11:50 INFO - 'SHELL': '/bin/bash', 13:11:50 INFO - 'SHLVL': '1', 13:11:50 INFO - 'TERM': 'linux', 13:11:50 INFO - 'TMOUT': '86400', 13:11:50 INFO - 'USER': 'cltbld', 13:11:50 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212', 13:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:50 INFO - '_': '/tools/buildbot/bin/python'} 13:11:50 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/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 13:11:50 INFO - mozversion application_buildid: 20170601191702 13:11:50 INFO - mozversion application_changeset: a85ff8f8399de4eea50c9f3aece921814c98d016 13:11:50 INFO - mozversion application_display_name: Firefox 13:11:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:11:50 INFO - mozversion application_name: Firefox 13:11:50 INFO - mozversion application_remotingname: firefox 13:11:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:11:50 INFO - mozversion application_vendor: Mozilla 13:11:50 INFO - mozversion application_version: 54.0 13:11:50 INFO - mozversion platform_buildid: 20170601191702 13:11:50 INFO - mozversion platform_changeset: a85ff8f8399de4eea50c9f3aece921814c98d016 13:11:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:11:50 INFO - mozversion platform_version: 54.0 13:11:50 INFO - using testdate: 1496347910 13:11:50 INFO - actual date: 1496347910 13:11:50 INFO - starting webserver on 'localhost:34115' 13:11:50 INFO - SUITE-START | Running 2 tests 13:11:50 INFO - TEST-START | dromaeo_css 13:11:50 INFO - operating with platform_type : linux_ 13:11:50 INFO - Initialising browser for dromaeo_css test... 13:11:50 INFO - TEST-INFO | started process 12054 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8a33Of/profile http://localhost:34115/getInfo.html) 13:11:54 INFO - PROCESS | 12054 | __metrics Screen width/height:1600/1200 13:11:54 INFO - PROCESS | 12054 | colorDepth:24 13:11:54 INFO - PROCESS | 12054 | Browser inner width/height: 1024/697 13:11:54 INFO - PROCESS | 12054 | __metrics 13:11:55 INFO - PROCESS | 12054 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:11:55 INFO - PROCESS | 12054 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:11:56 INFO - TEST-INFO | 12054: exit 0 13:11:56 INFO - Browser initialized. 13:11:56 INFO - Running cycle 1/1 for dromaeo_css test... 13:11:56 INFO - TEST-INFO | started process 12146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8a33Of/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:12:08 INFO - PROCESS | 12146 | RSS: Main: 141869056 13:12:08 INFO - PROCESS | 12146 | 13:15:09 INFO - PROCESS | 12146 | Cycle 1(1): loaded http://localhost:34115/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34115/tests/dromaeo/cssquery-ext.html) 13:15:11 INFO - PROCESS | 12146 | RSS: Main: 273240064 13:15:11 INFO - PROCESS | 12146 | 13:18:13 INFO - PROCESS | 12146 | Cycle 1(1): loaded http://localhost:34115/tests/dromaeo/cssquery-ext.html (next: http://localhost:34115/tests/dromaeo/cssquery-jquery.html) 13:18:14 INFO - PROCESS | 12146 | RSS: Main: 223559680 13:18:14 INFO - PROCESS | 12146 | 13:21:15 INFO - PROCESS | 12146 | Cycle 1(1): loaded http://localhost:34115/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34115/tests/dromaeo/cssquery-mootools.html) 13:21:16 INFO - PROCESS | 12146 | RSS: Main: 206405632 13:21:16 INFO - PROCESS | 12146 | 13:24:18 INFO - PROCESS | 12146 | Cycle 1(1): loaded http://localhost:34115/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34115/tests/dromaeo/cssquery-prototype.html) 13:24:19 INFO - PROCESS | 12146 | RSS: Main: 171937792 13:24:19 INFO - PROCESS | 12146 | 13:27:21 INFO - PROCESS | 12146 | Cycle 1(1): loaded http://localhost:34115/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34115/tests/dromaeo/cssquery-yui.html) 13:27:22 INFO - PROCESS | 12146 | RSS: Main: 176754688 13:27:22 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | Cycle 1(1): loaded http://localhost:34115/tests/dromaeo/cssquery-yui.html (next: http://localhost:34115/tests/dromaeo/cssquery-dojo.html) 13:30:24 INFO - PROCESS | 12146 | RSS: Main: 267386880 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | __start_tp_report 13:30:24 INFO - PROCESS | 12146 | _x_x_mozilla_page_load 13:30:24 INFO - PROCESS | 12146 | _x_x_mozilla_page_load_details 13:30:24 INFO - PROCESS | 12146 | |i|pagename|runs| 13:30:24 INFO - PROCESS | 12146 | |0;dojo.html;3346.7657263991514;3358.328334333133;3397.3714862750385;3398.4732366483204;3411.8805841795543;5549.250851135095;5641.182028605855;5651.8587035324135;5663.575231857607;5683.971580142099;5774.249347584808;5847.26909136358;5852.122181672751;5856.414358564141;5870.647761134324;5159.742012899349;5176;5206.921896171561;5211.635185536995;5239.790408383679;4993.02635985981;5037.773300201499;5293.258943747878;5295.099833028395;5330.840074797762;5106.157484015133;5133.153029750106;5161.561267292284;5165.38015438148;5210.505002024802;5243.842684719464;5244.292020577231;5251.133562962106;5253;5282.128448805943;4936.086823937583;5103.821366252163;5158.458361871987;5214.947850521522;5221.791128354861;5542.196381524696;5582.91625625616;5673.6879471629045;5674.460926212003;5687.7156142192725;6159.23009623797;6191.0713392990965;6197.225346831646;6209.130721698965;6209.534284928648;6221.377862213788;6230.158928544633;6249.781257656005;6251.093591429218;6261.624302541862;5456.127019676833;5504.697241651707;5679.403662615448;5738.713064346766;5741.799037033684;6345;6347.873042539123;6349.746010159634;6351.079093531412;6351.110844481776;6036;6080.543959202989;6080.635161890213;6093.573449858556;6097.786577469856;5846.7076646167525;5867.413258674099;6083.209182806296;6126.724297406628;6193.566450348432;6132.906245166512;6155.507559395238;6163.568550201532;6165.10605962133;6169.598976066542;5868.413158684098;5890.911636325459;5966.642001479925;5980.132880732356;6171.814845554641;4637.373954516164;4660.207764680013;4667.043256132485;4705.835295764632;4806.44725856524;4221.746695198298;4225.809838557272;4230.788460576959;4248.72383295091;4270.44484217056;5544.1960915667805;5582.469665381782;5744.368119506851;5758.280214973129;5853.0635098384055;6200.751969921243;6216.937830621727;6233.532485063638;6235.251769787564;6237.719302631393;5858.326292476419;5866.73599688015;5875.3243377012195;5883.205767221456;5910.172575839386;4974.90050198994;5008.57427118699;5200.739963001834;5245.0558899398475;5295;6491.480681545465;6499.38255865692;6499.512536559682;6500.674966251669;6501.772437964743;6204.968975155095;6218.720157593009;6252;6253.687315634109;6254.6247225166635;6118.832384214075;6145.907811382922;6378.202724659418;6457;6532.804015879531;6106.2061931948565;6111.834843226386;6112.290716697329;6135.9079613806725;6140.263168419639;6196.752129914933;6244.063390491463;6247.68761561911;6250;6251.874962500634;5716.171155182392;5810.82567522975;6038.758449661965;6040.969795150996;6041.124037014696;5944.346121926671;5951.642901425928;5993.490553302844;6034.879302414015;6050.4554590085345;6015.097735339734;6019.398060193946;6021.277446706424;6026.789062382795;6027.698615069141;5799.130130480293;5824.796132135355;5841.065429531254;5988.520918326611;6044.486218671286;5958.076498142622;5975.641461512323;5991.970040149946;5996.010658241341;6003.459688628044 13:30:24 INFO - PROCESS | 12146 | |1;ext.html;729.9379552738017;802.1657476224726;813.0730966697965;891.4294851295173;905.3255324783038;41959.37060944085;43287.3506897396;43385.34921976173;43386.13227735443;43455;40486.7975660122;40820;44381.556184438145;44388.55611443885;44440;7745.3029227369525;7910.802229944268;9150;9255.629774809;9290.814183716322;10559.894401056006;10839.945800270987;11202;11203.327800331965;11209.943950280238;10919.890801091968;10968.396738179397;10972.725681857977;10985.725356866062;10986.615468458605;10929.56281748729;10931.070858976997;10947.452627368639;10964.616238431696;10966.725831854188;10397.168226541859;10457.58169673212;10484.161267098614;10507.31702439339;10508.054275115277;42180.578194217975;42291.788541057096;42314;42329;42333.36499952503;4240.23675738367;4290.635295999812;4307.978460107711;4420.40324556184;4446.333050042487;30392.813053113718;30843.84578077118;31012.68987310143;31536.211594710087;32345.51481727776;4348.456442944632;4440.267355886275;4463.352813841975;4504.795989984915;4544.500104988449;246666;247131;247496;247779.76110119515;248265;52871;53288.20067698987;53324.20013699798;53413.198802018;53421;55527.72236138793;55649;55954.44045559574;55954.44045559574;56460.153097703565;15965.600859978362;16156.596085097732;16258.430954916521;16271.267792949346;16274.593135171479;11462.94268528652;11631.243969141979;12538.185017973803;13993.790093148611;14147;12701.809472857914;12719.491220351267;12796.808047879289;12894.161879477804;13061.281629510468;5190.506901844319;5282.845485009921;5283.947160528345;5318.9202161967605;5417.349918009864;1013.3160116921049;1017.0795430135739;1019.0777346501428;1020.6325722739808;1024.2215915903819;27220.59169112465;27396.17811465659;27531.44937101287;27623.447531049667;27868.721312787016;1073.2809017957986;1086.1419478611808;1087.255230167338;1118.3513562133942;1121.3328069798456;14729.631759205891;16911.069891155857;17553;17694.203760830795;17731.202095905715;301653;303260;303409;304904;305357;58447.70776146092;58723.70638146782;58846;58943;59025.70487147537;1470.43388295507;1477.3868844429442;1521.1025494957807;1534.6316883947773;1546.404634215835;27732.029378971638;27803.860980694968;28108.85945570259;28265.57601635936;28436.289092772848;37435.62564374322;37725.62274377221;37799.81100094482;37816.62183378131;37847.62152378441;300748;301141;301886;302653;303335;29331.560026600044;29421.705782941895;30047.69952300449;30428.84785576058;30617.693823061483;40764.59235407608;40817.79591102026;40849.18301633891;40926.59073409228;41061.38407923943;31309.686903130678;31543.21141971469;31831.84084079565;40861;41637.58362416337;36851.8157409222;39135.80432097821;39578.406323904586;40740.592594074864;41225.38161927633;46891.76554117208;47083.764581176874;47800.52199477961;48480.75759621179;48640.02719945511;45643.77178114088;46221.76889115533;46402;46671.29993050039;46675.53324466712;30646;30923.01803884113;31058.689413106484;31093.222669433446;31446.213844654067 13:30:24 INFO - PROCESS | 12146 | |2;jquery.html;456.82412271275547;472.2420515073307;480.9086273608015;484.30260424987995;499.50548956533027;20033.799662003377;21652;22297.44256393591;22375.328740137782;22570.210042648512;22589.983450744716;22957.770422295773;23082;23163.652545211793;23341.649875251842;6552.082708420801;6648.601083934967;6728.125343705311;6757.493188010894;6762.120924279837;6667.366600172976;6774.220964589062;7233.095863017123;7515;7555.471117021811;7332;7681.23187681233;7684.039495063117;7699.191584883562;7732.265434783687;7472.551646901176;7721.72973945912;7760.029996250469;7769.417293702995;7773.300402963704;7351.77944661661;7436.1448433430305;7490.101187857437;7512.098548174199;7517.135529414078;24742.628860567107;24768.504629907482;25274.368140796443;25928.222153335242;26690.33274168142;3367.6800703933086;3512.332656795201;3526.0655926179584;3546.4325707886874;3597.37046016946;16309.673806523802;16345.373326095736;16680.33278668852;16852.74720879188;17037.48887533376;2219.3120132758895;2287.748347681754;2315.976840231593;2347.2371479269136;2349.9060037598474;108633;112464.43767781109;114918;115520;117463.41268293603;17388;17563.473095807145;18057.187426565837;18341.16588341155;18625.7206141908;32139.51790723141;32350.35299294001;32432.83783581067;32478.188045299055;32821.835890820395;10182.745431364276;10322.380657160595;10334.638287660046;10438.739031524272;10467.895321046692;8603;8608.483490990435;8699.130086991378;8857.64569417216;8859.911400886038;9175.58709858058;9314.673986410444;9430.952845235866;9504.572294246775;9550.474723890253;3641.1235124252075;3681.9447708284397;3710.962890371116;3982.3628219484744;4019.537753158424;961.711486554031;963.0946909904667;970.1403522722816;978.6770365779275;991.4200192887098;20944.79055209459;21061;21228.469288267916;21251.574968500543;21565.24521641735;964.7732782796037;982.3320142303303;990.5245482168599;991.1822746234385;996.5615129343066;10703.83944240837;11696.70758231034;11838.408079595985;11867.940660296643;11873.584424545099;649253;652299;654104;672566;675768;86554;112675.436622818;117431;117564;124066;1316.697159653285;1336.692560711042;1376.4700590272816;1380.2063813307188;1385.916844989324;17702.734458982868;18007.09964501796;18161.27354905789;18604.34884779026;18915.71626425577;46442.99999999865;50168.49831501638;51366.74316628393;52171.47828521818;54846;68058;68897.31102688909;69300.6534967322;70179.64910175417;70984.29015709984;12546.096039229018;12824.551140710031;12983.675408114495;13130.803037954629;13349.731775481314;14534.85465145335;14654.63363415923;14953.25233738287;15013.174275414956;15231;12655.303958282015;12710.792474714888;12719.364031798557;12749.426275817796;12966.147447561521;12034.819477707666;12138.817917731418;12273.324967126893;12467.251964882136;12483.812742808683;11831.526738930346;11843.23019003762;11858.762824743284;13079.084464087422;13166.539171128965;12910.806337905127;12927;12987.740245195233;13121.803172952605;13304.733905322033;12755.170913890568;12765.808512872129;12836.87163128394;12931.706566160676;13134.211947283193 13:30:24 INFO - PROCESS | 12146 | |3;mootools.html;1405.149884319987;1532.900361476504;1565.960850978726;1573.6616627425105;1591.8567328940396;9573.425594464328;11161.665150045492;11606.071514278849;11836.053115750741;11914.52341906325;11578.363190024545;11683.182177247596;11693.473793679284;11709.121815863802;11728;2052.80498352657;2121.4802373418534;2410.710714714237;2447.571674956884;2449.4243852694603;2380.238323736405;2437.878106094697;2438.1953955194745;2439.5730747119273;2450.6691596634405;2384.856908585482;2448.571499987504;2457.213691618683;2457.508498300338;2460.987695061522;2435.6346548017764;2450.1057114153296;2450.620153876152;2456.0421435640114;2456.385903524128;2289.255991802671;2355.364051706047;2393.3418309964727;2398.8920498577604;2400.7719266669637;11557.422128893522;11559.364234967074;11612.941935290353;11633.534658613646;11640.126990475663;5577.8047768327915;5704.3154821421695;5714.571407144439;5714.857128571777;5722.62802917809;3251.869925202989;3311.2218628622254;3461.7403694723;3461.7749846260162;3473.8263086845554;1415.4267521653746;1421.644588852787;1461.4300422835104;1462.5393001204693;1520.0727556190716;16608.418705345375;16751.246193921183;16776;16797.41209057677;16852.241649125695;4642.582167604932;4703.506131856174;4742.95257047432;4743.810247590127;4754.191787396151;13542.729145417037;13576.049676522549;13589.388477518536;13594.796078058836;13625.523106691218;5650.039493286151;5904.9075920954765;5918.23063001807;5948.2862056553495;5954.017587098167;4379.255526560492;4455.1980643484485;5235.528802407725;5374.838754837361;5409.107497262908;3986.963389518746;4047.170330082326;4067.898302542401;4098.569650186747;4113.753174809521;1016.7407311135613;1017.3133135133755;1024.994875025635;1038.0605551975475;1041.5052849896238;979.6816034788723;993.973662673911;998.5756053677303;1007.7883644434604;1011.1910471622673;7128;7222.711091556279;7247.13034435871;7256.528325658815;7277.9636101820215;1009.1876039788041;1025.2823023883311;1035.5133087448887;1054.6888667843004;1056.4242487844144;4425.557444255549;4555.840545580889;4615.26155815068;4626.953730462719;4823.27650852375;14025.438982440588;14326.49857254991;14921.925390373195;14959;14976.775348369783;14819.925900370428;14825.481108161379;14856.554303370916;14880.107193568421;14889.627759306104;10874.945625271981;11101.888981110085;11103.944480277547;11111.333320000664;11118.22172447937;7356.926430735838;7572.886406704014;7621.085469743666;7751.108622508484;7823.76528704117;12627.747445050863;13046.608601741962;13435.731285374042;13470.865291346961;13634.93182534081;12759.61721148367;13064.21614703121;13291.734165316833;13434.462621495031;13484.730305394034;9303.581338839904;9555.952220238854;9821.607135714492;10867.565297388015;11209.887901120885;9521.238300936047;9806.803863922823;10958.726031849268;11298.4915678793;11426.542938282708;9095.090490951117;9141.862872057058;9360.297977651568;9492.667756628485;9609.9039009609;8921.197092261727;9060.275177985613;9364.297677674065;9384;9584.952075239858;8675.652973880975;8696.82606347857;8721;8760.693375731818;8846.292296616384;6165.32181460048;6206.937930620636;6217.813465596039;6256.874862502816;6263;6351.936480635134;6399.7440102395385;6506.3818937200895;6577.850543329011;6594 13:30:24 INFO - PROCESS | 12146 | |4;prototype.html;424.78760619690155;446.62260389970476;447.45634054623633;447.6016345452548;453.21819860740226;5162.38051433828;5189.662671926322;5191;5197.298364720767;5230.163173892178;4963.255511673251;5107;5137.743112844361;5141.665791723535;5193.039967218678;4284.721493102954;4304.913901721964;4316.417283666696;4317.827286908519;4330.805113769872;4313.374560688699;4332.176886391576;4338.158506178458;4342.500612429563;4349.26062569364;4314.719543229696;4337.696361254713;4344.348347747848;4347.260965635849;4353.804078816445;4095.508538975327;4291.592298731615;4324.502682191557;4338.522762496123;4343.6959412841115;4072.8392408163827;4074.7147699661036;4078.5309689385804;4111.835526578933;4120.896977575555;5093;5112.539871411591;5124.155304090525;5136.357955255593;5149.661088117079;5009.824656137054;5049.343585333883;5057.317262169597;5064.493550644943;5075.857931965315;5372.597055220835;5423.620346575742;5428.891422171534;5434.212039254326;5443.346798384219;4528.637708983273;4537.434585068147;4553.317002449659;4569.771511424448;4642.883927901796;5500;5593.860153496154;5599.496045355938;5632.9436705632825;5670.546356291446;4677.742724150136;4688.671792974459;4692.906141877143;4702.083093796745;4709.693869898446;4799.992001679617;4800.039992001613;4810.278458231223;4820;4837.830675926326;4861.124997500413;5022.522860328263;5025.271335656309;5036.212144688656;5037.395512538519;5426.457354264542;5435.994341046919;5438.498290786203;5445.891082178334;5490.286262785812;1573.9921300393426;1639.7622344759943;1643.145564306554;1646.2345009570606;1659.5021493551885;1238.7089034076985;1251.8059700746398;1268.346801397281;1276.6872116331372;1293.2822283632486;5248.947510524922;5273.604479664041;5275.57795376369;5292.655977361459;5300;5535.723213839292;5564.970480461127;5592.720363981784;5613.663180209201;5624.3813180550105;5023.246513023076;5286.920696189561;5296.305182341691;5305.230741542455;5321.787128514816;2938.7502062324943;2946.690597487233;2954.527275635888;2955.1725516855313;2956.9502826496714;5731.475142787239;5736.770529178786;5779.035128056481;5804.796832110856;5865.88268234633;5399.83800485986;5483.835484935458;5499.092649712753;5617.831465056055;5673.77304907799;5596.86007849807;5690.780595567732;5766.567507436959;5843;5856.209411729362;5500.532454741392;5513.133170751267;5590.86022849432;5619.185218143428;5640.238567793295;5361.785528578813;5448.891022179455;5495.642783219078;5505.284313039279;5515.255440515479;5747.885042299215;5777.6822274775295;5782.942170578409;5790.94209057904;5815.331236907809;4448.6218671412;4621.838235661736;4650.837220697259;4669.925917039168;4674.368960190467;5535.612507124462;5544.445555444585;5558.277423934863;5579.860503487445;5655.4910058094965;5405.216243644728;5414.566834653139;5428.824137879385;5466.398696143341;5477.643953143033;5307.735742518401;5421.647592906448;5439;5442.265294185234;5476.342838859362;5403.4326395727885;5410.891782164414;5417.83746487606;5419.512243898068;5446.863828404322;5452.791619394152;5452.809151679672;5481.643693159932;5486.670799752028;5529.695866727368;5202.73986300691;5246.737663116753;5254.553362964038;5281.366236051545;5310.920336194883 13:30:24 INFO - PROCESS | 12146 | |5;yui.html;899.3299991506328;899.4101658194323;904.5341649050736;909.715664413802;928.9721308360747;15732.29204685789;15835;15930.12384318862;15978;17448.47654570362;16988.405405810798;17223.655526889455;17350.132493375342;17386.304547818072;17577.472675819714;856.6787454704486;860.6901515454431;881.8985816631073;889.8976617688952;895.7939673875013;882.7704796752827;885.4377470306338;885.5572213893054;885.716570697377;886.2334081019903;936.9693337328918;937.610891480035;938.9389689670184;939.685205456173;939.9342046056779;922.4096578189964;924.1036194890945;929.3355250995537;929.6606738540452;930.144267274106;683.197243239192;683.4771399879096;684.3669605614787;684.9657517124174;685.5852209413283;18164.091795410175;18218.27126914922;18235.908820455945;18926;19250.22999080035;16376;16437.424690135907;16505;16709.331626734915;17295.135243237914;13258;13447.66380840477;13493.865061349457;14090.788638170436;14113;3963.4451176835264;4048.8785336439955;4099.959000409987;4115.279826030433;4181.268278051329;66101;77260.61369693115;92178;93038.53480732553;96267.0373296265;12756.61730148097;12758.681032974064;12867.099303048884;12971.416286266953;12990.480380784851;25121.874390628294;25198.874005629856;25297;25417.237482875542;25702;8848.292136629054;8999.865002024975;9023.684171054112;9029.367944243972;9056.773580660536;4883.609311255091;4982.626303027288;4994.425641051253;5011.0980023595375;5185.2999845021195;3809.847606095725;3902.453656488094;3902.5316961964177;3907.0623050467684;3908.179282350738;637.3594537489793;640.8013515810114;644.3942693867749;657.2376043789181;659.1891972873393;786.3041208530492;799.9640016199285;800.3837045475068;819.3338934766566;820.4953953318785;10854.403007834644;10914.236003479838;10964.287321324247;10982.890171098346;11088.445577721082;679.599036568427;685.7805502239336;703.5145749432875;719.7624783821431;735.8307589254501;5166.896662066738;5210.739463026909;5251.448597897166;5287.41838397776;5348;85549;87025.12974870297;87137.56431217803;88468.5576572113;88929;60598.18205453843;62231;71433.6428317855;73357.6332118336;73827;60120.699396502736;60380.39619603748;61083.38916610777;61085;61404;11531.423428828357;12036.699082523008;12048.397580121135;12136.150469467053;12302.138850280395;38189;39126;39483.60516394799;42313.730588082406;42614.36078458764;55318.44681553294;55639.16541251965;55748.72125639346;57585.13622295585;59134.40865591289;14386.784198236826;14393.424263029781;14552.708945820812;14668.046576972463;14799.92600037036;16553.751693724844;16882.746758798872;16938.745918810982;16983.150842458075;17235.741463877803;14805.555833325017;14831.703365932837;15034;15514.767278490606;15590.532284031497;15615.141167235457;16052.518424447286;16073.437429689904;16917.154142293082;16940.068296243826;15052.322645480761;15130.697386052438;15452.613684657974;15649.68700626004;15682.843171568595;10715.464226788474;11036.503357348778;11182.217244793112;11371.43142842838;11645.301281923112;11135.109191264517;11368.658940231806;11397.658070257905;11403.657890263305;11492.425378731197 13:30:24 INFO - PROCESS | 12146 | __end_tp_report 13:30:24 INFO - PROCESS | 12146 | __start_cc_report 13:30:24 INFO - PROCESS | 12146 | _x_x_mozilla_cycle_collect,1369 13:30:24 INFO - PROCESS | 12146 | __end_cc_report 13:30:24 INFO - PROCESS | 12146 | __startTimestamp1496349024914__endTimestamp 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | ------- Summary: start ------- 13:30:24 INFO - PROCESS | 12146 | Number of tests: 6 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | [#0] dojo.html Cycles:165 Average:5702.87 Median:5890.91 stddev:660.75 (11.2%) stddev-sans-first:636.40 13:30:24 INFO - PROCESS | 12146 | Values: 3346.8 3358.3 3397.4 3398.5 3411.9 5549.3 5641.2 5651.9 5663.6 5684.0 5774.2 5847.3 5852.1 5856.4 5870.6 5159.7 5176.0 5206.9 5211.6 5239.8 4993.0 5037.8 5293.3 5295.1 5330.8 5106.2 5133.2 5161.6 5165.4 5210.5 5243.8 5244.3 5251.1 5253.0 5282.1 4936.1 5103.8 5158.5 5214.9 5221.8 5542.2 5582.9 5673.7 5674.5 5687.7 6159.2 6191.1 6197.2 6209.1 6209.5 6221.4 6230.2 6249.8 6251.1 6261.6 5456.1 5504.7 5679.4 5738.7 5741.8 6345.0 6347.9 6349.7 6351.1 6351.1 6036.0 6080.5 6080.6 6093.6 6097.8 5846.7 5867.4 6083.2 6126.7 6193.6 6132.9 6155.5 6163.6 6165.1 6169.6 5868.4 5890.9 5966.6 5980.1 6171.8 4637.4 4660.2 4667.0 4705.8 4806.4 4221.7 4225.8 4230.8 4248.7 4270.4 5544.2 5582.5 5744.4 5758.3 5853.1 6200.8 6216.9 6233.5 6235.3 6237.7 5858.3 5866.7 5875.3 5883.2 5910.2 4974.9 5008.6 5200.7 5245.1 5295.0 6491.5 6499.4 6499.5 6500.7 6501.8 6205.0 6218.7 6252.0 6253.7 6254.6 6118.8 6145.9 6378.2 6457.0 6532.8 6106.2 6111.8 6112.3 6135.9 6140.3 6196.8 6244.1 6247.7 6250.0 6251.9 5716.2 5810.8 6038.8 6041.0 6041.1 5944.3 5951.6 5993.5 6034.9 6050.5 6015.1 6019.4 6021.3 6026.8 6027.7 5799.1 5824.8 5841.1 5988.5 6044.5 5958.1 5975.6 5992.0 5996.0 6003.5 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | [#1] ext.html Cycles:180 Average:46505.20 Median:31075.96 stddev:74361.22 (239.3%) stddev-sans-first:74490.40 13:30:24 INFO - PROCESS | 12146 | Values: 729.9 802.2 813.1 891.4 905.3 41959.4 43287.4 43385.3 43386.1 43455.0 40486.8 40820.0 44381.6 44388.6 44440.0 7745.3 7910.8 9150.0 9255.6 9290.8 10559.9 10839.9 11202.0 11203.3 11209.9 10919.9 10968.4 10972.7 10985.7 10986.6 10929.6 10931.1 10947.5 10964.6 10966.7 10397.2 10457.6 10484.2 10507.3 10508.1 42180.6 42291.8 42314.0 42329.0 42333.4 4240.2 4290.6 4308.0 4420.4 4446.3 30392.8 30843.8 31012.7 31536.2 32345.5 4348.5 4440.3 4463.4 4504.8 4544.5 246666.0 247131.0 247496.0 247779.8 248265.0 52871.0 53288.2 53324.2 53413.2 53421.0 55527.7 55649.0 55954.4 55954.4 56460.2 15965.6 16156.6 16258.4 16271.3 16274.6 11462.9 11631.2 12538.2 13993.8 14147.0 12701.8 12719.5 12796.8 12894.2 13061.3 5190.5 5282.8 5283.9 5318.9 5417.3 1013.3 1017.1 1019.1 1020.6 1024.2 27220.6 27396.2 27531.4 27623.4 27868.7 1073.3 1086.1 1087.3 1118.4 1121.3 14729.6 16911.1 17553.0 17694.2 17731.2 301653.0 303260.0 303409.0 304904.0 305357.0 58447.7 58723.7 58846.0 58943.0 59025.7 1470.4 1477.4 1521.1 1534.6 1546.4 27732.0 27803.9 28108.9 28265.6 28436.3 37435.6 37725.6 37799.8 37816.6 37847.6 300748.0 301141.0 301886.0 302653.0 303335.0 29331.6 29421.7 30047.7 30428.8 30617.7 40764.6 40817.8 40849.2 40926.6 41061.4 31309.7 31543.2 31831.8 40861.0 41637.6 36851.8 39135.8 39578.4 40740.6 41225.4 46891.8 47083.8 47800.5 48480.8 48640.0 45643.8 46221.8 46402.0 46671.3 46675.5 30646.0 30923.0 31058.7 31093.2 31446.2 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | [#2] jquery.html Cycles:180 Average:37913.60 Median:23252.65 stddev:108836.14 (468.1%) stddev-sans-first:109105.12 13:30:24 INFO - PROCESS | 12146 | Values: 456.8 472.2 480.9 484.3 499.5 20033.8 21652.0 22297.4 22375.3 22570.2 22590.0 22957.8 23082.0 23163.7 23341.6 6552.1 6648.6 6728.1 6757.5 6762.1 6667.4 6774.2 7233.1 7515.0 7555.5 7332.0 7681.2 7684.0 7699.2 7732.3 7472.6 7721.7 7760.0 7769.4 7773.3 7351.8 7436.1 7490.1 7512.1 7517.1 24742.6 24768.5 25274.4 25928.2 26690.3 3367.7 3512.3 3526.1 3546.4 3597.4 16309.7 16345.4 16680.3 16852.7 17037.5 2219.3 2287.7 2316.0 2347.2 2349.9 108633.0 112464.4 114918.0 115520.0 117463.4 17388.0 17563.5 18057.2 18341.2 18625.7 32139.5 32350.4 32432.8 32478.2 32821.8 10182.7 10322.4 10334.6 10438.7 10467.9 8603.0 8608.5 8699.1 8857.6 8859.9 9175.6 9314.7 9431.0 9504.6 9550.5 3641.1 3681.9 3711.0 3982.4 4019.5 961.7 963.1 970.1 978.7 991.4 20944.8 21061.0 21228.5 21251.6 21565.2 964.8 982.3 990.5 991.2 996.6 10703.8 11696.7 11838.4 11867.9 11873.6 649253.0 652299.0 654104.0 672566.0 675768.0 86554.0 112675.4 117431.0 117564.0 124066.0 1316.7 1336.7 1376.5 1380.2 1385.9 17702.7 18007.1 18161.3 18604.3 18915.7 46443.0 50168.5 51366.7 52171.5 54846.0 68058.0 68897.3 69300.7 70179.6 70984.3 12546.1 12824.6 12983.7 13130.8 13349.7 14534.9 14654.6 14953.3 15013.2 15231.0 12655.3 12710.8 12719.4 12749.4 12966.1 12034.8 12138.8 12273.3 12467.3 12483.8 11831.5 11843.2 11858.8 13079.1 13166.5 12910.8 12927.0 12987.7 13121.8 13304.7 12755.2 12765.8 12836.9 12931.7 13134.2 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | [#3] mootools.html Cycles:180 Average:7190.47 Median:2448.07 stddev:4626.53 (189.0%) stddev-sans-first:4619.09 13:30:24 INFO - PROCESS | 12146 | Values: 1405.1 1532.9 1566.0 1573.7 1591.9 9573.4 11161.7 11606.1 11836.1 11914.5 11578.4 11683.2 11693.5 11709.1 11728.0 2052.8 2121.5 2410.7 2447.6 2449.4 2380.2 2437.9 2438.2 2439.6 2450.7 2384.9 2448.6 2457.2 2457.5 2461.0 2435.6 2450.1 2450.6 2456.0 2456.4 2289.3 2355.4 2393.3 2398.9 2400.8 11557.4 11559.4 11612.9 11633.5 11640.1 5577.8 5704.3 5714.6 5714.9 5722.6 3251.9 3311.2 3461.7 3461.8 3473.8 1415.4 1421.6 1461.4 1462.5 1520.1 16608.4 16751.2 16776.0 16797.4 16852.2 4642.6 4703.5 4743.0 4743.8 4754.2 13542.7 13576.0 13589.4 13594.8 13625.5 5650.0 5904.9 5918.2 5948.3 5954.0 4379.3 4455.2 5235.5 5374.8 5409.1 3987.0 4047.2 4067.9 4098.6 4113.8 1016.7 1017.3 1025.0 1038.1 1041.5 979.7 994.0 998.6 1007.8 1011.2 7128.0 7222.7 7247.1 7256.5 7278.0 1009.2 1025.3 1035.5 1054.7 1056.4 4425.6 4555.8 4615.3 4627.0 4823.3 14025.4 14326.5 14921.9 14959.0 14976.8 14819.9 14825.5 14856.6 14880.1 14889.6 10874.9 11101.9 11103.9 11111.3 11118.2 7356.9 7572.9 7621.1 7751.1 7823.8 12627.7 13046.6 13435.7 13470.9 13634.9 12759.6 13064.2 13291.7 13434.5 13484.7 9303.6 9556.0 9821.6 10867.6 11209.9 9521.2 9806.8 10958.7 11298.5 11426.5 9095.1 9141.9 9360.3 9492.7 9609.9 8921.2 9060.3 9364.3 9384.0 9585.0 8675.7 8696.8 8721.0 8760.7 8846.3 6165.3 6206.9 6217.8 6256.9 6263.0 6351.9 6399.7 6506.4 6577.9 6594.0 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | [#4] prototype.html Cycles:180 Average:4746.36 Median:5216.45 stddev:1253.56 (24.0%) stddev-sans-first:1214.38 13:30:24 INFO - PROCESS | 12146 | Values: 424.8 446.6 447.5 447.6 453.2 5162.4 5189.7 5191.0 5197.3 5230.2 4963.3 5107.0 5137.7 5141.7 5193.0 4284.7 4304.9 4316.4 4317.8 4330.8 4313.4 4332.2 4338.2 4342.5 4349.3 4314.7 4337.7 4344.3 4347.3 4353.8 4095.5 4291.6 4324.5 4338.5 4343.7 4072.8 4074.7 4078.5 4111.8 4120.9 5093.0 5112.5 5124.2 5136.4 5149.7 5009.8 5049.3 5057.3 5064.5 5075.9 5372.6 5423.6 5428.9 5434.2 5443.3 4528.6 4537.4 4553.3 4569.8 4642.9 5500.0 5593.9 5599.5 5632.9 5670.5 4677.7 4688.7 4692.9 4702.1 4709.7 4800.0 4800.0 4810.3 4820.0 4837.8 4861.1 5022.5 5025.3 5036.2 5037.4 5426.5 5436.0 5438.5 5445.9 5490.3 1574.0 1639.8 1643.1 1646.2 1659.5 1238.7 1251.8 1268.3 1276.7 1293.3 5248.9 5273.6 5275.6 5292.7 5300.0 5535.7 5565.0 5592.7 5613.7 5624.4 5023.2 5286.9 5296.3 5305.2 5321.8 2938.8 2946.7 2954.5 2955.2 2957.0 5731.5 5736.8 5779.0 5804.8 5865.9 5399.8 5483.8 5499.1 5617.8 5673.8 5596.9 5690.8 5766.6 5843.0 5856.2 5500.5 5513.1 5590.9 5619.2 5640.2 5361.8 5448.9 5495.6 5505.3 5515.3 5747.9 5777.7 5782.9 5790.9 5815.3 4448.6 4621.8 4650.8 4669.9 4674.4 5535.6 5544.4 5558.3 5579.9 5655.5 5405.2 5414.6 5428.8 5466.4 5477.6 5307.7 5421.6 5439.0 5442.3 5476.3 5403.4 5410.9 5417.8 5419.5 5446.9 5452.8 5452.8 5481.6 5486.7 5529.7 5202.7 5246.7 5254.6 5281.4 5310.9 13:30:24 INFO - PROCESS | 12146 | 13:30:24 INFO - PROCESS | 12146 | [#5] yui.html Cycles:180 Average:18822.43 Median:4107.62 stddev:23388.98 (569.4%) stddev-sans-first:23415.87 13:30:24 INFO - PROCESS | 12146 | Values: 899.3 899.4 904.5 909.7 929.0 15732.3 15835.0 15930.1 15978.0 17448.5 16988.4 17223.7 17350.1 17386.3 17577.5 856.7 860.7 881.9 889.9 895.8 882.8 885.4 885.6 885.7 886.2 937.0 937.6 938.9 939.7 939.9 922.4 924.1 929.3 929.7 930.1 683.2 683.5 684.4 685.0 685.6 18164.1 18218.3 18235.9 18926.0 19250.2 16376.0 16437.4 16505.0 16709.3 17295.1 13258.0 13447.7 13493.9 14090.8 14113.0 3963.4 4048.9 4100.0 4115.3 4181.3 66101.0 77260.6 92178.0 93038.5 96267.0 12756.6 12758.7 12867.1 12971.4 12990.5 25121.9 25198.9 25297.0 25417.2 25702.0 8848.3 8999.9 9023.7 9029.4 9056.8 4883.6 4982.6 4994.4 5011.1 5185.3 3809.8 3902.5 3902.5 3907.1 3908.2 637.4 640.8 644.4 657.2 659.2 786.3 800.0 800.4 819.3 820.5 10854.4 10914.2 10964.3 10982.9 11088.4 679.6 685.8 703.5 719.8 735.8 5166.9 5210.7 5251.4 5287.4 5348.0 85549.0 87025.1 87137.6 88468.6 88929.0 60598.2 62231.0 71433.6 73357.6 73827.0 60120.7 60380.4 61083.4 61085.0 61404.0 11531.4 12036.7 12048.4 12136.2 12302.1 38189.0 39126.0 39483.6 42313.7 42614.4 55318.4 55639.2 55748.7 57585.1 59134.4 14386.8 14393.4 14552.7 14668.0 14799.9 16553.8 16882.7 16938.7 16983.2 17235.7 14805.6 14831.7 15034.0 15514.8 15590.5 15615.1 16052.5 16073.4 16917.2 16940.1 15052.3 15130.7 15452.6 15649.7 15682.8 10715.5 11036.5 11182.2 11371.4 11645.3 11135.1 11368.7 11397.7 11403.7 11492.4 13:30:24 INFO - PROCESS | 12146 | -------- Summary: end -------- 13:30:24 INFO - PROCESS | 12146 | 13:30:25 INFO - TEST-INFO | 12146: exit 0 13:30:25 INFO - TEST-OK | dromaeo_css | took 1114940ms 13:30:25 INFO - TEST-START | kraken 13:30:25 INFO - operating with platform_type : linux_ 13:30:25 INFO - Initialising browser for kraken test... 13:30:25 INFO - TEST-INFO | started process 12455 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFtwTbt/profile http://localhost:34115/getInfo.html) 13:30:29 INFO - PROCESS | 12455 | __metrics Screen width/height:1600/1200 13:30:29 INFO - PROCESS | 12455 | colorDepth:24 13:30:29 INFO - PROCESS | 12455 | Browser inner width/height: 1024/697 13:30:29 INFO - PROCESS | 12455 | __metrics 13:30:30 INFO - PROCESS | 12455 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:30:30 INFO - PROCESS | 12455 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:30:31 INFO - TEST-INFO | 12455: exit 0 13:30:31 INFO - Browser initialized. 13:30:31 INFO - Running cycle 1/1 for kraken test... 13:30:31 INFO - TEST-INFO | started process 12547 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFtwTbt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:30:42 INFO - PROCESS | 12547 | RSS: Main: 141484032 13:30:42 INFO - PROCESS | 12547 | 13:30:45 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34115/tests/kraken/driver.html?testName=audio-beat-detection) 13:30:46 INFO - PROCESS | 12547 | RSS: Main: 184078336 13:30:46 INFO - PROCESS | 12547 | 13:30:48 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34115/tests/kraken/driver.html?testName=audio-dft) 13:30:49 INFO - PROCESS | 12547 | RSS: Main: 173465600 13:30:49 INFO - PROCESS | 12547 | 13:30:52 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34115/tests/kraken/driver.html?testName=audio-fft) 13:30:52 INFO - PROCESS | 12547 | RSS: Main: 265445376 13:30:52 INFO - PROCESS | 12547 | 13:30:54 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34115/tests/kraken/driver.html?testName=audio-oscillator) 13:30:55 INFO - PROCESS | 12547 | RSS: Main: 171491328 13:30:55 INFO - PROCESS | 12547 | 13:30:57 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34115/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:30:57 INFO - PROCESS | 12547 | RSS: Main: 174211072 13:30:57 INFO - PROCESS | 12547 | 13:31:00 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34115/tests/kraken/driver.html?testName=imaging-darkroom) 13:31:01 INFO - PROCESS | 12547 | RSS: Main: 209489920 13:31:01 INFO - PROCESS | 12547 | 13:31:04 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34115/tests/kraken/driver.html?testName=imaging-desaturate) 13:31:05 INFO - PROCESS | 12547 | RSS: Main: 211222528 13:31:05 INFO - PROCESS | 12547 | 13:31:08 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34115/tests/kraken/driver.html?testName=json-parse-financial) 13:31:08 INFO - PROCESS | 12547 | RSS: Main: 211787776 13:31:08 INFO - PROCESS | 12547 | 13:31:10 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34115/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:31:10 INFO - PROCESS | 12547 | RSS: Main: 173498368 13:31:10 INFO - PROCESS | 12547 | 13:31:12 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:31:13 INFO - PROCESS | 12547 | RSS: Main: 180256768 13:31:13 INFO - PROCESS | 12547 | 13:31:15 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:31:15 INFO - PROCESS | 12547 | RSS: Main: 185221120 13:31:15 INFO - PROCESS | 12547 | 13:31:18 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:31:18 INFO - PROCESS | 12547 | RSS: Main: 180981760 13:31:18 INFO - PROCESS | 12547 | 13:31:21 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:31:22 INFO - PROCESS | 12547 | RSS: Main: 184590336 13:31:22 INFO - PROCESS | 12547 | 13:31:23 INFO - PROCESS | 12547 | Cycle 1(1): loaded http://localhost:34115/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34115/tests/kraken/driver.html?testName=ai-astar) 13:31:24 INFO - PROCESS | 12547 | RSS: Main: 238313472 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | __start_tp_report 13:31:24 INFO - PROCESS | 12547 | _x_x_mozilla_page_load 13:31:24 INFO - PROCESS | 12547 | _x_x_mozilla_page_load_details 13:31:24 INFO - PROCESS | 12547 | |i|pagename|runs| 13:31:24 INFO - PROCESS | 12547 | |0;ai-astar;103;113;112;112;112;113;112;111;112;112 13:31:24 INFO - PROCESS | 12547 | |1;audio-beat-detection;150;116;118;157;117;117;116;114;135;115 13:31:24 INFO - PROCESS | 12547 | |2;audio-dft;161;161;159;159;160;159;160;160;159;163 13:31:24 INFO - PROCESS | 12547 | |3;audio-fft;74;76;74;102;76;75;76;77;89;75 13:31:24 INFO - PROCESS | 12547 | |4;audio-oscillator;82;82;102;82;83;82;97;82;82;103 13:31:24 INFO - PROCESS | 12547 | |5;imaging-gaussian-blur;113;113;113;112;112;112;113;113;112;113 13:31:24 INFO - PROCESS | 12547 | |6;imaging-darkroom;146;146;147;146;146;145;148;146;147;145 13:31:24 INFO - PROCESS | 12547 | |7;imaging-desaturate;94;94;93;94;93;93;94;93;94;93 13:31:24 INFO - PROCESS | 12547 | |8;json-parse-financial;57;55;55;53;54;53;54;54;53;56 13:31:24 INFO - PROCESS | 12547 | |9;json-stringify-tinderbox;53;54;54;54;54;53;54;54;54;53 13:31:24 INFO - PROCESS | 12547 | |10;stanford-crypto-aes;85;83;75;76;75;80;84;80;78;96 13:31:24 INFO - PROCESS | 12547 | |11;stanford-crypto-ccm;124;127;122;133;121;121;118;131;120;117 13:31:24 INFO - PROCESS | 12547 | |12;stanford-crypto-pbkdf2;156;160;160;160;158;164;171;157;163;155 13:31:24 INFO - PROCESS | 12547 | |13;stanford-crypto-sha256-iterative;62;59;59;60;59;62;59;60;59;57 13:31:24 INFO - PROCESS | 12547 | __end_tp_report 13:31:24 INFO - PROCESS | 12547 | __start_cc_report 13:31:24 INFO - PROCESS | 12547 | _x_x_mozilla_cycle_collect,1608 13:31:24 INFO - PROCESS | 12547 | __end_cc_report 13:31:24 INFO - PROCESS | 12547 | __startTimestamp1496349084130__endTimestamp 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | ------- Summary: start ------- 13:31:24 INFO - PROCESS | 12547 | Number of tests: 14 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.94 (2.6%) stddev-sans-first:0.60 13:31:24 INFO - PROCESS | 12547 | Values: 103.0 113.0 112.0 112.0 112.0 113.0 112.0 111.0 112.0 112.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:117.50 stddev:16.01 (13.6%) stddev-sans-first:14.32 13:31:24 INFO - PROCESS | 12547 | Values: 150.0 116.0 118.0 157.0 117.0 117.0 116.0 114.0 135.0 115.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.29 (0.8%) stddev-sans-first:1.32 13:31:24 INFO - PROCESS | 12547 | Values: 161.0 161.0 159.0 159.0 160.0 159.0 160.0 160.0 159.0 163.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#3] audio-fft Cycles:10 Average:79.40 Median:76.00 stddev:9.07 (11.9%) stddev-sans-first:9.41 13:31:24 INFO - PROCESS | 12547 | Values: 74.0 76.0 74.0 102.0 76.0 75.0 76.0 77.0 89.0 75.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:82.00 stddev:9.08 (11.1%) stddev-sans-first:9.39 13:31:24 INFO - PROCESS | 12547 | Values: 82.0 82.0 102.0 82.0 83.0 82.0 97.0 82.0 82.0 103.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#5] imaging-gaussian-blur Cycles:10 Average:112.60 Median:113.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 13:31:24 INFO - PROCESS | 12547 | Values: 113.0 113.0 113.0 112.0 112.0 112.0 113.0 113.0 112.0 113.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 13:31:24 INFO - PROCESS | 12547 | Values: 146.0 146.0 147.0 146.0 146.0 145.0 148.0 146.0 147.0 145.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 13:31:24 INFO - PROCESS | 12547 | Values: 94.0 94.0 93.0 94.0 93.0 93.0 94.0 93.0 94.0 93.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.35 (2.5%) stddev-sans-first:1.05 13:31:24 INFO - PROCESS | 12547 | Values: 57.0 55.0 55.0 53.0 54.0 53.0 54.0 54.0 53.0 56.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 13:31:24 INFO - PROCESS | 12547 | Values: 53.0 54.0 54.0 54.0 54.0 53.0 54.0 54.0 54.0 53.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.50 stddev:6.34 (7.8%) stddev-sans-first:6.57 13:31:24 INFO - PROCESS | 12547 | Values: 85.0 83.0 75.0 76.0 75.0 80.0 84.0 80.0 78.0 96.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:123.00 stddev:5.36 (4.4%) stddev-sans-first:5.68 13:31:24 INFO - PROCESS | 12547 | Values: 124.0 127.0 122.0 133.0 121.0 121.0 118.0 131.0 120.0 117.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.40 Median:160.00 stddev:4.70 (2.9%) stddev-sans-first:4.70 13:31:24 INFO - PROCESS | 12547 | Values: 156.0 160.0 160.0 160.0 158.0 164.0 171.0 157.0 163.0 155.0 13:31:24 INFO - PROCESS | 12547 | 13:31:24 INFO - PROCESS | 12547 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:59.50 stddev:1.51 (2.5%) stddev-sans-first:1.32 13:31:24 INFO - PROCESS | 12547 | Values: 62.0 59.0 59.0 60.0 59.0 62.0 59.0 60.0 59.0 57.0 13:31:24 INFO - PROCESS | 12547 | -------- Summary: end -------- 13:31:24 INFO - PROCESS | 12547 | 13:31:25 INFO - TEST-INFO | 12547: exit 0 13:31:25 INFO - TEST-OK | kraken | took 59610ms 13:31:25 INFO - SUITE-END | took 1174s 13:31:25 INFO - Completed test suite (00:19:34) 13:31:25 INFO - javascript benchmark 13:31:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7907.963897620614, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.7657263991514, 3358.328334333133, 3397.3714862750385, 3398.4732366483204, 3411.8805841795543, 5549.250851135095, 5641.182028605855, 5651.8587035324135, 5663.575231857607, 5683.971580142099, 5774.249347584808, 5847.26909136358, 5852.122181672751, 5856.414358564141, 5870.647761134324, 5159.742012899349, 5176.0, 5206.921896171561, 5211.635185536995, 5239.790408383679, 4993.02635985981, 5037.773300201499, 5293.258943747878, 5295.099833028395, 5330.840074797762, 5106.157484015133, 5133.153029750106, 5161.561267292284, 5165.38015438148, 5210.505002024802, 5243.842684719464, 5244.292020577231, 5251.133562962106, 5253.0, 5282.128448805943, 4936.086823937583, 5103.821366252163, 5158.458361871987, 5214.947850521522, 5221.791128354861, 5542.196381524696, 5582.91625625616, 5673.6879471629045, 5674.460926212003, 5687.7156142192725, 6159.23009623797, 6191.0713392990965, 6197.225346831646, 6209.130721698965, 6209.534284928648, 6221.377862213788, 6230.158928544633, 6249.781257656005, 6251.093591429218, 6261.624302541862, 5456.127019676833, 5504.697241651707, 5679.403662615448, 5738.713064346766, 5741.799037033684, 6345.0, 6347.873042539123, 6349.746010159634, 6351.079093531412, 6351.110844481776, 6036.0, 6080.543959202989, 6080.635161890213, 6093.573449858556, 6097.786577469856, 5846.7076646167525, 5867.413258674099, 6083.209182806296, 6126.724297406628, 6193.566450348432, 6132.906245166512, 6155.507559395238, 6163.568550201532, 6165.10605962133, 6169.598976066542, 5868.413158684098, 5890.911636325459, 5966.642001479925, 5980.132880732356, 6171.814845554641, 4637.373954516164, 4660.207764680013, 4667.043256132485, 4705.835295764632, 4806.44725856524, 4221.746695198298, 4225.809838557272, 4230.788460576959, 4248.72383295091, 4270.44484217056, 5544.1960915667805, 5582.469665381782, 5744.368119506851, 5758.280214973129, 5853.0635098384055, 6200.751969921243, 6216.937830621727, 6233.532485063638, 6235.251769787564, 6237.719302631393, 5858.326292476419, 5866.73599688015, 5875.3243377012195, 5883.205767221456, 5910.172575839386, 4974.90050198994, 5008.57427118699, 5200.739963001834, 5245.0558899398475, 5295.0, 6491.480681545465, 6499.38255865692, 6499.512536559682, 6500.674966251669, 6501.772437964743, 6204.968975155095, 6218.720157593009, 6252.0, 6253.687315634109, 6254.6247225166635, 6118.832384214075, 6145.907811382922, 6378.202724659418, 6457.0, 6532.804015879531, 6106.2061931948565, 6111.834843226386, 6112.290716697329, 6135.9079613806725, 6140.263168419639, 6196.752129914933, 6244.063390491463, 6247.68761561911, 6250.0, 6251.874962500634, 5716.171155182392, 5810.82567522975, 6038.758449661965, 6040.969795150996, 6041.124037014696, 5944.346121926671, 5951.642901425928, 5993.490553302844, 6034.879302414015, 6050.4554590085345, 6015.097735339734, 6019.398060193946, 6021.277446706424, 6026.789062382795, 6027.698615069141, 5799.130130480293, 5824.796132135355, 5841.065429531254, 5988.520918326611, 6044.486218671286, 5958.076498142622, 5975.641461512323, 5991.970040149946, 5996.010658241341, 6003.459688628044], "value": 5658.575429740039, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.9379552738017, 802.1657476224726, 813.0730966697965, 891.4294851295173, 905.3255324783038, 41959.37060944085, 43287.3506897396, 43385.34921976173, 43386.13227735443, 43455.0, 40486.7975660122, 40820.0, 44381.556184438145, 44388.55611443885, 44440.0, 7745.3029227369525, 7910.802229944268, 9150.0, 9255.629774809, 9290.814183716322, 10559.894401056006, 10839.945800270987, 11202.0, 11203.327800331965, 11209.943950280238, 10919.890801091968, 10968.396738179397, 10972.725681857977, 10985.725356866062, 10986.615468458605, 10929.56281748729, 10931.070858976997, 10947.452627368639, 10964.616238431696, 10966.725831854188, 10397.168226541859, 10457.58169673212, 10484.161267098614, 10507.31702439339, 10508.054275115277, 42180.578194217975, 42291.788541057096, 42314.0, 42329.0, 42333.36499952503, 4240.23675738367, 4290.635295999812, 4307.978460107711, 4420.40324556184, 4446.333050042487, 30392.813053113718, 30843.84578077118, 31012.68987310143, 31536.211594710087, 32345.51481727776, 4348.456442944632, 4440.267355886275, 4463.352813841975, 4504.795989984915, 4544.500104988449, 246666.0, 247131.0, 247496.0, 247779.76110119515, 248265.0, 52871.0, 53288.20067698987, 53324.20013699798, 53413.198802018, 53421.0, 55527.72236138793, 55649.0, 55954.44045559574, 55954.44045559574, 56460.153097703565, 15965.600859978362, 16156.596085097732, 16258.430954916521, 16271.267792949346, 16274.593135171479, 11462.94268528652, 11631.243969141979, 12538.185017973803, 13993.790093148611, 14147.0, 12701.809472857914, 12719.491220351267, 12796.808047879289, 12894.161879477804, 13061.281629510468, 5190.506901844319, 5282.845485009921, 5283.947160528345, 5318.9202161967605, 5417.349918009864, 1013.3160116921049, 1017.0795430135739, 1019.0777346501428, 1020.6325722739808, 1024.2215915903819, 27220.59169112465, 27396.17811465659, 27531.44937101287, 27623.447531049667, 27868.721312787016, 1073.2809017957986, 1086.1419478611808, 1087.255230167338, 1118.3513562133942, 1121.3328069798456, 14729.631759205891, 16911.069891155857, 17553.0, 17694.203760830795, 17731.202095905715, 301653.0, 303260.0, 303409.0, 304904.0, 305357.0, 58447.70776146092, 58723.70638146782, 58846.0, 58943.0, 59025.70487147537, 1470.43388295507, 1477.3868844429442, 1521.1025494957807, 1534.6316883947773, 1546.404634215835, 27732.029378971638, 27803.860980694968, 28108.85945570259, 28265.57601635936, 28436.289092772848, 37435.62564374322, 37725.62274377221, 37799.81100094482, 37816.62183378131, 37847.62152378441, 300748.0, 301141.0, 301886.0, 302653.0, 303335.0, 29331.560026600044, 29421.705782941895, 30047.69952300449, 30428.84785576058, 30617.693823061483, 40764.59235407608, 40817.79591102026, 40849.18301633891, 40926.59073409228, 41061.38407923943, 31309.686903130678, 31543.21141971469, 31831.84084079565, 40861.0, 41637.58362416337, 36851.8157409222, 39135.80432097821, 39578.406323904586, 40740.592594074864, 41225.38161927633, 46891.76554117208, 47083.764581176874, 47800.52199477961, 48480.75759621179, 48640.02719945511, 45643.77178114088, 46221.76889115533, 46402.0, 46671.29993050039, 46675.53324466712, 30646.0, 30923.01803884113, 31058.689413106484, 31093.222669433446, 31446.213844654067], "value": 19463.240445146417, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.82412271275547, 472.2420515073307, 480.9086273608015, 484.30260424987995, 499.50548956533027, 20033.799662003377, 21652.0, 22297.44256393591, 22375.328740137782, 22570.210042648512, 22589.983450744716, 22957.770422295773, 23082.0, 23163.652545211793, 23341.649875251842, 6552.082708420801, 6648.601083934967, 6728.125343705311, 6757.493188010894, 6762.120924279837, 6667.366600172976, 6774.220964589062, 7233.095863017123, 7515.0, 7555.471117021811, 7332.0, 7681.23187681233, 7684.039495063117, 7699.191584883562, 7732.265434783687, 7472.551646901176, 7721.72973945912, 7760.029996250469, 7769.417293702995, 7773.300402963704, 7351.77944661661, 7436.1448433430305, 7490.101187857437, 7512.098548174199, 7517.135529414078, 24742.628860567107, 24768.504629907482, 25274.368140796443, 25928.222153335242, 26690.33274168142, 3367.6800703933086, 3512.332656795201, 3526.0655926179584, 3546.4325707886874, 3597.37046016946, 16309.673806523802, 16345.373326095736, 16680.33278668852, 16852.74720879188, 17037.48887533376, 2219.3120132758895, 2287.748347681754, 2315.976840231593, 2347.2371479269136, 2349.9060037598474, 108633.0, 112464.43767781109, 114918.0, 115520.0, 117463.41268293603, 17388.0, 17563.473095807145, 18057.187426565837, 18341.16588341155, 18625.7206141908, 32139.51790723141, 32350.35299294001, 32432.83783581067, 32478.188045299055, 32821.835890820395, 10182.745431364276, 10322.380657160595, 10334.638287660046, 10438.739031524272, 10467.895321046692, 8603.0, 8608.483490990435, 8699.130086991378, 8857.64569417216, 8859.911400886038, 9175.58709858058, 9314.673986410444, 9430.952845235866, 9504.572294246775, 9550.474723890253, 3641.1235124252075, 3681.9447708284397, 3710.962890371116, 3982.3628219484744, 4019.537753158424, 961.711486554031, 963.0946909904667, 970.1403522722816, 978.6770365779275, 991.4200192887098, 20944.79055209459, 21061.0, 21228.469288267916, 21251.574968500543, 21565.24521641735, 964.7732782796037, 982.3320142303303, 990.5245482168599, 991.1822746234385, 996.5615129343066, 10703.83944240837, 11696.70758231034, 11838.408079595985, 11867.940660296643, 11873.584424545099, 649253.0, 652299.0, 654104.0, 672566.0, 675768.0, 86554.0, 112675.436622818, 117431.0, 117564.0, 124066.0, 1316.697159653285, 1336.692560711042, 1376.4700590272816, 1380.2063813307188, 1385.916844989324, 17702.734458982868, 18007.09964501796, 18161.27354905789, 18604.34884779026, 18915.71626425577, 46442.99999999865, 50168.49831501638, 51366.74316628393, 52171.47828521818, 54846.0, 68058.0, 68897.31102688909, 69300.6534967322, 70179.64910175417, 70984.29015709984, 12546.096039229018, 12824.551140710031, 12983.675408114495, 13130.803037954629, 13349.731775481314, 14534.85465145335, 14654.63363415923, 14953.25233738287, 15013.174275414956, 15231.0, 12655.303958282015, 12710.792474714888, 12719.364031798557, 12749.426275817796, 12966.147447561521, 12034.819477707666, 12138.817917731418, 12273.324967126893, 12467.251964882136, 12483.812742808683, 11831.526738930346, 11843.23019003762, 11858.762824743284, 13079.084464087422, 13166.539171128965, 12910.806337905127, 12927.0, 12987.740245195233, 13121.803172952605, 13304.733905322033, 12755.170913890568, 12765.808512872129, 12836.87163128394, 12931.706566160676, 13134.211947283193], "value": 11774.434064241022, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.149884319987, 1532.900361476504, 1565.960850978726, 1573.6616627425105, 1591.8567328940396, 9573.425594464328, 11161.665150045492, 11606.071514278849, 11836.053115750741, 11914.52341906325, 11578.363190024545, 11683.182177247596, 11693.473793679284, 11709.121815863802, 11728.0, 2052.80498352657, 2121.4802373418534, 2410.710714714237, 2447.571674956884, 2449.4243852694603, 2380.238323736405, 2437.878106094697, 2438.1953955194745, 2439.5730747119273, 2450.6691596634405, 2384.856908585482, 2448.571499987504, 2457.213691618683, 2457.508498300338, 2460.987695061522, 2435.6346548017764, 2450.1057114153296, 2450.620153876152, 2456.0421435640114, 2456.385903524128, 2289.255991802671, 2355.364051706047, 2393.3418309964727, 2398.8920498577604, 2400.7719266669637, 11557.422128893522, 11559.364234967074, 11612.941935290353, 11633.534658613646, 11640.126990475663, 5577.8047768327915, 5704.3154821421695, 5714.571407144439, 5714.857128571777, 5722.62802917809, 3251.869925202989, 3311.2218628622254, 3461.7403694723, 3461.7749846260162, 3473.8263086845554, 1415.4267521653746, 1421.644588852787, 1461.4300422835104, 1462.5393001204693, 1520.0727556190716, 16608.418705345375, 16751.246193921183, 16776.0, 16797.41209057677, 16852.241649125695, 4642.582167604932, 4703.506131856174, 4742.95257047432, 4743.810247590127, 4754.191787396151, 13542.729145417037, 13576.049676522549, 13589.388477518536, 13594.796078058836, 13625.523106691218, 5650.039493286151, 5904.9075920954765, 5918.23063001807, 5948.2862056553495, 5954.017587098167, 4379.255526560492, 4455.1980643484485, 5235.528802407725, 5374.838754837361, 5409.107497262908, 3986.963389518746, 4047.170330082326, 4067.898302542401, 4098.569650186747, 4113.753174809521, 1016.7407311135613, 1017.3133135133755, 1024.994875025635, 1038.0605551975475, 1041.5052849896238, 979.6816034788723, 993.973662673911, 998.5756053677303, 1007.7883644434604, 1011.1910471622673, 7128.0, 7222.711091556279, 7247.13034435871, 7256.528325658815, 7277.9636101820215, 1009.1876039788041, 1025.2823023883311, 1035.5133087448887, 1054.6888667843004, 1056.4242487844144, 4425.557444255549, 4555.840545580889, 4615.26155815068, 4626.953730462719, 4823.27650852375, 14025.438982440588, 14326.49857254991, 14921.925390373195, 14959.0, 14976.775348369783, 14819.925900370428, 14825.481108161379, 14856.554303370916, 14880.107193568421, 14889.627759306104, 10874.945625271981, 11101.888981110085, 11103.944480277547, 11111.333320000664, 11118.22172447937, 7356.926430735838, 7572.886406704014, 7621.085469743666, 7751.108622508484, 7823.76528704117, 12627.747445050863, 13046.608601741962, 13435.731285374042, 13470.865291346961, 13634.93182534081, 12759.61721148367, 13064.21614703121, 13291.734165316833, 13434.462621495031, 13484.730305394034, 9303.581338839904, 9555.952220238854, 9821.607135714492, 10867.565297388015, 11209.887901120885, 9521.238300936047, 9806.803863922823, 10958.726031849268, 11298.4915678793, 11426.542938282708, 9095.090490951117, 9141.862872057058, 9360.297977651568, 9492.667756628485, 9609.9039009609, 8921.197092261727, 9060.275177985613, 9364.297677674065, 9384.0, 9584.952075239858, 8675.652973880975, 8696.82606347857, 8721.0, 8760.693375731818, 8846.292296616384, 6165.32181460048, 6206.937930620636, 6217.813465596039, 6256.874862502816, 6263.0, 6351.936480635134, 6399.7440102395385, 6506.3818937200895, 6577.850543329011, 6594.0], "value": 5409.04756761989, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.78760619690155, 446.62260389970476, 447.45634054623633, 447.6016345452548, 453.21819860740226, 5162.38051433828, 5189.662671926322, 5191.0, 5197.298364720767, 5230.163173892178, 4963.255511673251, 5107.0, 5137.743112844361, 5141.665791723535, 5193.039967218678, 4284.721493102954, 4304.913901721964, 4316.417283666696, 4317.827286908519, 4330.805113769872, 4313.374560688699, 4332.176886391576, 4338.158506178458, 4342.500612429563, 4349.26062569364, 4314.719543229696, 4337.696361254713, 4344.348347747848, 4347.260965635849, 4353.804078816445, 4095.508538975327, 4291.592298731615, 4324.502682191557, 4338.522762496123, 4343.6959412841115, 4072.8392408163827, 4074.7147699661036, 4078.5309689385804, 4111.835526578933, 4120.896977575555, 5093.0, 5112.539871411591, 5124.155304090525, 5136.357955255593, 5149.661088117079, 5009.824656137054, 5049.343585333883, 5057.317262169597, 5064.493550644943, 5075.857931965315, 5372.597055220835, 5423.620346575742, 5428.891422171534, 5434.212039254326, 5443.346798384219, 4528.637708983273, 4537.434585068147, 4553.317002449659, 4569.771511424448, 4642.883927901796, 5500.0, 5593.860153496154, 5599.496045355938, 5632.9436705632825, 5670.546356291446, 4677.742724150136, 4688.671792974459, 4692.906141877143, 4702.083093796745, 4709.693869898446, 4799.992001679617, 4800.039992001613, 4810.278458231223, 4820.0, 4837.830675926326, 4861.124997500413, 5022.522860328263, 5025.271335656309, 5036.212144688656, 5037.395512538519, 5426.457354264542, 5435.994341046919, 5438.498290786203, 5445.891082178334, 5490.286262785812, 1573.9921300393426, 1639.7622344759943, 1643.145564306554, 1646.2345009570606, 1659.5021493551885, 1238.7089034076985, 1251.8059700746398, 1268.346801397281, 1276.6872116331372, 1293.2822283632486, 5248.947510524922, 5273.604479664041, 5275.57795376369, 5292.655977361459, 5300.0, 5535.723213839292, 5564.970480461127, 5592.720363981784, 5613.663180209201, 5624.3813180550105, 5023.246513023076, 5286.920696189561, 5296.305182341691, 5305.230741542455, 5321.787128514816, 2938.7502062324943, 2946.690597487233, 2954.527275635888, 2955.1725516855313, 2956.9502826496714, 5731.475142787239, 5736.770529178786, 5779.035128056481, 5804.796832110856, 5865.88268234633, 5399.83800485986, 5483.835484935458, 5499.092649712753, 5617.831465056055, 5673.77304907799, 5596.86007849807, 5690.780595567732, 5766.567507436959, 5843.0, 5856.209411729362, 5500.532454741392, 5513.133170751267, 5590.86022849432, 5619.185218143428, 5640.238567793295, 5361.785528578813, 5448.891022179455, 5495.642783219078, 5505.284313039279, 5515.255440515479, 5747.885042299215, 5777.6822274775295, 5782.942170578409, 5790.94209057904, 5815.331236907809, 4448.6218671412, 4621.838235661736, 4650.837220697259, 4669.925917039168, 4674.368960190467, 5535.612507124462, 5544.445555444585, 5558.277423934863, 5579.860503487445, 5655.4910058094965, 5405.216243644728, 5414.566834653139, 5428.824137879385, 5466.398696143341, 5477.643953143033, 5307.735742518401, 5421.647592906448, 5439.0, 5442.265294185234, 5476.342838859362, 5403.4326395727885, 5410.891782164414, 5417.83746487606, 5419.512243898068, 5446.863828404322, 5452.791619394152, 5452.809151679672, 5481.643693159932, 5486.670799752028, 5529.695866727368, 5202.73986300691, 5246.737663116753, 5254.553362964038, 5281.366236051545, 5310.920336194883], "value": 4389.391629668969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.3299991506328, 899.4101658194323, 904.5341649050736, 909.715664413802, 928.9721308360747, 15732.29204685789, 15835.0, 15930.12384318862, 15978.0, 17448.47654570362, 16988.405405810798, 17223.655526889455, 17350.132493375342, 17386.304547818072, 17577.472675819714, 856.6787454704486, 860.6901515454431, 881.8985816631073, 889.8976617688952, 895.7939673875013, 882.7704796752827, 885.4377470306338, 885.5572213893054, 885.716570697377, 886.2334081019903, 936.9693337328918, 937.610891480035, 938.9389689670184, 939.685205456173, 939.9342046056779, 922.4096578189964, 924.1036194890945, 929.3355250995537, 929.6606738540452, 930.144267274106, 683.197243239192, 683.4771399879096, 684.3669605614787, 684.9657517124174, 685.5852209413283, 18164.091795410175, 18218.27126914922, 18235.908820455945, 18926.0, 19250.22999080035, 16376.0, 16437.424690135907, 16505.0, 16709.331626734915, 17295.135243237914, 13258.0, 13447.66380840477, 13493.865061349457, 14090.788638170436, 14113.0, 3963.4451176835264, 4048.8785336439955, 4099.959000409987, 4115.279826030433, 4181.268278051329, 66101.0, 77260.61369693115, 92178.0, 93038.53480732553, 96267.0373296265, 12756.61730148097, 12758.681032974064, 12867.099303048884, 12971.416286266953, 12990.480380784851, 25121.874390628294, 25198.874005629856, 25297.0, 25417.237482875542, 25702.0, 8848.292136629054, 8999.865002024975, 9023.684171054112, 9029.367944243972, 9056.773580660536, 4883.609311255091, 4982.626303027288, 4994.425641051253, 5011.0980023595375, 5185.2999845021195, 3809.847606095725, 3902.453656488094, 3902.5316961964177, 3907.0623050467684, 3908.179282350738, 637.3594537489793, 640.8013515810114, 644.3942693867749, 657.2376043789181, 659.1891972873393, 786.3041208530492, 799.9640016199285, 800.3837045475068, 819.3338934766566, 820.4953953318785, 10854.403007834644, 10914.236003479838, 10964.287321324247, 10982.890171098346, 11088.445577721082, 679.599036568427, 685.7805502239336, 703.5145749432875, 719.7624783821431, 735.8307589254501, 5166.896662066738, 5210.739463026909, 5251.448597897166, 5287.41838397776, 5348.0, 85549.0, 87025.12974870297, 87137.56431217803, 88468.5576572113, 88929.0, 60598.18205453843, 62231.0, 71433.6428317855, 73357.6332118336, 73827.0, 60120.699396502736, 60380.39619603748, 61083.38916610777, 61085.0, 61404.0, 11531.423428828357, 12036.699082523008, 12048.397580121135, 12136.150469467053, 12302.138850280395, 38189.0, 39126.0, 39483.60516394799, 42313.730588082406, 42614.36078458764, 55318.44681553294, 55639.16541251965, 55748.72125639346, 57585.13622295585, 59134.40865591289, 14386.784198236826, 14393.424263029781, 14552.708945820812, 14668.046576972463, 14799.92600037036, 16553.751693724844, 16882.746758798872, 16938.745918810982, 16983.150842458075, 17235.741463877803, 14805.555833325017, 14831.703365932837, 15034.0, 15514.767278490606, 15590.532284031497, 15615.141167235457, 16052.518424447286, 16073.437429689904, 16917.154142293082, 16940.068296243826, 15052.322645480761, 15130.697386052438, 15452.613684657974, 15649.68700626004, 15682.843171568595, 10715.464226788474, 11036.503357348778, 11182.217244793112, 11371.43142842838, 11645.301281923112, 11135.109191264517, 11368.658940231806, 11397.658070257905, 11403.657890263305, 11492.425378731197], "value": 7942.556244572257, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 112.0, 112.0, 113.0, 112.0, 111.0, 112.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 118.0, 157.0, 117.0, 117.0, 116.0, 114.0, 135.0, 115.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 159.0, 159.0, 160.0, 159.0, 160.0, 160.0, 159.0, 163.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 102.0, 76.0, 75.0, 76.0, 77.0, 89.0, 75.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 102.0, 82.0, 83.0, 82.0, 97.0, 82.0, 82.0, 103.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 148.0, 146.0, 147.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 53.0, 54.0, 53.0, 54.0, 54.0, 53.0, 56.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 75.0, 76.0, 75.0, 80.0, 84.0, 80.0, 78.0, 96.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 127.0, 122.0, 133.0, 121.0, 121.0, 118.0, 131.0, 120.0, 117.0], "value": 123.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 160.0, 160.0, 160.0, 158.0, 164.0, 171.0, 157.0, 163.0, 155.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 59.0, 59.0, 60.0, 59.0, 62.0, 59.0, 60.0, 59.0, 57.0], "value": 59.6, "unit": "score"}]}]} 13:31:25 INFO - Return code: 0 13:31:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:31:25 INFO - # TBPL SUCCESS # 13:31:25 INFO - Running post-action listener: _resource_record_post_action 13:31:25 INFO - [mozharness: 2017-06-01 20:31:25.440587Z] Finished run-tests step (success) 13:31:25 INFO - Running post-run listener: _resource_record_post_run 13:31:25 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 328630272; Read time: 0; Write time: 190528 13:31:25 INFO - TinderboxPrint: CPU usage
13.1% 13:31:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 13:31:25 INFO - TinderboxPrint: I/O write bytes / time
328,630,272 / 190,528 13:31:25 INFO - TinderboxPrint: CPU idle
8,246.3 (86.9%) 13:31:25 INFO - TinderboxPrint: CPU user
1,199.6 (12.6%) 13:31:25 INFO - TinderboxPrint: Swap in / out
0 / 0 13:31:25 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 98775040; Read time: 0; Write time: 91600 13:31:25 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 229281792; Read time: 0; Write time: 98904 13:31:26 INFO - Running post-run listener: _upload_blobber_files 13:31:26 INFO - Blob upload gear active. 13:31:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:31:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:31:26 INFO - (blobuploader) - INFO - Open directory for files ... 13:31:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:31:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:31:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:27 INFO - (blobuploader) - INFO - Done attempting. 13:31:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:31:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:31:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:28 INFO - (blobuploader) - INFO - Done attempting. 13:31:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:31:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:31:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:34 INFO - (blobuploader) - INFO - Done attempting. 13:31:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:31:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:31:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:35 INFO - (blobuploader) - INFO - Done attempting. 13:31:35 INFO - (blobuploader) - INFO - Iteration through files over. 13:31:35 INFO - Return code: 0 13:31:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:31:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:31:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bb660d5f0b97b4236ed9f07dd8de8bf4bf5443586d6cba7409b0413bda14e3eac5e4349b04974c7c3b3c5c6d8cd3cef0a78c26aa971efa4e90b809efb91c5312", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fae5104a860942934935b2ecb4a66ba979d9572b8cc6504641c415d31b2c5d06a50683fd75c2080695dfce2a50d908fc1865dd7d2470f931701a7a79a9642f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a128ff1c5d125a6d8134ddedd3ea76a72f14753be16cf0559de854bce62f2310602e3237a029727a9d3f37427baa63f43a50efb510bb7c3e72b1e8581cf56bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/12bb9e20bd78a83739dbb96b27c2cc8359c46d0183a40d99d3199d5367a7820d98568c099b0a998cde3621c9d9788a6d1596934ac9533be4e12fd972fc475746"} 13:31:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:31:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:31:35 INFO - Contents: 13:31:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bb660d5f0b97b4236ed9f07dd8de8bf4bf5443586d6cba7409b0413bda14e3eac5e4349b04974c7c3b3c5c6d8cd3cef0a78c26aa971efa4e90b809efb91c5312", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fae5104a860942934935b2ecb4a66ba979d9572b8cc6504641c415d31b2c5d06a50683fd75c2080695dfce2a50d908fc1865dd7d2470f931701a7a79a9642f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a128ff1c5d125a6d8134ddedd3ea76a72f14753be16cf0559de854bce62f2310602e3237a029727a9d3f37427baa63f43a50efb510bb7c3e72b1e8581cf56bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/12bb9e20bd78a83739dbb96b27c2cc8359c46d0183a40d99d3199d5367a7820d98568c099b0a998cde3621c9d9788a6d1596934ac9533be4e12fd972fc475746"} 13:31:35 INFO - Running post-run listener: copy_logs_to_upload_dir 13:31:35 INFO - Copying logs to upload dir... 13:31:35 INFO - mkdir: /builds/slave/test/build/upload/logs 13:31:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.602822 ========= master_lag: -0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-06-01 13:31:35.094568) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 13:31:35.103806) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212 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/bb660d5f0b97b4236ed9f07dd8de8bf4bf5443586d6cba7409b0413bda14e3eac5e4349b04974c7c3b3c5c6d8cd3cef0a78c26aa971efa4e90b809efb91c5312", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fae5104a860942934935b2ecb4a66ba979d9572b8cc6504641c415d31b2c5d06a50683fd75c2080695dfce2a50d908fc1865dd7d2470f931701a7a79a9642f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a128ff1c5d125a6d8134ddedd3ea76a72f14753be16cf0559de854bce62f2310602e3237a029727a9d3f37427baa63f43a50efb510bb7c3e72b1e8581cf56bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/12bb9e20bd78a83739dbb96b27c2cc8359c46d0183a40d99d3199d5367a7820d98568c099b0a998cde3621c9d9788a6d1596934ac9533be4e12fd972fc475746"} build_url:https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/E9WRSt1oSNuSlqUemC7gpg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bb660d5f0b97b4236ed9f07dd8de8bf4bf5443586d6cba7409b0413bda14e3eac5e4349b04974c7c3b3c5c6d8cd3cef0a78c26aa971efa4e90b809efb91c5312", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6fae5104a860942934935b2ecb4a66ba979d9572b8cc6504641c415d31b2c5d06a50683fd75c2080695dfce2a50d908fc1865dd7d2470f931701a7a79a9642f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a128ff1c5d125a6d8134ddedd3ea76a72f14753be16cf0559de854bce62f2310602e3237a029727a9d3f37427baa63f43a50efb510bb7c3e72b1e8581cf56bda", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/12bb9e20bd78a83739dbb96b27c2cc8359c46d0183a40d99d3199d5367a7820d98568c099b0a998cde3621c9d9788a6d1596934ac9533be4e12fd972fc475746"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 13:31:35.128173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:31:35.132571) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1496343552.297030-2136209212 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:31:35.210311) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 13:31:35.210627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 13:31:35.210947) ========= ========= Total master_lag: 0.12 =========