builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-049 starttime: 1490701105.65 results: success (0) revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:25.648069) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:25.648522) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:25.648804) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:25.663012) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 04:38:25.663273) ========= 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=db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-28 04:38:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.8M=0s 2017-03-28 04:38:27 (57.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.166088 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 04:38:26.843161) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:26.843487) ========= 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=db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018609 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:26.892517) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 04:38:26.892864) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 --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=db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-28 04:38:27,327 truncating revision to first 12 chars 2017-03-28 04:38:27,327 Setting DEBUG logging. 2017-03-28 04:38:27,327 attempt 1/10 2017-03-28 04:38:27,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d4af7ec6cfcd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 04:38:30,865 unpacking tar archive at: mozilla-central-d4af7ec6cfcd/testing/mozharness/ program finished with exit code 0 elapsedTime=3.856864 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 04:38:30.762837) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:30.763153) ========= script_repo_revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:30.763510) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:30.763781) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 04:38:30.772385) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-03-28 04:38:30.772836) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:38:31 INFO - MultiFileLogger online at 20170328 04:38:31 in /builds/slave/test 04:38:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:38:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:38:31 INFO - {'append_to_log': False, 04:38:31 INFO - 'base_work_dir': '/builds/slave/test', 04:38:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:38:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:38:31 INFO - 'branch': 'Firefox-Non-PGO', 04:38:31 INFO - 'buildbot_json_path': 'buildprops.json', 04:38:31 INFO - 'config_files': ('talos/linux_config.py',), 04:38:31 INFO - 'default_actions': ('clobber', 04:38:31 INFO - 'read-buildbot-config', 04:38:31 INFO - 'download-and-extract', 04:38:31 INFO - 'populate-webroot', 04:38:31 INFO - 'create-virtualenv', 04:38:31 INFO - 'install', 04:38:31 INFO - 'run-tests'), 04:38:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:38:31 INFO - 'download_minidump_stackwalk': True, 04:38:31 INFO - 'download_symbols': 'ondemand', 04:38:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:38:31 INFO - 'tooltool.py': '/tools/tooltool.py', 04:38:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:38:31 INFO - '/tools/misc-python/virtualenv.py')}, 04:38:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:38:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:38:31 INFO - 'gecko_profile': False, 04:38:31 INFO - 'gecko_profile_interval': 0, 04:38:31 INFO - 'installer_path': 'installer.exe', 04:38:31 INFO - 'log_level': 'info', 04:38:31 INFO - 'log_name': 'talos', 04:38:31 INFO - 'log_to_console': True, 04:38:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:38:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:38:31 INFO - 'opt_config_files': (), 04:38:31 INFO - 'pip_index': False, 04:38:31 INFO - 'suite': 'dromaeojs', 04:38:31 INFO - 'system_bits': '32', 04:38:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:38:31 INFO - 'title': 'talos-linux64-ix-049', 04:38:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:38:31 INFO - 'use_talos_json': True, 04:38:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:38:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:38:31 INFO - 'work_dir': 'build'} 04:38:31 INFO - [mozharness: 2017-03-28 11:38:31.303809Z] Running clobber step. 04:38:31 INFO - Running pre-action listener: _resource_record_pre_action 04:38:31 INFO - Running main action method: clobber 04:38:31 INFO - rmtree: /builds/slave/test/build 04:38:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:38:32 INFO - Running post-action listener: _resource_record_post_action 04:38:32 INFO - [mozharness: 2017-03-28 11:38:32.889947Z] Finished clobber step (success) 04:38:32 INFO - [mozharness: 2017-03-28 11:38:32.890044Z] Running read-buildbot-config step. 04:38:32 INFO - Running pre-action listener: _resource_record_pre_action 04:38:32 INFO - Running main action method: read_buildbot_config 04:38:32 INFO - Using buildbot properties: 04:38:32 INFO - { 04:38:32 INFO - "project": "", 04:38:32 INFO - "product": "firefox", 04:38:32 INFO - "who": "cbook@mozilla.com", 04:38:32 INFO - "installer_path": "public/build/target.tar.bz2", 04:38:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:38:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:38:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 04:38:32 INFO - "stage_platform": "linux64", 04:38:32 INFO - "basedir": "/builds/slave/test", 04:38:32 INFO - "buildnumber": 515, 04:38:32 INFO - "platform": "ubuntu64_hw", 04:38:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:38:32 INFO - "slavebuilddir": "test", 04:38:32 INFO - "taskId": "c8hw6vzoRzyxQSz58HlIDg", 04:38:32 INFO - "branch": "mozilla-central", 04:38:32 INFO - "script_repo_revision": "production", 04:38:32 INFO - "revision": "d4af7ec6cfcd9b81cd1f433a00b412de61e95b62", 04:38:32 INFO - "slavename": "talos-linux64-ix-049", 04:38:32 INFO - "repo_path": "mozilla-central" 04:38:32 INFO - } 04:38:32 INFO - Finding installer, test and symbols from parent task. 04:38:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c8hw6vzoRzyxQSz58HlIDg'}, attempt #1 04:38:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c8hw6vzoRzyxQSz58HlIDg'}, attempt #1 04:38:35 INFO - Task dependencies: bsyIprExTAeKjTbpenp6iA 04:38:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA'}, attempt #1 04:38:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 04:38:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/test_packages.json 04:38:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip 04:38:37 INFO - Running post-action listener: _resource_record_post_action 04:38:37 INFO - [mozharness: 2017-03-28 11:38:37.369713Z] Finished read-buildbot-config step (success) 04:38:37 INFO - [mozharness: 2017-03-28 11:38:37.370033Z] Running download-and-extract step. 04:38:37 INFO - Running pre-action listener: _resource_record_pre_action 04:38:37 INFO - Running main action method: download_and_extract 04:38:37 INFO - mkdir: /builds/slave/test/build/tests 04:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:38:37 INFO - trying https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.test_packages.json 04:38:37 INFO - Downloading https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:38:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:38:40 INFO - Downloaded 986 bytes. 04:38:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:38:40 INFO - Using the following test package requirements: 04:38:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:38:40 INFO - u'common': [u'target.common.tests.zip'], 04:38:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:38:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:38:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:38:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:38:40 INFO - u'mozbase': [u'target.common.tests.zip'], 04:38:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:38:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:38:40 INFO - u'web-platform': [u'target.common.tests.zip', 04:38:40 INFO - u'target.web-platform.tests.zip'], 04:38:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:38:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:38:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip 04:38:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:38:40 INFO - Fetch https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip into memory 04:38:44 INFO - Expected file size: 58220873 04:38:44 INFO - Obtained file size: 58220873 04:38:53 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:38:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip 04:38:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:38:53 INFO - Fetch https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip into memory 04:38:57 INFO - Expected file size: 58220873 04:38:57 INFO - Obtained file size: 58220873 04:39:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.talos.tests.zip 04:39:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:39:07 INFO - Fetch https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.talos.tests.zip into memory 04:39:10 INFO - Expected file size: 14404171 04:39:10 INFO - Obtained file size: 14404171 04:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:10 INFO - trying https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 04:39:10 INFO - Downloading https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:39:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:39:13 INFO - Downloaded 62551454 bytes. 04:39:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 04:39:13 INFO - mkdir: /builds/slave/test/properties 04:39:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:39:13 INFO - Writing to file /builds/slave/test/properties/build_url 04:39:13 INFO - Contents: 04:39:13 INFO - build_url:https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 04:39:13 INFO - Running post-action listener: _resource_record_post_action 04:39:13 INFO - Running post-action listener: set_extra_try_arguments 04:39:13 INFO - [mozharness: 2017-03-28 11:39:13.762240Z] Finished download-and-extract step (success) 04:39:13 INFO - [mozharness: 2017-03-28 11:39:13.762337Z] Running populate-webroot step. 04:39:13 INFO - Running pre-action listener: _resource_record_pre_action 04:39:13 INFO - Running main action method: populate_webroot 04:39:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:39:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:39:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:39:13 INFO - '--tppagecycles', 04:39:13 INFO - '7'], 04:39:13 INFO - 'tests': ['tsvgm']}}, 04:39:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:39:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:39:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:39:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:39:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:39:13 INFO - 'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:39:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:39:13 INFO - 'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['damp', 'tps']}, 04:39:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:39:13 INFO - 'tests': ['damp', 'tps']}, 04:39:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['dromaeo_dom']}, 04:39:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:39:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['basic_compositor_video']}, 04:39:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:39:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['a11yr', 04:39:13 INFO - 'ts_paint', 04:39:13 INFO - 'tpaint', 04:39:13 INFO - 'sessionrestore', 04:39:13 INFO - 'sessionrestore_no_auto_restore', 04:39:13 INFO - 'tabpaint']}, 04:39:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:39:13 INFO - 'ts_paint', 04:39:13 INFO - 'tpaint', 04:39:13 INFO - 'sessionrestore', 04:39:13 INFO - 'sessionrestore_no_auto_restore', 04:39:13 INFO - 'tabpaint']}, 04:39:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:39:13 INFO - 'ts_paint', 04:39:13 INFO - 'tpaint', 04:39:13 INFO - 'sessionrestore', 04:39:13 INFO - 'sessionrestore_no_auto_restore', 04:39:13 INFO - 'tabpaint']}, 04:39:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:39:13 INFO - 'ts_paint', 04:39:13 INFO - 'tpaint', 04:39:13 INFO - 'sessionrestore', 04:39:13 INFO - 'sessionrestore_no_auto_restore', 04:39:13 INFO - 'tabpaint']}, 04:39:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['a11yr', 04:39:13 INFO - 'ts_paint', 04:39:13 INFO - 'tpaint', 04:39:13 INFO - 'sessionrestore', 04:39:13 INFO - 'sessionrestore_no_auto_restore', 04:39:13 INFO - 'tabpaint']}, 04:39:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['a11yr', 04:39:13 INFO - 'ts_paint', 04:39:13 INFO - 'tpaint', 04:39:13 INFO - 'sessionrestore', 04:39:13 INFO - 'sessionrestore_no_auto_restore', 04:39:13 INFO - 'tabpaint']}, 04:39:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['tsvgx', 04:39:13 INFO - 'tsvgr_opacity', 04:39:13 INFO - 'tart', 04:39:13 INFO - 'tscrollx', 04:39:13 INFO - 'cart', 04:39:13 INFO - 'tsvg_static']}, 04:39:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:39:13 INFO - 'tsvgr_opacity', 04:39:13 INFO - 'tart', 04:39:13 INFO - 'tscrollx', 04:39:13 INFO - 'cart', 04:39:13 INFO - 'tsvg_static']}, 04:39:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:39:13 INFO - 'talos_options': ['--disable-e10s'], 04:39:13 INFO - 'tests': ['tp5o']}, 04:39:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:39:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:39:13 INFO - 'talos_options': ['--disable-e10s', 04:39:13 INFO - '--xperf_path', 04:39:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:13 INFO - 'tests': ['tp5n']}, 04:39:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:39:13 INFO - 'talos_options': ['--xperf_path', 04:39:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:13 INFO - 'tests': ['tp5n']}}, 04:39:13 INFO - 'talos.zip': {'path': '', 04:39:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:39:13 INFO - Running post-action listener: _resource_record_post_action 04:39:13 INFO - [mozharness: 2017-03-28 11:39:13.775591Z] Finished populate-webroot step (success) 04:39:13 INFO - [mozharness: 2017-03-28 11:39:13.775680Z] Running create-virtualenv step. 04:39:13 INFO - Running pre-action listener: _resource_record_pre_action 04:39:13 INFO - Running main action method: create_virtualenv 04:39:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:39:13 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 04:39:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:39:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:39:13 INFO - Using real prefix '/usr' 04:39:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:39:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:39:16 INFO - Installing pip.................done. 04:39:16 INFO - Return code: 0 04:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:39:16 INFO - Reading from file tmpfile_stdout 04:39:16 INFO - Output received: 04:39:16 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) 04:39:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:39:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:39:19 INFO - Using env: {'DISPLAY': ':0', 04:39:19 INFO - 'HOME': '/home/cltbld', 04:39:19 INFO - 'LANG': 'en_US.UTF-8', 04:39:19 INFO - 'LANGUAGE': 'en_US:en', 04:39:19 INFO - 'LOGNAME': 'cltbld', 04:39:19 INFO - 'MAIL': '/var/mail/cltbld', 04:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:19 INFO - 'MOZ_NO_REMOTE': '1', 04:39:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:19 INFO - 'NO_EM_RESTART': '1', 04:39:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:19 INFO - 'PWD': '/builds/slave/test', 04:39:19 INFO - 'SHELL': '/bin/bash', 04:39:19 INFO - 'SHLVL': '1', 04:39:19 INFO - 'TERM': 'linux', 04:39:19 INFO - 'TMOUT': '86400', 04:39:19 INFO - 'USER': 'cltbld', 04:39:19 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:19 INFO - '_': '/tools/buildbot/bin/python'} 04:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:20 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 04:39:20 INFO - Cleaning up... 04:39:20 INFO - Return code: 0 04:39:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:39:23 INFO - Using env: {'DISPLAY': ':0', 04:39:23 INFO - 'HOME': '/home/cltbld', 04:39:23 INFO - 'LANG': 'en_US.UTF-8', 04:39:23 INFO - 'LANGUAGE': 'en_US:en', 04:39:23 INFO - 'LOGNAME': 'cltbld', 04:39:23 INFO - 'MAIL': '/var/mail/cltbld', 04:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:23 INFO - 'MOZ_NO_REMOTE': '1', 04:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:23 INFO - 'NO_EM_RESTART': '1', 04:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:23 INFO - 'PWD': '/builds/slave/test', 04:39:23 INFO - 'SHELL': '/bin/bash', 04:39:23 INFO - 'SHLVL': '1', 04:39:23 INFO - 'TERM': 'linux', 04:39:23 INFO - 'TMOUT': '86400', 04:39:23 INFO - 'USER': 'cltbld', 04:39:23 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:23 INFO - '_': '/tools/buildbot/bin/python'} 04:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:23 INFO - Downloading/unpacking psutil>=3.1.1 04:39: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 04:39: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 04:39:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39: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 04:39: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 04:39:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:39:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:31 INFO - Installing collected packages: psutil 04:39:31 INFO - Running setup.py install for psutil 04:39:31 INFO - building 'psutil._psutil_linux' extension 04:39:31 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 04:39:31 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 04:39:31 INFO - building 'psutil._psutil_posix' extension 04:39:31 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 04:39:31 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 04:39:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:31 INFO - Successfully installed psutil 04:39:31 INFO - Cleaning up... 04:39:31 INFO - Return code: 0 04:39:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:39:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:39:35 INFO - Using env: {'DISPLAY': ':0', 04:39:35 INFO - 'HOME': '/home/cltbld', 04:39:35 INFO - 'LANG': 'en_US.UTF-8', 04:39:35 INFO - 'LANGUAGE': 'en_US:en', 04:39:35 INFO - 'LOGNAME': 'cltbld', 04:39:35 INFO - 'MAIL': '/var/mail/cltbld', 04:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:35 INFO - 'MOZ_NO_REMOTE': '1', 04:39:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:35 INFO - 'NO_EM_RESTART': '1', 04:39:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:35 INFO - 'PWD': '/builds/slave/test', 04:39:35 INFO - 'SHELL': '/bin/bash', 04:39:35 INFO - 'SHLVL': '1', 04:39:35 INFO - 'TERM': 'linux', 04:39:35 INFO - 'TMOUT': '86400', 04:39:35 INFO - 'USER': 'cltbld', 04:39:35 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:35 INFO - '_': '/tools/buildbot/bin/python'} 04:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:39:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:39:47 INFO - Installing collected packages: mozsystemmonitor 04:39:47 INFO - Running setup.py install for mozsystemmonitor 04:39:47 INFO - Successfully installed mozsystemmonitor 04:39:47 INFO - Cleaning up... 04:39:47 INFO - Return code: 0 04:39:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:39:49 INFO - Using env: {'DISPLAY': ':0', 04:39:49 INFO - 'HOME': '/home/cltbld', 04:39:49 INFO - 'LANG': 'en_US.UTF-8', 04:39:49 INFO - 'LANGUAGE': 'en_US:en', 04:39:49 INFO - 'LOGNAME': 'cltbld', 04:39:49 INFO - 'MAIL': '/var/mail/cltbld', 04:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:49 INFO - 'MOZ_NO_REMOTE': '1', 04:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:49 INFO - 'NO_EM_RESTART': '1', 04:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:49 INFO - 'PWD': '/builds/slave/test', 04:39:49 INFO - 'SHELL': '/bin/bash', 04:39:49 INFO - 'SHLVL': '1', 04:39:49 INFO - 'TERM': 'linux', 04:39:49 INFO - 'TMOUT': '86400', 04:39:49 INFO - 'USER': 'cltbld', 04:39:49 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:49 INFO - '_': '/tools/buildbot/bin/python'} 04:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:50 INFO - Downloading/unpacking jsonschema==2.5.1 04:39:50 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 04:39:50 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 04:39:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:50 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 04:39:50 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 04:39:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:39:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:39:54 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 04:39:54 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 04:39:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:54 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 04:39:54 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 04:39:54 INFO - Downloading functools32-3.2.3-2.tar.gz 04:39:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:39:54 INFO - warning: no files found matching '*.txt' 04:39:54 INFO - no previously-included directories found matching 'build' 04:39:54 INFO - no previously-included directories found matching 'dist' 04:39:54 INFO - no previously-included directories found matching '.git*' 04:39:54 INFO - Installing collected packages: jsonschema, functools32 04:39:54 INFO - Running setup.py install for functools32 04:39:54 INFO - warning: no files found matching '*.txt' 04:39:54 INFO - no previously-included directories found matching 'build' 04:39:54 INFO - no previously-included directories found matching 'dist' 04:39:54 INFO - no previously-included directories found matching '.git*' 04:39:54 INFO - Successfully installed jsonschema functools32 04:39:54 INFO - Cleaning up... 04:39:54 INFO - Return code: 0 04:39:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:39:56 INFO - Using env: {'DISPLAY': ':0', 04:39:56 INFO - 'HOME': '/home/cltbld', 04:39:56 INFO - 'LANG': 'en_US.UTF-8', 04:39:56 INFO - 'LANGUAGE': 'en_US:en', 04:39:56 INFO - 'LOGNAME': 'cltbld', 04:39:56 INFO - 'MAIL': '/var/mail/cltbld', 04:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:56 INFO - 'MOZ_NO_REMOTE': '1', 04:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:56 INFO - 'NO_EM_RESTART': '1', 04:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:56 INFO - 'PWD': '/builds/slave/test', 04:39:56 INFO - 'SHELL': '/bin/bash', 04:39:56 INFO - 'SHLVL': '1', 04:39:56 INFO - 'TERM': 'linux', 04:39:56 INFO - 'TMOUT': '86400', 04:39:56 INFO - 'USER': 'cltbld', 04:39:56 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:56 INFO - '_': '/tools/buildbot/bin/python'} 04:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:39:56 INFO - Cleaning up... 04:39:56 INFO - Return code: 0 04:39:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:39:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:39:57 INFO - Using env: {'DISPLAY': ':0', 04:39:57 INFO - 'HOME': '/home/cltbld', 04:39:57 INFO - 'LANG': 'en_US.UTF-8', 04:39:57 INFO - 'LANGUAGE': 'en_US:en', 04:39:57 INFO - 'LOGNAME': 'cltbld', 04:39:57 INFO - 'MAIL': '/var/mail/cltbld', 04:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:57 INFO - 'MOZ_NO_REMOTE': '1', 04:39:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:57 INFO - 'NO_EM_RESTART': '1', 04:39:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:57 INFO - 'PWD': '/builds/slave/test', 04:39:57 INFO - 'SHELL': '/bin/bash', 04:39:57 INFO - 'SHLVL': '1', 04:39:57 INFO - 'TERM': 'linux', 04:39:57 INFO - 'TMOUT': '86400', 04:39:57 INFO - 'USER': 'cltbld', 04:39:57 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:57 INFO - '_': '/tools/buildbot/bin/python'} 04:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:57 INFO - Downloading/unpacking blobuploader==1.2.4 04:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:02 INFO - Downloading blobuploader-1.2.4.tar.gz 04:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:40:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:40:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:03 INFO - Downloading docopt-0.6.1.tar.gz 04:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:40:03 INFO - Installing collected packages: blobuploader, requests, docopt 04:40:03 INFO - Running setup.py install for blobuploader 04:40:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:40:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:40:03 INFO - Running setup.py install for requests 04:40:04 INFO - Running setup.py install for docopt 04:40:04 INFO - Successfully installed blobuploader requests docopt 04:40:04 INFO - Cleaning up... 04:40:04 INFO - Return code: 0 04:40:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:40:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:40:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:40:04 INFO - Using env: {'DISPLAY': ':0', 04:40:04 INFO - 'HOME': '/home/cltbld', 04:40:04 INFO - 'LANG': 'en_US.UTF-8', 04:40:04 INFO - 'LANGUAGE': 'en_US:en', 04:40:04 INFO - 'LOGNAME': 'cltbld', 04:40:04 INFO - 'MAIL': '/var/mail/cltbld', 04:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:04 INFO - 'MOZ_NO_REMOTE': '1', 04:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:04 INFO - 'NO_EM_RESTART': '1', 04:40:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:04 INFO - 'PWD': '/builds/slave/test', 04:40:04 INFO - 'SHELL': '/bin/bash', 04:40:04 INFO - 'SHLVL': '1', 04:40:04 INFO - 'TERM': 'linux', 04:40:04 INFO - 'TMOUT': '86400', 04:40:04 INFO - 'USER': 'cltbld', 04:40:04 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:04 INFO - '_': '/tools/buildbot/bin/python'} 04:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:40:04 INFO - Running setup.py (path:/tmp/pip-GsGlUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:40:04 INFO - Running setup.py (path:/tmp/pip-tXbnt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:40:05 INFO - Running setup.py (path:/tmp/pip-xzK6rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:40:05 INFO - Running setup.py (path:/tmp/pip-P3o98x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:40:05 INFO - Running setup.py (path:/tmp/pip-yxcIMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:40:05 INFO - Running setup.py (path:/tmp/pip-muZdFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:40:05 INFO - Running setup.py (path:/tmp/pip-0Umayq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:40:05 INFO - Running setup.py (path:/tmp/pip-xspgb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:40:05 INFO - Running setup.py (path:/tmp/pip-0jmVPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:40:05 INFO - Running setup.py (path:/tmp/pip-W8zwjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:40:05 INFO - Running setup.py (path:/tmp/pip-Qd8urm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:40:05 INFO - Running setup.py (path:/tmp/pip-mG1lB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:40:05 INFO - Running setup.py (path:/tmp/pip-vY_2pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:40:05 INFO - Running setup.py (path:/tmp/pip-o51JPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:05 INFO - Running setup.py (path:/tmp/pip-1sFd4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:40:05 INFO - Running setup.py (path:/tmp/pip-e5gAQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:40:06 INFO - Running setup.py (path:/tmp/pip-rH3RU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:40:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:40:06 INFO - Running setup.py install for manifestparser 04:40:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Running setup.py install for mozcrash 04:40:06 INFO - Running setup.py install for mozdebug 04:40:06 INFO - Running setup.py install for mozdevice 04:40:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Running setup.py install for mozfile 04:40:06 INFO - Running setup.py install for mozhttpd 04:40:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Running setup.py install for mozinfo 04:40:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Running setup.py install for mozInstall 04:40:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:40:06 INFO - Running setup.py install for mozleak 04:40:06 INFO - Running setup.py install for mozlog 04:40:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:40:07 INFO - Running setup.py install for moznetwork 04:40:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:40:07 INFO - Running setup.py install for mozprocess 04:40:07 INFO - Running setup.py install for mozprofile 04:40:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:40:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:40:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:40:07 INFO - Running setup.py install for mozrunner 04:40:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:40:07 INFO - Running setup.py install for mozscreenshot 04:40:07 INFO - Running setup.py install for moztest 04:40:07 INFO - Running setup.py install for mozversion 04:40:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:40:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:40:07 INFO - Cleaning up... 04:40:07 INFO - Return code: 0 04:40:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:40:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:40:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:40:08 INFO - Using env: {'DISPLAY': ':0', 04:40:08 INFO - 'HOME': '/home/cltbld', 04:40:08 INFO - 'LANG': 'en_US.UTF-8', 04:40:08 INFO - 'LANGUAGE': 'en_US:en', 04:40:08 INFO - 'LOGNAME': 'cltbld', 04:40:08 INFO - 'MAIL': '/var/mail/cltbld', 04:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:08 INFO - 'MOZ_NO_REMOTE': '1', 04:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:08 INFO - 'NO_EM_RESTART': '1', 04:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:08 INFO - 'PWD': '/builds/slave/test', 04:40:08 INFO - 'SHELL': '/bin/bash', 04:40:08 INFO - 'SHLVL': '1', 04:40:08 INFO - 'TERM': 'linux', 04:40:08 INFO - 'TMOUT': '86400', 04:40:08 INFO - 'USER': 'cltbld', 04:40:08 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:08 INFO - '_': '/tools/buildbot/bin/python'} 04:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:40:08 INFO - Running setup.py (path:/tmp/pip-drBJrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:40:08 INFO - Running setup.py (path:/tmp/pip-bVNYTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:40:08 INFO - Running setup.py (path:/tmp/pip-ONL0Lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:40:08 INFO - Running setup.py (path:/tmp/pip-YZIsFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:40:08 INFO - Running setup.py (path:/tmp/pip-seDXO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:40:08 INFO - Running setup.py (path:/tmp/pip-xRwE5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:40:08 INFO - Running setup.py (path:/tmp/pip-YDMZ6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:40:08 INFO - Running setup.py (path:/tmp/pip-fXqHwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:40:08 INFO - Running setup.py (path:/tmp/pip-XblqxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:40:08 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)) 04:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:40:08 INFO - Running setup.py (path:/tmp/pip-ji1tnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:40:09 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)) 04:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:40:09 INFO - Running setup.py (path:/tmp/pip-nwu4Wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:40:09 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)) 04:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:40:09 INFO - Running setup.py (path:/tmp/pip-D2lqdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:40:09 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)) 04:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:40:09 INFO - Running setup.py (path:/tmp/pip-VSll9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:40:09 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)) 04:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:40:09 INFO - Running setup.py (path:/tmp/pip-Am0_CO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:40:09 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)) 04:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:09 INFO - Running setup.py (path:/tmp/pip-NqmyLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:09 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)) 04:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:40:09 INFO - Running setup.py (path:/tmp/pip-dYahfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:40:09 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)) 04:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:40:09 INFO - Running setup.py (path:/tmp/pip-7TvBNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:40:09 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)) 04:40:09 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)) 04:40:09 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)) 04:40:09 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)) 04:40:09 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)) 04:40:09 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)) 04:40:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:12 INFO - Downloading blessings-1.6.tar.gz 04:40:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:40:12 INFO - Installing collected packages: blessings 04:40:12 INFO - Running setup.py install for blessings 04:40:12 INFO - Successfully installed blessings 04:40:12 INFO - Cleaning up... 04:40:12 INFO - Return code: 0 04:40:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:12 INFO - Reading from file tmpfile_stdout 04:40:12 INFO - Current package versions: 04:40:12 INFO - argparse == 1.2.1 04:40:12 INFO - blessings == 1.6 04:40:12 INFO - blobuploader == 1.2.4 04:40:12 INFO - docopt == 0.6.1 04:40:12 INFO - functools32 == 3.2.3-2 04:40:12 INFO - jsonschema == 2.5.1 04:40:12 INFO - manifestparser == 1.1 04:40:12 INFO - mozInstall == 1.12 04:40:12 INFO - mozcrash == 1.0 04:40:12 INFO - mozdebug == 0.1 04:40:12 INFO - mozdevice == 0.49 04:40:12 INFO - mozfile == 1.2 04:40:12 INFO - mozhttpd == 0.7 04:40:12 INFO - mozinfo == 0.9 04:40:12 INFO - mozleak == 0.1 04:40:12 INFO - mozlog == 3.4 04:40:12 INFO - moznetwork == 0.27 04:40:12 INFO - mozprocess == 0.25 04:40:12 INFO - mozprofile == 0.28 04:40:12 INFO - mozrunner == 6.13 04:40:12 INFO - mozscreenshot == 0.1 04:40:12 INFO - mozsystemmonitor == 0.3 04:40:12 INFO - moztest == 0.8 04:40:12 INFO - mozversion == 1.4 04:40:12 INFO - psutil == 3.1.1 04:40:12 INFO - requests == 1.2.3 04:40:12 INFO - wsgiref == 0.1.2 04:40:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:40:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:40:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:40:12 INFO - Using env: {'DISPLAY': ':0', 04:40:12 INFO - 'HOME': '/home/cltbld', 04:40:12 INFO - 'LANG': 'en_US.UTF-8', 04:40:12 INFO - 'LANGUAGE': 'en_US:en', 04:40:12 INFO - 'LOGNAME': 'cltbld', 04:40:12 INFO - 'MAIL': '/var/mail/cltbld', 04:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:12 INFO - 'MOZ_NO_REMOTE': '1', 04:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:12 INFO - 'NO_EM_RESTART': '1', 04:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:12 INFO - 'PWD': '/builds/slave/test', 04:40:12 INFO - 'SHELL': '/bin/bash', 04:40:12 INFO - 'SHLVL': '1', 04:40:12 INFO - 'TERM': 'linux', 04:40:12 INFO - 'TMOUT': '86400', 04:40:12 INFO - 'USER': 'cltbld', 04:40:12 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:12 INFO - '_': '/tools/buildbot/bin/python'} 04:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 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)) 04:40:12 INFO - Cleaning up... 04:40:12 INFO - Return code: 0 04:40:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a0a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b02a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, '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': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', '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 04:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:40:12 INFO - Using env: {'DISPLAY': ':0', 04:40:12 INFO - 'HOME': '/home/cltbld', 04:40:12 INFO - 'LANG': 'en_US.UTF-8', 04:40:12 INFO - 'LANGUAGE': 'en_US:en', 04:40:12 INFO - 'LOGNAME': 'cltbld', 04:40:12 INFO - 'MAIL': '/var/mail/cltbld', 04:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:12 INFO - 'MOZ_NO_REMOTE': '1', 04:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:12 INFO - 'NO_EM_RESTART': '1', 04:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:12 INFO - 'PWD': '/builds/slave/test', 04:40:12 INFO - 'SHELL': '/bin/bash', 04:40:12 INFO - 'SHLVL': '1', 04:40:12 INFO - 'TERM': 'linux', 04:40:12 INFO - 'TMOUT': '86400', 04:40:12 INFO - 'USER': 'cltbld', 04:40:12 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:12 INFO - '_': '/tools/buildbot/bin/python'} 04:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:40:13 INFO - Cleaning up... 04:40:13 INFO - Return code: 0 04:40:13 INFO - Running post-action listener: _resource_record_post_action 04:40:13 INFO - Running post-action listener: _start_resource_monitoring 04:40:13 INFO - Starting resource monitoring. 04:40:13 INFO - [mozharness: 2017-03-28 11:40:13.208546Z] Finished create-virtualenv step (success) 04:40:13 INFO - [mozharness: 2017-03-28 11:40:13.209021Z] Running install step. 04:40:13 INFO - Running pre-action listener: _resource_record_pre_action 04:40:13 INFO - Running main action method: install 04:40:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:13 INFO - Reading from file tmpfile_stdout 04:40:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:40:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:40:13 INFO - Reading from file tmpfile_stdout 04:40:13 INFO - Output received: 04:40:13 INFO - Usage: mozinstall [options] installer 04:40:13 INFO - Options: 04:40:13 INFO - -h, --help show this help message and exit 04:40:13 INFO - -d DEST, --destination=DEST 04:40:13 INFO - Directory to install application into. [default: 04:40:13 INFO - "/builds/slave/test"] 04:40:13 INFO - --app=APP Application being installed. [default: firefox] 04:40:13 INFO - mkdir: /builds/slave/test/build/application 04:40:13 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'] 04:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:40:29 INFO - Reading from file tmpfile_stdout 04:40:29 INFO - Output received: 04:40:29 INFO - /builds/slave/test/build/application/firefox/firefox 04:40:29 INFO - Running post-action listener: _resource_record_post_action 04:40:29 INFO - [mozharness: 2017-03-28 11:40:29.173762Z] Finished install step (success) 04:40:29 INFO - [mozharness: 2017-03-28 11:40:29.174040Z] Running run-tests step. 04:40:29 INFO - Running pre-action listener: _resource_record_pre_action 04:40:29 INFO - Running main action method: run_tests 04:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:40:29 INFO - Python 2.7.3 04:40:29 INFO - Return code: 0 04:40:29 INFO - grabbing minidump binary from tooltool 04:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:40:29 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 0x2ca5290>, '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 0x2ca5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:40:29 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 04:40:29 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 04:40:29 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 04:40:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:40:29 INFO - Return code: 0 04:40:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:40:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:40:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:40:29 INFO - ENV: RUST_BACKTRACE is now 1 04:40:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:40:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 04:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 04:40:29 INFO - Using env: {'DISPLAY': ':0', 04:40:29 INFO - 'HOME': '/home/cltbld', 04:40:29 INFO - 'LANG': 'en_US.UTF-8', 04:40:29 INFO - 'LANGUAGE': 'en_US:en', 04:40:29 INFO - 'LOGNAME': 'cltbld', 04:40:29 INFO - 'MAIL': '/var/mail/cltbld', 04:40:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:40:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:29 INFO - 'MOZ_NO_REMOTE': '1', 04:40:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:29 INFO - 'NO_EM_RESTART': '1', 04:40:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:29 INFO - 'PWD': '/builds/slave/test', 04:40:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:40:29 INFO - 'RUST_BACKTRACE': '1', 04:40:29 INFO - 'SHELL': '/bin/bash', 04:40:29 INFO - 'SHLVL': '1', 04:40:29 INFO - 'TERM': 'linux', 04:40:29 INFO - 'TMOUT': '86400', 04:40:29 INFO - 'USER': 'cltbld', 04:40:29 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808', 04:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:29 INFO - '_': '/tools/buildbot/bin/python'} 04:40:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 04:40:29 INFO - mozversion application_buildid: 20170328110348 04:40:29 INFO - mozversion application_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 04:40:29 INFO - mozversion application_display_name: Nightly 04:40:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:40:29 INFO - mozversion application_name: Firefox 04:40:29 INFO - mozversion application_remotingname: firefox 04:40:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:40:29 INFO - mozversion application_vendor: Mozilla 04:40:29 INFO - mozversion application_version: 55.0a1 04:40:29 INFO - mozversion platform_buildid: 20170328110348 04:40:29 INFO - mozversion platform_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 04:40:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:40:29 INFO - mozversion platform_version: 55.0a1 04:40:29 INFO - using testdate: 1490701229 04:40:29 INFO - actual date: 1490701229 04:40:29 INFO - starting webserver on 'localhost:51256' 04:40:29 INFO - SUITE-START | Running 2 tests 04:40:29 INFO - TEST-START | dromaeo_css 04:40:29 INFO - operating with platform_type : linux_ 04:40:29 INFO - Initialising browser for dromaeo_css test... 04:40:29 INFO - TEST-INFO | started process 3903 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWG9251/profile http://localhost:51256/getInfo.html) 04:40:33 INFO - PID 3903 | __metrics Screen width/height:1600/1200 04:40:33 INFO - PID 3903 | colorDepth:24 04:40:33 INFO - PID 3903 | Browser inner width/height: 1024/697 04:40:33 INFO - PID 3903 | __metrics 04:40:34 ERROR - PID 3903 | 1490701234725 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:40:34 INFO - PID 3903 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:40:34 INFO - PID 3903 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:40:35 INFO - TEST-INFO | 3903: exit 0 04:40:35 INFO - Browser initialized. 04:40:35 INFO - Running cycle 1/1 for dromaeo_css test... 04:40:35 INFO - TEST-INFO | started process 3995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWG9251/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:47 INFO - PID 3995 | RSS: Main: 149856256 04:40:47 INFO - PID 3995 | 04:43:55 INFO - PID 3995 | Cycle 1(1): loaded http://localhost:51256/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51256/tests/dromaeo/cssquery-ext.html) 04:43:57 INFO - PID 3995 | RSS: Main: 334413824 04:43:57 INFO - PID 3995 | 04:45:37 INFO - PID 3995 | ************************* 04:45:37 INFO - PID 3995 | A coding exception was thrown and uncaught in a Task. 04:45:37 INFO - PID 3995 | 04:45:37 ERROR - PID 3995 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:45:37 INFO - PID 3995 | Full stack: 04:45:37 INFO - PID 3995 | ************************* 04:45:37 INFO - PID 3995 | ************************* 04:45:37 INFO - PID 3995 | A coding exception was thrown and uncaught in a Task. 04:45:37 INFO - PID 3995 | 04:45:37 ERROR - PID 3995 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:45:37 INFO - PID 3995 | Full stack: 04:45:37 INFO - PID 3995 | ************************* 04:45:37 INFO - PID 3995 | ************************* 04:45:37 INFO - PID 3995 | A coding exception was thrown and uncaught in a Task. 04:45:37 INFO - PID 3995 | 04:45:37 ERROR - PID 3995 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:45:37 INFO - PID 3995 | Full stack: 04:45:37 INFO - PID 3995 | ************************* 04:45:37 INFO - PID 3995 | ************************* 04:45:37 INFO - PID 3995 | A coding exception was thrown and uncaught in a Task. 04:45:37 INFO - PID 3995 | 04:45:37 ERROR - PID 3995 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:45:37 INFO - PID 3995 | Full stack: 04:45:37 INFO - PID 3995 | ************************* 04:47:00 INFO - PID 3995 | Cycle 1(1): loaded http://localhost:51256/tests/dromaeo/cssquery-ext.html (next: http://localhost:51256/tests/dromaeo/cssquery-jquery.html) 04:47:01 INFO - PID 3995 | RSS: Main: 237846528 04:47:01 INFO - PID 3995 | 04:50:03 INFO - PID 3995 | Cycle 1(1): loaded http://localhost:51256/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51256/tests/dromaeo/cssquery-mootools.html) 04:50:03 INFO - PID 3995 | RSS: Main: 177537024 04:50:03 INFO - PID 3995 | 04:53:06 INFO - PID 3995 | Cycle 1(1): loaded http://localhost:51256/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51256/tests/dromaeo/cssquery-prototype.html) 04:53:06 INFO - PID 3995 | RSS: Main: 233902080 04:53:06 INFO - PID 3995 | 04:56:09 INFO - PID 3995 | Cycle 1(1): loaded http://localhost:51256/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51256/tests/dromaeo/cssquery-yui.html) 04:56:10 INFO - PID 3995 | RSS: Main: 185929728 04:56:10 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | Cycle 1(1): loaded http://localhost:51256/tests/dromaeo/cssquery-yui.html (next: http://localhost:51256/tests/dromaeo/cssquery-dojo.html) 04:59:13 INFO - PID 3995 | RSS: Main: 278028288 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | __start_tp_report 04:59:13 INFO - PID 3995 | _x_x_mozilla_page_load 04:59:13 INFO - PID 3995 | _x_x_mozilla_page_load_details 04:59:13 INFO - PID 3995 | |i|pagename|runs| 04:59:13 INFO - PID 3995 | |0;dojo.html;2645.563482025466;2668.7598116169543;2700.864956752162;2706.9593956090657;2710.6340644013053;4997.550220480156;5084.440711521739;5134.306868572741;5175.844724658257;5212.08788462019;5131.974340128294;5143.09995750744;5145.382554093505;5162.741862906849;5198.090334191511;4633.745144017078;4661.30080487927;4677.4387073551225;4761.571458568728;4806.375171227735;4714.269288260325;4715.221988371949;4738.502457241992;4760.928586071212;4782.450018247911;4555.06621142665;4592.793324300381;4602.148602508513;4638.1187574360765;4665.510121437268;4695.647826412998;4700.976495117503;4712.151812673717;4753.83361582343;4778.139934811733;4632.976835115836;4679.251319788817;4701.059788042405;4708.74101924394;4745.6440766942615;4997.525235102659;5025.899482010376;5053;5165.173572228426;5181.1969144782615;5483.259759932398;5495.203195536625;5534.363548191929;5534.778608855641;5540.9168862467095;5563.944360556424;5615;5615.578831587606;5618.438156184389;5618.606697531174;5100.209467532539;5195.844124676266;5212.166053431432;5218.712970786567;5271.920921186185;5580.888382232332;5584.944150558524;5721.227634269404;5775.711214439261;5780.88438231233;5377.354717433933;5418.91871621926;5427.782888684487;5429.484199001088;5480.616356855062;5478.945210547923;5537.446255374431;5537.58468114893;5540.750666220029;5597.272354593958;5315.681059136464;5374.328208973879;5386;5446.373667028342;5486.067368547357;5518.061929471999;5518.061929471999;5556.249906262603;5570.582206334541;5571.9442805572235;4698.906021879543;4700.4580701176665;4704.694194877322;4705.082508910798;4747.667663263538;3937.5274967004143;3952.9209415812097;4049.797510124482;4053.2096241232675;4074.9388759168637;4652.395188625457;4670.159371313128;4695.577398034125;4701.6003639690325;4716;5541.473560011793;5571.498565129158;5616.129499927518;5617.045102332613;5621.241132447149;4639;4759.6668233223345;4767.76161191939;4768.713877167381;4773.188557945157;4878.487758785347;5031.77357018935;5053.166227572409;5054.772535235997;5078.4159821619505;5037.57180639635;5095.745212739274;5096.617753668415;5110.48895110486;5124.052050370693;5474.2062400950845;5486.588505862156;5595.384507704231;5648.406917273792;5663.773449061991;5230.2677625131755;5231.372235331784;5233.685978841282;5236;5267.841964741064;5286.497782710637;5330.493603107699;5355.9464405357;5360.597955153301;5389.380221274447;5571.804986825442;5604.635698679573;5608.775648973995;5626.099824028136;5632.380438151882;5442.918356224739;5447.400785913625;5452;5453.809116680897;5454.400015998316;5207.140821764367;5253.23828044939;5268.367795864521;5448.318960129984;5493.313335833021;5221.242919776687;5236.816711415082;5245.029669511153;5251.789928402821;5256.684598924076;5292.444293349143;5352.357717073976;5355.651882627617;5357.410684824588;5357.598180136428;5198.272241886215;5231.581473482189;5236.502532259429;5283.471652834686;5291.417944026077 04:59:13 INFO - PID 3995 | |1;ext.html;728.6502478810171;737.483761367043;1096.5339730614487;1106.468894930433;1116.3190453823167;29984.700152998463;30742.385152296967;30749.692503074963;30770.846145769297;30787;34846.12884677884;35351.469727954034;35438.64561354386;35528.2894342113;35548.64451355492;5048.091343558158;7422.96288518558;7532.322091011808;7592.658330375119;7594.354479869217;5135.12702840518;5220.582353411718;5222.4255331913455;7055.435565154775;7363.9631801840915;7456.217097204797;7521.435892308099;7526.096868375803;7526.887096693554;7540.736074237403;7394;7395.852082958338;7450.329470347695;7455.179930207672;7463.104427468685;5076.949230507685;5190.143626301656;7082.681279342442;7288.489805713602;7350.3384695377135;30656.540151897738;30874;30918;30938.845305773328;30958.0712578623;2687.1535466328046;2694.434168824549;2695.3261684578856;2705;2724.3733941193636;24132.63801042986;24180.758192418027;24209.273721788373;24372.146974855794;25086.874565627237;2961.689022652612;2977.374751302229;2980.4188183304263;2981.075866481376;2990.1627544287635;146748;146862.26568867193;147054;147065;147720;31858.681413186034;32430;42293;42476.57523424788;42492;39111.41332880009;39671.2065758689;39701;39705.60294397019;39713.20573588512;11104.61133860311;11165.776684466266;11169.944150279196;11176.776464470666;11283.210175287815;9265.39774914642;9281.814363712689;9289.581968811419;9302;9305.25557955362;12191.87808121925;12525.749485010248;12534.937325313314;12536.310502922426;12682.239065656091;5132.153194722903;5242.318498595158;5273.894522109613;5275;5307;871.1027641529236;877.9473231606124;879.4320583895042;892.9419587726907;913.141646851957;20481.385558432972;21146;21156;21288.574228515343;21476.78523214779;949.3212353167519;958.2812890332251;983.7786498037884;984.9359791613522;988.5304480371909;10596.89403105959;10865.130789536817;10930.945345273223;11034.172437067253;11109.055730262857;158891;160222;160572.19713901356;161165.19417402838;196819;36815.44776828296;36960.26079478396;36973.81513092417;37064.07339816472;37118;1195.665213740154;1244.6888277930518;1246.2211118051036;1251.8622951475331;1256.4722816417127;19788.703169452183;20688.379348619565;20717.378478645664;22284.66573001439;22307.553848923257;28758.85620571884;28775.280617984718;28800.423991519634;28804;28841;173882.13058934626;174529;174977;175419;176820;26751.331216719736;28703.856480717463;28906.566401503573;29031.56452653254;29287.7071229285;35625.82187089048;35727.46408803818;35800.64199357973;35885.28229435344;37140.257194856495;26533.60199596969;27414.314642134108;27575.03487377932;27579.44841103207;27688;27044.45911081728;29170.854145729136;29668.554971675872;29994;30258;33089.834550827094;34020.1494962618;34251;34256.657433425346;34274.14314642154;24957.376065598506;26670.333241669112;26868;28721.569176462788;30413.695863041972;19404.90297548503;20752.688709669066;21119.472013199178;21120.471988200417;21175.68236476485 04:59:13 INFO - PID 3995 | |2;jquery.html;393.00296727114767;411.1920077048599;418.44764910318383;438.568010509648;442.67021069303377;12632.873671263285;17596.296148154073;17842.999999999985;18645.25418983239;18754.156062977163;15937.123458209793;16354.100524471149;16356.1004144772;16410.589735256624;18176.909115454404;4125.504939407268;5871;5883.382244864292;5887.116932460122;5921.674307913063;5659.943400566003;5815.534757219433;5882.264716910387;5888.205092312526;5903.085021821603;5733.5413166946755;5762.135679648065;5774.335951365605;5780.306363236417;5784.82645520635;5527.226188333637;5764.07774756037;5788.6816225107605;5794.971025144869;5799.65202087874;5202.92195617066;5217.087009773274;5227.085260079471;5229.790808367661;5230.34620672416;20916.895415522824;21349.679754803692;22026.33920982373;22155.667664985038;22204.55590888189;1542.8302886682457;1545.8763298936171;1555.5100143454777;1603.038177093746;1603.3907115296113;13118;13179.86820131796;13664.795028074586;13886.652833679238;14010.649733756636;1728.4555365059966;1731.3247833345006;1735.1324337831084;1741.686496430642;1790.4270633397318;85126;88214;88312.55843720805;89891.55054224753;91014;9747.756306092404;10916.563337466412;10950.671479855617;11149.21955463126;11178.664640060812;13699.794503082461;14580.489682861049;14921.403143874124;14921.552353429415;14952.401903923721;7691.5769632670745;7895.6052197391045;7940.1265860755275;7942.245486678757;7978.242067003625;6884.65576721172;7045.7181712731945;7200.567965922061;7214.49498535108;7380.446466515033;7065.540739851893;7081.468889833283;7101.076860008166;7114.217436082027;7165.140183178052;2047.8156965873143;2053.887036213023;2055.9588808223752;2082.552251265985;2118.2374345235703;831.4720152702956;852.1904191018433;863.2144748279161;873.2271939333735;897.1028971028971;16502.587435314214;16599;17056.48830535086;17078.23147958345;17292.481225563253;863.5811631358786;869.1612593846968;878.3193025405362;883.726044926075;898.4205187653927;9036.548172591476;9466.715998520056;9475.384100033476;9498.572564234626;9516.19112375442;421187;422966;426844;432269;436691;85086.57456712677;85755.5712221435;85812.57093714492;86399.56800215959;86481;1011.600417834951;1031.5925209542338;1056.4242487844144;1059.6873922192972;1061.1934929453516;14231.07498012626;14471.348789304297;14489.333726621573;14548.927255363655;15092.622684432978;39672.40491392574;39844.80077599594;40743.1851362977;42283.36574951317;44011.55988440075;52992.73503632457;53456.465435346705;53522.73238633782;54631;55382.72308638431;5727.284089488814;6428;7714.845703085794;7942.682292708227;8288.668453262044;12564.434600442819;12731;12833.679158021125;12849;12941.676458088623;10042.297039207477;10154.898451015395;10245.180385568987;10455.68632941013;10513.474326283807;9542.66600669001;10019.799604008025;10324.07083362501;10396.58413663445;10795.6221532246;10112.393256404639;10124.898751012395;10245.590176392861;10450.320729152581;10664.57341706354;7179.641017949186;7500.662470188746;7537.886931695919;7762.4178186637355;7880.408969327438;9134.634614615341;9298.209652179641;9552.235821134154;9561.569729362056;9742.610295588098 04:59:13 INFO - PID 3995 | |3;mootools.html;1210.6247063410342;1251.5181655062797;1257.7987521996483;1265.9999999999998;1267.3219827392347;9400.764980875472;9771.706848794529;9784.364016338934;9785.951070244657;9790.951045244772;9540.093691099335;9743.2692548059;9961.800763984716;9965.850512242323;10005.099541041305;2191.791779780918;2233.7602630540046;2234.3743751749453;2237.496563273267;2245.2141750387395;1894.9147288372055;2096.622607930572;2201.427628816511;2248.7301523817164;2268.1494439585153;1919.673655478572;2151.8278537717024;2257.243823319182;2258.017762273408;2261.231181398324;2191.8684878907316;2239.2946221940038;2278.1343089626002;2281.0761641535205;2282.8402011859175;2198.703175071377;2224.9888750556142;2226.421130506064;2227.12028748645;2227.2984010036785;9796.461194634292;9829.90170098297;9855.162311203556;9870.45712485813;10199.13307368867;5321.760520776575;5559.777608895639;5560.666360018412;5562.221289019541;5562.471565201299;3101.085179871943;3109.5957525521762;3329.3674201901563;3357.278185190196;3368.4947257911263;1035.637526865591;1039.7296702857236;1046.785408991155;1082.8484012238293;1102.2835157147838;13636.863631363658;14006.509772158028;14104.576862694135;14109.929450352682;14118.294085295694;4802.903941921142;4875.463698993108;5025.422076461181;5041.974790126026;5062.341895553628;12281.508739650493;12305.753884922251;12343.938280308541;12369.443375048144;12371.319577423148;5696.3733989261145;5697.772089116472;5704.172894930211;5704.429557044263;5710.571707121982;4976.464017871456;5039.748012599355;5126.53861152498;5165.044466773659;5194.428612852583;4115.20988370054;4195.328747400401;4257.382679511454;4274.764887931194;4310.870673879788;1148.1962626161721;1289.3617659258728;1345.6837643153851;1369.2879702554617;1374.3746595299115;582.5688990147261;674.3694645506467;687.3058211206617;700.467644590105;714.7962830593256;5068.797248110034;5303.893922121536;5365.436629153961;5379.569634429236;5557.805476808292;810.4326971120239;814.2549567146114;828.9295409890227;832.646125396704;842.4608250719544;3371.3931492331617;3403.149212696826;3430.2624935638955;3441.8125746617375;3548.1129717570607;12606.180598261084;13041.608751737464;13121.409536570878;13130.474781008843;13137.60587182386;12540.874591254153;12799.808002879965;12941.870581294255;12972.286524241068;12977.740445191232;9295.908420605445;9412;9483.47840868745;9496.90503094974;9501.524923753785;5582.804601839079;5678.176664383559;5692.943070569407;5717.1995920570325;5849.532037436909;10803;11523.481443334902;11531.88468115308;11649.534018639159;11721.589744358907;11336.943315283701;11416.029637480908;11507.712307192387;11521.078313735048;11538;7994.640241189277;8723.214910658071;9007.729768106967;9140.405873618194;9146.451212927246;9259.907400926175;9431;9510.667126650535;9670.854937175807;9811.264155188532;6411.294757576569;7575.053118360205;7618.466707330434;7800.180980997141;7861.567613781297;7706.653200606098;7847.372210223282;7969.760907172794;8055.033395992518;8088.919110809052;6850.177978642595;6969.30306969299;6983.1620205575655;6983.336583024605;6984.650767461708;5050.267711181931;5201.089809283285;5218.24335471347;5405.135178371442;5465.043617366865;5218.139007063944;5227.4249832517635;5273.920891186559;5381.596380271417;5401.594880383908 04:59:13 INFO - PID 3995 | |4;prototype.html;369.7799809113578;387.77509044754044;394.5245978595792;396.4865499178563;401.67865707434044;5219.765110570024;5286.4449232830575;5292.660018784589;5305.708186049766;5306.7346632668405;5186.766595503202;5186.870328241786;5187.870303242421;5189;5201.609879259052;4116.9794151029355;4143;4192.874213773592;4200.600942910419;4212.810423530941;4168.812403441852;4180.686448516358;4182.017225951899;4189.72766770159;4199.60103790141;4141.503019637647;4153.314703073989;4159.3968874513175;4173.770442625654;4176.665866730669;4068.7355321903983;4146.813393397304;4158.459400277975;4164.562720914321;4164.604362585549;3759.473673685686;3761.680257178143;3773.2453509297975;3792.6207379262128;3799.6580307772433;5062.190049592047;5065.645404821664;5089;5102.38771347437;5130.820421285274;4684.1334353144775;4817.253325734517;4836.153673107192;4840.903181936377;4843.903121937541;5475.4524547545325;5495.53287970523;5502.80740174092;5511.53151982082;5512.724363781835;4157.812898419578;4166.437530933345;4180.26845302074;4184.497860256789;4204.096119334357;5183.429822719498;5493.7802487900435;5581.148383227041;5787.942120578825;5828.6502809831545;4166.166766646683;4252.277112890816;4286.764227967492;4290.141971605692;4295.140971805651;4360.149770794728;4373.650107991353;4377.365282034151;4388.758618276025;4390.824367025347;4830.420349558076;4841.104395686809;4845.248986407112;4860.8784780380065;4875.24433712775;5347.331583552056;5490.80782172622;5493.2034854945805;5529.866377392625;5531.778728850882;1497.7154340675236;1505.4580351073605;1515.0379509011636;1518.32434566619;1519.8632123108973;1022.192467950311;1088.8475613414128;1095.742500512379;1104.3373975614568;1104.4919337104861;4824.614030877521;4828.493008234155;4835.95164048362;4836.806527738921;4845.781939812717;5697.658140511582;5713;5734.681023364649;5736.368999410061;5752.223449834302;4722.504137065627;4831.275308703653;4854.95145048545;4856.708597484162;4863.440704318978;2992.925176870596;3018.4566777980176;3022.667506574275;3023.8488075596133;3046.2536678513907;5312.335958005249;5317.9202311965355;5318.494742999486;5355.223492593552;5368.812091576776;5528.695921724344;5737.8565535861935;5823.330317013598;5849.766009359663;5878.617889837147;5140.306058682105;5199.272101905736;5245.186996015624;5277.918026804496;5306.204069389468;5631.943680563142;5634.549236061023;5644.350899646429;5653.632513886584;5694.800681976111;5390.353157621111;5595.440455954372;5597.80407685729;5606.495415412633;5634.675851175052;5344.599155063308;5347.51872331492;5349.251104845403;5351.892962140658;5358.276632654698;4001.379786133154;4007.178528401729;4019.3167161583187;4029.597040295947;4040.2323558523785;5515.972420137874;5733.283339582552;5733.885322293614;5734.913976290442;5752.33848107473;5526.309211348582;5533.446655334435;5535.7785688572;5536.557075434036;5554.2779438672715;5400.162974738922;5499.642523236137;5518.089515229942;5518.944810551843;5544.63959842609;5442.836714898559;5459.918101228405;5462.9726851365485;5473;5477.370102438271;5556.277424062261;5581.386047534849;5582.469665381782;5588.105903055492;5588.2455868458865;5304.28392167068;5407.459254074561;5417.295751552273;5418.945810541844;5432.239486471818 04:59:13 INFO - PID 3995 | |5;yui.html;689.6103701408707;691.7094820175525;691.9896201556976;697.560536861777;723.9420846332291;12707.428165732541;13094;13838.377273022714;13957.581272561827;14293.285335733197;13429.328533573307;13649.795253071212;13860.237686927216;13871.722565548684;13891.652708682288;841.2386789955085;855.3542075732813;855.6748435594466;856.0540602634093;856.3235044314983;841.7138173021171;857.691231156783;881.5151666583386;887.2901678657048;887.529609307065;921.5438358012793;923.1322556796623;923.5982347678781;924.0159230419615;924.0205382294789;904.9638014479413;909.5043201455222;910.6038873090226;912.8083102548472;913.4108500017452;668.9632070236136;671.6540981394586;673.3872176319575;673.5722816011819;674.1404708996021;14308.713825723531;14368.425262989467;14472.131672099707;14475.638109047253;15071.11611048619;12871.356432178354;13201.27392993385;13366.928126140463;13471.12437691547;13807.930960345235;10095.116297658087;10520.210984176218;10667.733306667318;10720.517576709066;10869.29349592274;2722.823016503921;2738.5070687276284;2760.7101254368404;2795.4269374778123;2799.076304819405;61635.1761254894;62224.37775622231;63218.367816321705;64732.20311867233;64794.3520564793;10431.269811113149;10560.260781745357;10576.682699519026;10695.251332406657;11205.383703896365;19408.22367105328;19614.411567652707;19686.40940771779;19712.802871971384;19837.702434463496;6162.753489860355;6255.093011513396;6263.937360626426;6267.561270711098;6267.811965641038;3138.905832825019;3140.5917230759856;3182.268078341994;3209.646938836726;3213.453712868818;3773.396256598931;3841.385378339508;3850.961490385116;3862.9227415452096;4041.191761647682;748.547128986966;750.2010358967682;753.1038064702985;770.1259070954507;770.7918861907253;499.42566049043165;516.8010216298152;522.9947700523022;524.3890867139739;527.082875796112;7845.37237021037;8404.537750423664;8453.704254096658;8604.698835540852;8794.516301603346;503.6575128912375;521.2311840035917;521.4524749013597;525.6635753118007;539.3689383421406;3731.421629647409;3822.2546603412065;3981.3828856526707;3997.140614767839;4009.205366151898;59191.112133318034;61462.078068829;62627.68686156631;63734.68132659307;64217.678911605144;53051.469485305424;53817.461825381244;53874.46125538773;54784.72607636936;55074.17388739172;48120.51879481161;48435.51564484381;48867;49253.50746492489;49824.75087624539;7768.53388796674;8081;8088.797780055545;8159.877601836096;8272.875906861522;26565.33586660349;26707.19878403651;26834.865825671528;27090.593641095005;27669.169924902286;32470.35059298848;32793.67206327906;32805.50791738078;33587;33660.3267934635;10077.344972576759;10182;10200.3369780964;10388.37669739818;10403.271770975958;10066.798664026825;11157.721056973316;11338.60314888975;11951.402429878644;11956.282623042645;11127.499262533367;11659.76680466369;11669.416529173337;11964;12371.75256494847;12194.57318993831;12226.816597751218;12228.510859565873;12527.310997895203;12949.093563450468;11517.712057198638;11657.708557286136;11711.88288117108;11766.294022358687;12020.278783273032;8783.648654053766;8818.691345802867;8888.822223555622;9030.135139823;9214.216791572791;9274.536273186179;9428.15146636806;9474.147326740627;9667.371620844622;10050.547725352231 04:59:13 INFO - PID 3995 | __end_tp_report 04:59:13 INFO - PID 3995 | __start_cc_report 04:59:13 INFO - PID 3995 | _x_x_mozilla_cycle_collect,2423 04:59:13 INFO - PID 3995 | __end_cc_report 04:59:13 INFO - PID 3995 | __startTimestamp1490702353622__endTimestamp 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | ------- Summary: start ------- 04:59:13 INFO - PID 3995 | Number of tests: 6 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | [#0] dojo.html Cycles:165 Average:5081.87 Median:5230.27 stddev:574.28 (11.0%) stddev-sans-first:543.31 04:59:13 INFO - PID 3995 | Values: 2645.6 2668.8 2700.9 2707.0 2710.6 4997.6 5084.4 5134.3 5175.8 5212.1 5132.0 5143.1 5145.4 5162.7 5198.1 4633.7 4661.3 4677.4 4761.6 4806.4 4714.3 4715.2 4738.5 4760.9 4782.5 4555.1 4592.8 4602.1 4638.1 4665.5 4695.6 4701.0 4712.2 4753.8 4778.1 4633.0 4679.3 4701.1 4708.7 4745.6 4997.5 5025.9 5053.0 5165.2 5181.2 5483.3 5495.2 5534.4 5534.8 5540.9 5563.9 5615.0 5615.6 5618.4 5618.6 5100.2 5195.8 5212.2 5218.7 5271.9 5580.9 5584.9 5721.2 5775.7 5780.9 5377.4 5418.9 5427.8 5429.5 5480.6 5478.9 5537.4 5537.6 5540.8 5597.3 5315.7 5374.3 5386.0 5446.4 5486.1 5518.1 5518.1 5556.2 5570.6 5571.9 4698.9 4700.5 4704.7 4705.1 4747.7 3937.5 3952.9 4049.8 4053.2 4074.9 4652.4 4670.2 4695.6 4701.6 4716.0 5541.5 5571.5 5616.1 5617.0 5621.2 4639.0 4759.7 4767.8 4768.7 4773.2 4878.5 5031.8 5053.2 5054.8 5078.4 5037.6 5095.7 5096.6 5110.5 5124.1 5474.2 5486.6 5595.4 5648.4 5663.8 5230.3 5231.4 5233.7 5236.0 5267.8 5286.5 5330.5 5355.9 5360.6 5389.4 5571.8 5604.6 5608.8 5626.1 5632.4 5442.9 5447.4 5452.0 5453.8 5454.4 5207.1 5253.2 5268.4 5448.3 5493.3 5221.2 5236.8 5245.0 5251.8 5256.7 5292.4 5352.4 5355.7 5357.4 5357.6 5198.3 5231.6 5236.5 5283.5 5291.4 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | [#1] ext.html Cycles:180 Average:30375.38 Median:29989.35 stddev:42313.30 (141.1%) stddev-sans-first:42373.44 04:59:13 INFO - PID 3995 | Values: 728.7 737.5 1096.5 1106.5 1116.3 29984.7 30742.4 30749.7 30770.8 30787.0 34846.1 35351.5 35438.6 35528.3 35548.6 5048.1 7423.0 7532.3 7592.7 7594.4 5135.1 5220.6 5222.4 7055.4 7364.0 7456.2 7521.4 7526.1 7526.9 7540.7 7394.0 7395.9 7450.3 7455.2 7463.1 5076.9 5190.1 7082.7 7288.5 7350.3 30656.5 30874.0 30918.0 30938.8 30958.1 2687.2 2694.4 2695.3 2705.0 2724.4 24132.6 24180.8 24209.3 24372.1 25086.9 2961.7 2977.4 2980.4 2981.1 2990.2 146748.0 146862.3 147054.0 147065.0 147720.0 31858.7 32430.0 42293.0 42476.6 42492.0 39111.4 39671.2 39701.0 39705.6 39713.2 11104.6 11165.8 11169.9 11176.8 11283.2 9265.4 9281.8 9289.6 9302.0 9305.3 12191.9 12525.7 12534.9 12536.3 12682.2 5132.2 5242.3 5273.9 5275.0 5307.0 871.1 877.9 879.4 892.9 913.1 20481.4 21146.0 21156.0 21288.6 21476.8 949.3 958.3 983.8 984.9 988.5 10596.9 10865.1 10930.9 11034.2 11109.1 158891.0 160222.0 160572.2 161165.2 196819.0 36815.4 36960.3 36973.8 37064.1 37118.0 1195.7 1244.7 1246.2 1251.9 1256.5 19788.7 20688.4 20717.4 22284.7 22307.6 28758.9 28775.3 28800.4 28804.0 28841.0 173882.1 174529.0 174977.0 175419.0 176820.0 26751.3 28703.9 28906.6 29031.6 29287.7 35625.8 35727.5 35800.6 35885.3 37140.3 26533.6 27414.3 27575.0 27579.4 27688.0 27044.5 29170.9 29668.6 29994.0 30258.0 33089.8 34020.1 34251.0 34256.7 34274.1 24957.4 26670.3 26868.0 28721.6 30413.7 19404.9 20752.7 21119.5 21120.5 21175.7 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | [#2] jquery.html Cycles:180 Average:26658.73 Median:218564.78 stddev:71032.64 (32.5%) stddev-sans-first:71204.53 04:59:13 INFO - PID 3995 | Values: 393.0 411.2 418.4 438.6 442.7 12632.9 17596.3 17843.0 18645.3 18754.2 15937.1 16354.1 16356.1 16410.6 18176.9 4125.5 5871.0 5883.4 5887.1 5921.7 5659.9 5815.5 5882.3 5888.2 5903.1 5733.5 5762.1 5774.3 5780.3 5784.8 5527.2 5764.1 5788.7 5795.0 5799.7 5202.9 5217.1 5227.1 5229.8 5230.3 20916.9 21349.7 22026.3 22155.7 22204.6 1542.8 1545.9 1555.5 1603.0 1603.4 13118.0 13179.9 13664.8 13886.7 14010.6 1728.5 1731.3 1735.1 1741.7 1790.4 85126.0 88214.0 88312.6 89891.6 91014.0 9747.8 10916.6 10950.7 11149.2 11178.7 13699.8 14580.5 14921.4 14921.6 14952.4 7691.6 7895.6 7940.1 7942.2 7978.2 6884.7 7045.7 7200.6 7214.5 7380.4 7065.5 7081.5 7101.1 7114.2 7165.1 2047.8 2053.9 2056.0 2082.6 2118.2 831.5 852.2 863.2 873.2 897.1 16502.6 16599.0 17056.5 17078.2 17292.5 863.6 869.2 878.3 883.7 898.4 9036.5 9466.7 9475.4 9498.6 9516.2 421187.0 422966.0 426844.0 432269.0 436691.0 85086.6 85755.6 85812.6 86399.6 86481.0 1011.6 1031.6 1056.4 1059.7 1061.2 14231.1 14471.3 14489.3 14548.9 15092.6 39672.4 39844.8 40743.2 42283.4 44011.6 52992.7 53456.5 53522.7 54631.0 55382.7 5727.3 6428.0 7714.8 7942.7 8288.7 12564.4 12731.0 12833.7 12849.0 12941.7 10042.3 10154.9 10245.2 10455.7 10513.5 9542.7 10019.8 10324.1 10396.6 10795.6 10112.4 10124.9 10245.6 10450.3 10664.6 7179.6 7500.7 7537.9 7762.4 7880.4 9134.6 9298.2 9552.2 9561.6 9742.6 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | [#3] mootools.html Cycles:180 Average:6239.20 Median:5032.59 stddev:3949.58 (78.5%) stddev-sans-first:3942.58 04:59:13 INFO - PID 3995 | Values: 1210.6 1251.5 1257.8 1266.0 1267.3 9400.8 9771.7 9784.4 9786.0 9791.0 9540.1 9743.3 9961.8 9965.9 10005.1 2191.8 2233.8 2234.4 2237.5 2245.2 1894.9 2096.6 2201.4 2248.7 2268.1 1919.7 2151.8 2257.2 2258.0 2261.2 2191.9 2239.3 2278.1 2281.1 2282.8 2198.7 2225.0 2226.4 2227.1 2227.3 9796.5 9829.9 9855.2 9870.5 10199.1 5321.8 5559.8 5560.7 5562.2 5562.5 3101.1 3109.6 3329.4 3357.3 3368.5 1035.6 1039.7 1046.8 1082.8 1102.3 13636.9 14006.5 14104.6 14109.9 14118.3 4802.9 4875.5 5025.4 5042.0 5062.3 12281.5 12305.8 12343.9 12369.4 12371.3 5696.4 5697.8 5704.2 5704.4 5710.6 4976.5 5039.7 5126.5 5165.0 5194.4 4115.2 4195.3 4257.4 4274.8 4310.9 1148.2 1289.4 1345.7 1369.3 1374.4 582.6 674.4 687.3 700.5 714.8 5068.8 5303.9 5365.4 5379.6 5557.8 810.4 814.3 828.9 832.6 842.5 3371.4 3403.1 3430.3 3441.8 3548.1 12606.2 13041.6 13121.4 13130.5 13137.6 12540.9 12799.8 12941.9 12972.3 12977.7 9295.9 9412.0 9483.5 9496.9 9501.5 5582.8 5678.2 5692.9 5717.2 5849.5 10803.0 11523.5 11531.9 11649.5 11721.6 11336.9 11416.0 11507.7 11521.1 11538.0 7994.6 8723.2 9007.7 9140.4 9146.5 9259.9 9431.0 9510.7 9670.9 9811.3 6411.3 7575.1 7618.5 7800.2 7861.6 7706.7 7847.4 7969.8 8055.0 8088.9 6850.2 6969.3 6983.2 6983.3 6984.7 5050.3 5201.1 5218.2 5405.1 5465.0 5218.1 5227.4 5273.9 5381.6 5401.6 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | [#4] prototype.html Cycles:180 Average:4624.98 Median:5161.87 stddev:1289.35 (25.0%) stddev-sans-first:1252.79 04:59:13 INFO - PID 3995 | Values: 369.8 387.8 394.5 396.5 401.7 5219.8 5286.4 5292.7 5305.7 5306.7 5186.8 5186.9 5187.9 5189.0 5201.6 4117.0 4143.0 4192.9 4200.6 4212.8 4168.8 4180.7 4182.0 4189.7 4199.6 4141.5 4153.3 4159.4 4173.8 4176.7 4068.7 4146.8 4158.5 4164.6 4164.6 3759.5 3761.7 3773.2 3792.6 3799.7 5062.2 5065.6 5089.0 5102.4 5130.8 4684.1 4817.3 4836.2 4840.9 4843.9 5475.5 5495.5 5502.8 5511.5 5512.7 4157.8 4166.4 4180.3 4184.5 4204.1 5183.4 5493.8 5581.1 5787.9 5828.7 4166.2 4252.3 4286.8 4290.1 4295.1 4360.1 4373.7 4377.4 4388.8 4390.8 4830.4 4841.1 4845.2 4860.9 4875.2 5347.3 5490.8 5493.2 5529.9 5531.8 1497.7 1505.5 1515.0 1518.3 1519.9 1022.2 1088.8 1095.7 1104.3 1104.5 4824.6 4828.5 4836.0 4836.8 4845.8 5697.7 5713.0 5734.7 5736.4 5752.2 4722.5 4831.3 4855.0 4856.7 4863.4 2992.9 3018.5 3022.7 3023.8 3046.3 5312.3 5317.9 5318.5 5355.2 5368.8 5528.7 5737.9 5823.3 5849.8 5878.6 5140.3 5199.3 5245.2 5277.9 5306.2 5631.9 5634.5 5644.4 5653.6 5694.8 5390.4 5595.4 5597.8 5606.5 5634.7 5344.6 5347.5 5349.3 5351.9 5358.3 4001.4 4007.2 4019.3 4029.6 4040.2 5516.0 5733.3 5733.9 5734.9 5752.3 5526.3 5533.4 5535.8 5536.6 5554.3 5400.2 5499.6 5518.1 5518.9 5544.6 5442.8 5459.9 5463.0 5473.0 5477.4 5556.3 5581.4 5582.5 5588.1 5588.2 5304.3 5407.5 5417.3 5418.9 5432.2 04:59:13 INFO - PID 3995 | 04:59:13 INFO - PID 3995 | [#5] yui.html Cycles:180 Average:13997.90 Median:26080.86 stddev:17058.29 (65.4%) stddev-sans-first:17076.87 04:59:13 INFO - PID 3995 | Values: 689.6 691.7 692.0 697.6 723.9 12707.4 13094.0 13838.4 13957.6 14293.3 13429.3 13649.8 13860.2 13871.7 13891.7 841.2 855.4 855.7 856.1 856.3 841.7 857.7 881.5 887.3 887.5 921.5 923.1 923.6 924.0 924.0 905.0 909.5 910.6 912.8 913.4 669.0 671.7 673.4 673.6 674.1 14308.7 14368.4 14472.1 14475.6 15071.1 12871.4 13201.3 13366.9 13471.1 13807.9 10095.1 10520.2 10667.7 10720.5 10869.3 2722.8 2738.5 2760.7 2795.4 2799.1 61635.2 62224.4 63218.4 64732.2 64794.4 10431.3 10560.3 10576.7 10695.3 11205.4 19408.2 19614.4 19686.4 19712.8 19837.7 6162.8 6255.1 6263.9 6267.6 6267.8 3138.9 3140.6 3182.3 3209.6 3213.5 3773.4 3841.4 3851.0 3862.9 4041.2 748.5 750.2 753.1 770.1 770.8 499.4 516.8 523.0 524.4 527.1 7845.4 8404.5 8453.7 8604.7 8794.5 503.7 521.2 521.5 525.7 539.4 3731.4 3822.3 3981.4 3997.1 4009.2 59191.1 61462.1 62627.7 63734.7 64217.7 53051.5 53817.5 53874.5 54784.7 55074.2 48120.5 48435.5 48867.0 49253.5 49824.8 7768.5 8081.0 8088.8 8159.9 8272.9 26565.3 26707.2 26834.9 27090.6 27669.2 32470.4 32793.7 32805.5 33587.0 33660.3 10077.3 10182.0 10200.3 10388.4 10403.3 10066.8 11157.7 11338.6 11951.4 11956.3 11127.5 11659.8 11669.4 11964.0 12371.8 12194.6 12226.8 12228.5 12527.3 12949.1 11517.7 11657.7 11711.9 11766.3 12020.3 8783.6 8818.7 8888.8 9030.1 9214.2 9274.5 9428.2 9474.1 9667.4 10050.5 04:59:13 INFO - PID 3995 | -------- Summary: end -------- 04:59:13 INFO - PID 3995 | 04:59:14 INFO - TEST-INFO | 3995: exit 0 04:59:14 INFO - TEST-OK | dromaeo_css | took 1125079ms 04:59:14 INFO - TEST-START | kraken 04:59:14 INFO - operating with platform_type : linux_ 04:59:14 INFO - Initialising browser for kraken test... 04:59:14 INFO - TEST-INFO | started process 4319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfpFvWm/profile http://localhost:51256/getInfo.html) 04:59:18 INFO - PID 4319 | __metrics Screen width/height:1600/1200 04:59:18 INFO - PID 4319 | colorDepth:24 04:59:18 INFO - PID 4319 | Browser inner width/height: 1024/697 04:59:18 INFO - PID 4319 | __metrics 04:59:19 ERROR - PID 4319 | 1490702359722 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:59:19 INFO - PID 4319 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:59:19 INFO - PID 4319 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:59:20 INFO - TEST-INFO | 4319: exit 0 04:59:20 INFO - Browser initialized. 04:59:20 INFO - Running cycle 1/1 for kraken test... 04:59:20 INFO - TEST-INFO | started process 4409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfpFvWm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:59:33 INFO - PID 4409 | RSS: Main: 149712896 04:59:33 INFO - PID 4409 | 04:59:35 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51256/tests/kraken/driver.html?testName=audio-beat-detection) 04:59:36 INFO - PID 4409 | RSS: Main: 204922880 04:59:36 INFO - PID 4409 | 04:59:39 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51256/tests/kraken/driver.html?testName=audio-dft) 04:59:40 INFO - PID 4409 | RSS: Main: 184078336 04:59:40 INFO - PID 4409 | 04:59:43 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51256/tests/kraken/driver.html?testName=audio-fft) 04:59:43 INFO - PID 4409 | RSS: Main: 266960896 04:59:43 INFO - PID 4409 | 04:59:46 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51256/tests/kraken/driver.html?testName=audio-oscillator) 04:59:46 INFO - PID 4409 | RSS: Main: 176328704 04:59:46 INFO - PID 4409 | 04:59:49 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51256/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:59:49 INFO - PID 4409 | RSS: Main: 180461568 04:59:49 INFO - PID 4409 | 04:59:52 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51256/tests/kraken/driver.html?testName=imaging-darkroom) 04:59:53 INFO - PID 4409 | RSS: Main: 219631616 04:59:53 INFO - PID 4409 | 04:59:57 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51256/tests/kraken/driver.html?testName=imaging-desaturate) 04:59:57 INFO - PID 4409 | RSS: Main: 214814720 04:59:57 INFO - PID 4409 | 05:00:00 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51256/tests/kraken/driver.html?testName=json-parse-financial) 05:00:01 INFO - PID 4409 | RSS: Main: 216625152 05:00:01 INFO - PID 4409 | 05:00:03 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51256/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:00:03 INFO - PID 4409 | RSS: Main: 181248000 05:00:03 INFO - PID 4409 | 05:00:05 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:00:06 INFO - PID 4409 | RSS: Main: 186847232 05:00:06 INFO - PID 4409 | 05:00:08 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:00:08 INFO - PID 4409 | RSS: Main: 197275648 05:00:08 INFO - PID 4409 | 05:00:11 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:00:12 INFO - PID 4409 | RSS: Main: 200794112 05:00:12 INFO - PID 4409 | 05:00:15 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:00:16 INFO - PID 4409 | RSS: Main: 202326016 05:00:16 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | Cycle 1(1): loaded http://localhost:51256/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51256/tests/kraken/driver.html?testName=ai-astar) 05:00:18 INFO - PID 4409 | RSS: Main: 272019456 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | __start_tp_report 05:00:18 INFO - PID 4409 | _x_x_mozilla_page_load 05:00:18 INFO - PID 4409 | _x_x_mozilla_page_load_details 05:00:18 INFO - PID 4409 | |i|pagename|runs| 05:00:18 INFO - PID 4409 | |0;ai-astar;103;110;111;113;113;110;112;113;111;111 05:00:18 INFO - PID 4409 | |1;audio-beat-detection;158;130;130;166;125;128;125;126;147;133 05:00:18 INFO - PID 4409 | |2;audio-dft;164;167;167;168;165;165;164;163;165;164 05:00:18 INFO - PID 4409 | |3;audio-fft;76;75;76;110;75;77;76;77;94;77 05:00:18 INFO - PID 4409 | |4;audio-oscillator;86;85;110;86;83;85;110;84;83;108 05:00:18 INFO - PID 4409 | |5;imaging-gaussian-blur;115;115;115;115;123;115;114;114;114;114 05:00:18 INFO - PID 4409 | |6;imaging-darkroom;150;151;151;152;151;151;151;151;151;151 05:00:18 INFO - PID 4409 | |7;imaging-desaturate;95;95;96;94;95;95;95;94;95;95 05:00:18 INFO - PID 4409 | |8;json-parse-financial;64;64;65;64;65;66;64;67;67;66 05:00:18 INFO - PID 4409 | |9;json-stringify-tinderbox;55;56;55;56;56;55;56;56;56;56 05:00:18 INFO - PID 4409 | |10;stanford-crypto-aes;85;85;90;84;84;93;91;91;83;85 05:00:18 INFO - PID 4409 | |11;stanford-crypto-ccm;136;142;145;136;132;143;154;133;215;141 05:00:18 INFO - PID 4409 | |12;stanford-crypto-pbkdf2;192;198;197;196;201;200;204;202;196;194 05:00:18 INFO - PID 4409 | |13;stanford-crypto-sha256-iterative;72;75;72;72;71;76;72;71;69;71 05:00:18 INFO - PID 4409 | __end_tp_report 05:00:18 INFO - PID 4409 | __start_cc_report 05:00:18 INFO - PID 4409 | _x_x_mozilla_cycle_collect,2047 05:00:18 INFO - PID 4409 | __end_cc_report 05:00:18 INFO - PID 4409 | __startTimestamp1490702418664__endTimestamp 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | ------- Summary: start ------- 05:00:18 INFO - PID 4409 | Number of tests: 14 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.50 stddev:2.95 (2.6%) stddev-sans-first:1.24 05:00:18 INFO - PID 4409 | Values: 103.0 110.0 111.0 113.0 113.0 110.0 112.0 113.0 111.0 111.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:131.50 stddev:14.85 (11.3%) stddev-sans-first:13.63 05:00:18 INFO - PID 4409 | Values: 158.0 130.0 130.0 166.0 125.0 128.0 125.0 126.0 147.0 133.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#2] audio-dft Cycles:10 Average:165.20 Median:165.00 stddev:1.62 (1.0%) stddev-sans-first:1.66 05:00:18 INFO - PID 4409 | Values: 164.0 167.0 167.0 168.0 165.0 165.0 164.0 163.0 165.0 164.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.50 stddev:11.57 (15.1%) stddev-sans-first:12.11 05:00:18 INFO - PID 4409 | Values: 76.0 75.0 76.0 110.0 75.0 77.0 76.0 77.0 94.0 77.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:84.50 stddev:12.02 (14.2%) stddev-sans-first:12.55 05:00:18 INFO - PID 4409 | Values: 86.0 85.0 110.0 86.0 83.0 85.0 110.0 84.0 83.0 108.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#5] imaging-gaussian-blur Cycles:10 Average:115.40 Median:115.00 stddev:2.72 (2.4%) stddev-sans-first:2.88 05:00:18 INFO - PID 4409 | Values: 115.0 115.0 115.0 115.0 123.0 115.0 114.0 114.0 114.0 114.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:151.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 05:00:18 INFO - PID 4409 | Values: 150.0 151.0 151.0 152.0 151.0 151.0 151.0 151.0 151.0 151.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:00:18 INFO - PID 4409 | Values: 95.0 95.0 96.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.50 stddev:1.23 (1.9%) stddev-sans-first:1.22 05:00:18 INFO - PID 4409 | Values: 64.0 64.0 65.0 64.0 65.0 66.0 64.0 67.0 67.0 66.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#9] json-stringify-tinderbox Cycles:10 Average:55.70 Median:56.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 05:00:18 INFO - PID 4409 | Values: 55.0 56.0 55.0 56.0 56.0 55.0 56.0 56.0 56.0 56.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.50 stddev:3.70 (4.2%) stddev-sans-first:3.84 05:00:18 INFO - PID 4409 | Values: 85.0 85.0 90.0 84.0 84.0 93.0 91.0 91.0 83.0 85.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:142.50 stddev:24.52 (17.2%) stddev-sans-first:25.64 05:00:18 INFO - PID 4409 | Values: 136.0 142.0 145.0 136.0 132.0 143.0 154.0 133.0 215.0 141.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.00 Median:199.00 stddev:3.74 (1.9%) stddev-sans-first:3.28 05:00:18 INFO - PID 4409 | Values: 192.0 198.0 197.0 196.0 201.0 200.0 204.0 202.0 196.0 194.0 05:00:18 INFO - PID 4409 | 05:00:18 INFO - PID 4409 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.00 stddev:2.02 (2.8%) stddev-sans-first:2.15 05:00:18 INFO - PID 4409 | Values: 72.0 75.0 72.0 72.0 71.0 76.0 72.0 71.0 69.0 71.0 05:00:18 INFO - PID 4409 | -------- Summary: end -------- 05:00:18 INFO - PID 4409 | 05:00:19 INFO - TEST-INFO | 4409: exit 0 05:00:19 INFO - TEST-OK | kraken | took 65349ms 05:00:19 INFO - SUITE-END | took 1190s 05:00:20 INFO - Completed test suite (00:19:50) 05:00:20 INFO - javascript benchmark 05:00:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6542.223127602117, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2645.563482025466, 2668.7598116169543, 2700.864956752162, 2706.9593956090657, 2710.6340644013053, 4997.550220480156, 5084.440711521739, 5134.306868572741, 5175.844724658257, 5212.08788462019, 5131.974340128294, 5143.09995750744, 5145.382554093505, 5162.741862906849, 5198.090334191511, 4633.745144017078, 4661.30080487927, 4677.4387073551225, 4761.571458568728, 4806.375171227735, 4714.269288260325, 4715.221988371949, 4738.502457241992, 4760.928586071212, 4782.450018247911, 4555.06621142665, 4592.793324300381, 4602.148602508513, 4638.1187574360765, 4665.510121437268, 4695.647826412998, 4700.976495117503, 4712.151812673717, 4753.83361582343, 4778.139934811733, 4632.976835115836, 4679.251319788817, 4701.059788042405, 4708.74101924394, 4745.6440766942615, 4997.525235102659, 5025.899482010376, 5053.0, 5165.173572228426, 5181.1969144782615, 5483.259759932398, 5495.203195536625, 5534.363548191929, 5534.778608855641, 5540.9168862467095, 5563.944360556424, 5615.0, 5615.578831587606, 5618.438156184389, 5618.606697531174, 5100.209467532539, 5195.844124676266, 5212.166053431432, 5218.712970786567, 5271.920921186185, 5580.888382232332, 5584.944150558524, 5721.227634269404, 5775.711214439261, 5780.88438231233, 5377.354717433933, 5418.91871621926, 5427.782888684487, 5429.484199001088, 5480.616356855062, 5478.945210547923, 5537.446255374431, 5537.58468114893, 5540.750666220029, 5597.272354593958, 5315.681059136464, 5374.328208973879, 5386.0, 5446.373667028342, 5486.067368547357, 5518.061929471999, 5518.061929471999, 5556.249906262603, 5570.582206334541, 5571.9442805572235, 4698.906021879543, 4700.4580701176665, 4704.694194877322, 4705.082508910798, 4747.667663263538, 3937.5274967004143, 3952.9209415812097, 4049.797510124482, 4053.2096241232675, 4074.9388759168637, 4652.395188625457, 4670.159371313128, 4695.577398034125, 4701.6003639690325, 4716.0, 5541.473560011793, 5571.498565129158, 5616.129499927518, 5617.045102332613, 5621.241132447149, 4639.0, 4759.6668233223345, 4767.76161191939, 4768.713877167381, 4773.188557945157, 4878.487758785347, 5031.77357018935, 5053.166227572409, 5054.772535235997, 5078.4159821619505, 5037.57180639635, 5095.745212739274, 5096.617753668415, 5110.48895110486, 5124.052050370693, 5474.2062400950845, 5486.588505862156, 5595.384507704231, 5648.406917273792, 5663.773449061991, 5230.2677625131755, 5231.372235331784, 5233.685978841282, 5236.0, 5267.841964741064, 5286.497782710637, 5330.493603107699, 5355.9464405357, 5360.597955153301, 5389.380221274447, 5571.804986825442, 5604.635698679573, 5608.775648973995, 5626.099824028136, 5632.380438151882, 5442.918356224739, 5447.400785913625, 5452.0, 5453.809116680897, 5454.400015998316, 5207.140821764367, 5253.23828044939, 5268.367795864521, 5448.318960129984, 5493.313335833021, 5221.242919776687, 5236.816711415082, 5245.029669511153, 5251.789928402821, 5256.684598924076, 5292.444293349143, 5352.357717073976, 5355.651882627617, 5357.410684824588, 5357.598180136428, 5198.272241886215, 5231.581473482189, 5236.502532259429, 5283.471652834686, 5291.417944026077], "value": 5041.246773642797, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.6502478810171, 737.483761367043, 1096.5339730614487, 1106.468894930433, 1116.3190453823167, 29984.700152998463, 30742.385152296967, 30749.692503074963, 30770.846145769297, 30787.0, 34846.12884677884, 35351.469727954034, 35438.64561354386, 35528.2894342113, 35548.64451355492, 5048.091343558158, 7422.96288518558, 7532.322091011808, 7592.658330375119, 7594.354479869217, 5135.12702840518, 5220.582353411718, 5222.4255331913455, 7055.435565154775, 7363.9631801840915, 7456.217097204797, 7521.435892308099, 7526.096868375803, 7526.887096693554, 7540.736074237403, 7394.0, 7395.852082958338, 7450.329470347695, 7455.179930207672, 7463.104427468685, 5076.949230507685, 5190.143626301656, 7082.681279342442, 7288.489805713602, 7350.3384695377135, 30656.540151897738, 30874.0, 30918.0, 30938.845305773328, 30958.0712578623, 2687.1535466328046, 2694.434168824549, 2695.3261684578856, 2705.0, 2724.3733941193636, 24132.63801042986, 24180.758192418027, 24209.273721788373, 24372.146974855794, 25086.874565627237, 2961.689022652612, 2977.374751302229, 2980.4188183304263, 2981.075866481376, 2990.1627544287635, 146748.0, 146862.26568867193, 147054.0, 147065.0, 147720.0, 31858.681413186034, 32430.0, 42293.0, 42476.57523424788, 42492.0, 39111.41332880009, 39671.2065758689, 39701.0, 39705.60294397019, 39713.20573588512, 11104.61133860311, 11165.776684466266, 11169.944150279196, 11176.776464470666, 11283.210175287815, 9265.39774914642, 9281.814363712689, 9289.581968811419, 9302.0, 9305.25557955362, 12191.87808121925, 12525.749485010248, 12534.937325313314, 12536.310502922426, 12682.239065656091, 5132.153194722903, 5242.318498595158, 5273.894522109613, 5275.0, 5307.0, 871.1027641529236, 877.9473231606124, 879.4320583895042, 892.9419587726907, 913.141646851957, 20481.385558432972, 21146.0, 21156.0, 21288.574228515343, 21476.78523214779, 949.3212353167519, 958.2812890332251, 983.7786498037884, 984.9359791613522, 988.5304480371909, 10596.89403105959, 10865.130789536817, 10930.945345273223, 11034.172437067253, 11109.055730262857, 158891.0, 160222.0, 160572.19713901356, 161165.19417402838, 196819.0, 36815.44776828296, 36960.26079478396, 36973.81513092417, 37064.07339816472, 37118.0, 1195.665213740154, 1244.6888277930518, 1246.2211118051036, 1251.8622951475331, 1256.4722816417127, 19788.703169452183, 20688.379348619565, 20717.378478645664, 22284.66573001439, 22307.553848923257, 28758.85620571884, 28775.280617984718, 28800.423991519634, 28804.0, 28841.0, 173882.13058934626, 174529.0, 174977.0, 175419.0, 176820.0, 26751.331216719736, 28703.856480717463, 28906.566401503573, 29031.56452653254, 29287.7071229285, 35625.82187089048, 35727.46408803818, 35800.64199357973, 35885.28229435344, 37140.257194856495, 26533.60199596969, 27414.314642134108, 27575.03487377932, 27579.44841103207, 27688.0, 27044.45911081728, 29170.854145729136, 29668.554971675872, 29994.0, 30258.0, 33089.834550827094, 34020.1494962618, 34251.0, 34256.657433425346, 34274.14314642154, 24957.376065598506, 26670.333241669112, 26868.0, 28721.569176462788, 30413.695863041972, 19404.90297548503, 20752.688709669066, 21119.472013199178, 21120.471988200417, 21175.68236476485], "value": 14282.416502660104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.00296727114767, 411.1920077048599, 418.44764910318383, 438.568010509648, 442.67021069303377, 12632.873671263285, 17596.296148154073, 17842.999999999985, 18645.25418983239, 18754.156062977163, 15937.123458209793, 16354.100524471149, 16356.1004144772, 16410.589735256624, 18176.909115454404, 4125.504939407268, 5871.0, 5883.382244864292, 5887.116932460122, 5921.674307913063, 5659.943400566003, 5815.534757219433, 5882.264716910387, 5888.205092312526, 5903.085021821603, 5733.5413166946755, 5762.135679648065, 5774.335951365605, 5780.306363236417, 5784.82645520635, 5527.226188333637, 5764.07774756037, 5788.6816225107605, 5794.971025144869, 5799.65202087874, 5202.92195617066, 5217.087009773274, 5227.085260079471, 5229.790808367661, 5230.34620672416, 20916.895415522824, 21349.679754803692, 22026.33920982373, 22155.667664985038, 22204.55590888189, 1542.8302886682457, 1545.8763298936171, 1555.5100143454777, 1603.038177093746, 1603.3907115296113, 13118.0, 13179.86820131796, 13664.795028074586, 13886.652833679238, 14010.649733756636, 1728.4555365059966, 1731.3247833345006, 1735.1324337831084, 1741.686496430642, 1790.4270633397318, 85126.0, 88214.0, 88312.55843720805, 89891.55054224753, 91014.0, 9747.756306092404, 10916.563337466412, 10950.671479855617, 11149.21955463126, 11178.664640060812, 13699.794503082461, 14580.489682861049, 14921.403143874124, 14921.552353429415, 14952.401903923721, 7691.5769632670745, 7895.6052197391045, 7940.1265860755275, 7942.245486678757, 7978.242067003625, 6884.65576721172, 7045.7181712731945, 7200.567965922061, 7214.49498535108, 7380.446466515033, 7065.540739851893, 7081.468889833283, 7101.076860008166, 7114.217436082027, 7165.140183178052, 2047.8156965873143, 2053.887036213023, 2055.9588808223752, 2082.552251265985, 2118.2374345235703, 831.4720152702956, 852.1904191018433, 863.2144748279161, 873.2271939333735, 897.1028971028971, 16502.587435314214, 16599.0, 17056.48830535086, 17078.23147958345, 17292.481225563253, 863.5811631358786, 869.1612593846968, 878.3193025405362, 883.726044926075, 898.4205187653927, 9036.548172591476, 9466.715998520056, 9475.384100033476, 9498.572564234626, 9516.19112375442, 421187.0, 422966.0, 426844.0, 432269.0, 436691.0, 85086.57456712677, 85755.5712221435, 85812.57093714492, 86399.56800215959, 86481.0, 1011.600417834951, 1031.5925209542338, 1056.4242487844144, 1059.6873922192972, 1061.1934929453516, 14231.07498012626, 14471.348789304297, 14489.333726621573, 14548.927255363655, 15092.622684432978, 39672.40491392574, 39844.80077599594, 40743.1851362977, 42283.36574951317, 44011.55988440075, 52992.73503632457, 53456.465435346705, 53522.73238633782, 54631.0, 55382.72308638431, 5727.284089488814, 6428.0, 7714.845703085794, 7942.682292708227, 8288.668453262044, 12564.434600442819, 12731.0, 12833.679158021125, 12849.0, 12941.676458088623, 10042.297039207477, 10154.898451015395, 10245.180385568987, 10455.68632941013, 10513.474326283807, 9542.66600669001, 10019.799604008025, 10324.07083362501, 10396.58413663445, 10795.6221532246, 10112.393256404639, 10124.898751012395, 10245.590176392861, 10450.320729152581, 10664.57341706354, 7179.641017949186, 7500.662470188746, 7537.886931695919, 7762.4178186637355, 7880.408969327438, 9134.634614615341, 9298.209652179641, 9552.235821134154, 9561.569729362056, 9742.610295588098], "value": 8704.498369232806, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1210.6247063410342, 1251.5181655062797, 1257.7987521996483, 1265.9999999999998, 1267.3219827392347, 9400.764980875472, 9771.706848794529, 9784.364016338934, 9785.951070244657, 9790.951045244772, 9540.093691099335, 9743.2692548059, 9961.800763984716, 9965.850512242323, 10005.099541041305, 2191.791779780918, 2233.7602630540046, 2234.3743751749453, 2237.496563273267, 2245.2141750387395, 1894.9147288372055, 2096.622607930572, 2201.427628816511, 2248.7301523817164, 2268.1494439585153, 1919.673655478572, 2151.8278537717024, 2257.243823319182, 2258.017762273408, 2261.231181398324, 2191.8684878907316, 2239.2946221940038, 2278.1343089626002, 2281.0761641535205, 2282.8402011859175, 2198.703175071377, 2224.9888750556142, 2226.421130506064, 2227.12028748645, 2227.2984010036785, 9796.461194634292, 9829.90170098297, 9855.162311203556, 9870.45712485813, 10199.13307368867, 5321.760520776575, 5559.777608895639, 5560.666360018412, 5562.221289019541, 5562.471565201299, 3101.085179871943, 3109.5957525521762, 3329.3674201901563, 3357.278185190196, 3368.4947257911263, 1035.637526865591, 1039.7296702857236, 1046.785408991155, 1082.8484012238293, 1102.2835157147838, 13636.863631363658, 14006.509772158028, 14104.576862694135, 14109.929450352682, 14118.294085295694, 4802.903941921142, 4875.463698993108, 5025.422076461181, 5041.974790126026, 5062.341895553628, 12281.508739650493, 12305.753884922251, 12343.938280308541, 12369.443375048144, 12371.319577423148, 5696.3733989261145, 5697.772089116472, 5704.172894930211, 5704.429557044263, 5710.571707121982, 4976.464017871456, 5039.748012599355, 5126.53861152498, 5165.044466773659, 5194.428612852583, 4115.20988370054, 4195.328747400401, 4257.382679511454, 4274.764887931194, 4310.870673879788, 1148.1962626161721, 1289.3617659258728, 1345.6837643153851, 1369.2879702554617, 1374.3746595299115, 582.5688990147261, 674.3694645506467, 687.3058211206617, 700.467644590105, 714.7962830593256, 5068.797248110034, 5303.893922121536, 5365.436629153961, 5379.569634429236, 5557.805476808292, 810.4326971120239, 814.2549567146114, 828.9295409890227, 832.646125396704, 842.4608250719544, 3371.3931492331617, 3403.149212696826, 3430.2624935638955, 3441.8125746617375, 3548.1129717570607, 12606.180598261084, 13041.608751737464, 13121.409536570878, 13130.474781008843, 13137.60587182386, 12540.874591254153, 12799.808002879965, 12941.870581294255, 12972.286524241068, 12977.740445191232, 9295.908420605445, 9412.0, 9483.47840868745, 9496.90503094974, 9501.524923753785, 5582.804601839079, 5678.176664383559, 5692.943070569407, 5717.1995920570325, 5849.532037436909, 10803.0, 11523.481443334902, 11531.88468115308, 11649.534018639159, 11721.589744358907, 11336.943315283701, 11416.029637480908, 11507.712307192387, 11521.078313735048, 11538.0, 7994.640241189277, 8723.214910658071, 9007.729768106967, 9140.405873618194, 9146.451212927246, 9259.907400926175, 9431.0, 9510.667126650535, 9670.854937175807, 9811.264155188532, 6411.294757576569, 7575.053118360205, 7618.466707330434, 7800.180980997141, 7861.567613781297, 7706.653200606098, 7847.372210223282, 7969.760907172794, 8055.033395992518, 8088.919110809052, 6850.177978642595, 6969.30306969299, 6983.1620205575655, 6983.336583024605, 6984.650767461708, 5050.267711181931, 5201.089809283285, 5218.24335471347, 5405.135178371442, 5465.043617366865, 5218.139007063944, 5227.4249832517635, 5273.920891186559, 5381.596380271417, 5401.594880383908], "value": 4722.990920335373, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.7799809113578, 387.77509044754044, 394.5245978595792, 396.4865499178563, 401.67865707434044, 5219.765110570024, 5286.4449232830575, 5292.660018784589, 5305.708186049766, 5306.7346632668405, 5186.766595503202, 5186.870328241786, 5187.870303242421, 5189.0, 5201.609879259052, 4116.9794151029355, 4143.0, 4192.874213773592, 4200.600942910419, 4212.810423530941, 4168.812403441852, 4180.686448516358, 4182.017225951899, 4189.72766770159, 4199.60103790141, 4141.503019637647, 4153.314703073989, 4159.3968874513175, 4173.770442625654, 4176.665866730669, 4068.7355321903983, 4146.813393397304, 4158.459400277975, 4164.562720914321, 4164.604362585549, 3759.473673685686, 3761.680257178143, 3773.2453509297975, 3792.6207379262128, 3799.6580307772433, 5062.190049592047, 5065.645404821664, 5089.0, 5102.38771347437, 5130.820421285274, 4684.1334353144775, 4817.253325734517, 4836.153673107192, 4840.903181936377, 4843.903121937541, 5475.4524547545325, 5495.53287970523, 5502.80740174092, 5511.53151982082, 5512.724363781835, 4157.812898419578, 4166.437530933345, 4180.26845302074, 4184.497860256789, 4204.096119334357, 5183.429822719498, 5493.7802487900435, 5581.148383227041, 5787.942120578825, 5828.6502809831545, 4166.166766646683, 4252.277112890816, 4286.764227967492, 4290.141971605692, 4295.140971805651, 4360.149770794728, 4373.650107991353, 4377.365282034151, 4388.758618276025, 4390.824367025347, 4830.420349558076, 4841.104395686809, 4845.248986407112, 4860.8784780380065, 4875.24433712775, 5347.331583552056, 5490.80782172622, 5493.2034854945805, 5529.866377392625, 5531.778728850882, 1497.7154340675236, 1505.4580351073605, 1515.0379509011636, 1518.32434566619, 1519.8632123108973, 1022.192467950311, 1088.8475613414128, 1095.742500512379, 1104.3373975614568, 1104.4919337104861, 4824.614030877521, 4828.493008234155, 4835.95164048362, 4836.806527738921, 4845.781939812717, 5697.658140511582, 5713.0, 5734.681023364649, 5736.368999410061, 5752.223449834302, 4722.504137065627, 4831.275308703653, 4854.95145048545, 4856.708597484162, 4863.440704318978, 2992.925176870596, 3018.4566777980176, 3022.667506574275, 3023.8488075596133, 3046.2536678513907, 5312.335958005249, 5317.9202311965355, 5318.494742999486, 5355.223492593552, 5368.812091576776, 5528.695921724344, 5737.8565535861935, 5823.330317013598, 5849.766009359663, 5878.617889837147, 5140.306058682105, 5199.272101905736, 5245.186996015624, 5277.918026804496, 5306.204069389468, 5631.943680563142, 5634.549236061023, 5644.350899646429, 5653.632513886584, 5694.800681976111, 5390.353157621111, 5595.440455954372, 5597.80407685729, 5606.495415412633, 5634.675851175052, 5344.599155063308, 5347.51872331492, 5349.251104845403, 5351.892962140658, 5358.276632654698, 4001.379786133154, 4007.178528401729, 4019.3167161583187, 4029.597040295947, 4040.2323558523785, 5515.972420137874, 5733.283339582552, 5733.885322293614, 5734.913976290442, 5752.33848107473, 5526.309211348582, 5533.446655334435, 5535.7785688572, 5536.557075434036, 5554.2779438672715, 5400.162974738922, 5499.642523236137, 5518.089515229942, 5518.944810551843, 5544.63959842609, 5442.836714898559, 5459.918101228405, 5462.9726851365485, 5473.0, 5477.370102438271, 5556.277424062261, 5581.386047534849, 5582.469665381782, 5588.105903055492, 5588.2455868458865, 5304.28392167068, 5407.459254074561, 5417.295751552273, 5418.945810541844, 5432.239486471818], "value": 4240.864882280492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.6103701408707, 691.7094820175525, 691.9896201556976, 697.560536861777, 723.9420846332291, 12707.428165732541, 13094.0, 13838.377273022714, 13957.581272561827, 14293.285335733197, 13429.328533573307, 13649.795253071212, 13860.237686927216, 13871.722565548684, 13891.652708682288, 841.2386789955085, 855.3542075732813, 855.6748435594466, 856.0540602634093, 856.3235044314983, 841.7138173021171, 857.691231156783, 881.5151666583386, 887.2901678657048, 887.529609307065, 921.5438358012793, 923.1322556796623, 923.5982347678781, 924.0159230419615, 924.0205382294789, 904.9638014479413, 909.5043201455222, 910.6038873090226, 912.8083102548472, 913.4108500017452, 668.9632070236136, 671.6540981394586, 673.3872176319575, 673.5722816011819, 674.1404708996021, 14308.713825723531, 14368.425262989467, 14472.131672099707, 14475.638109047253, 15071.11611048619, 12871.356432178354, 13201.27392993385, 13366.928126140463, 13471.12437691547, 13807.930960345235, 10095.116297658087, 10520.210984176218, 10667.733306667318, 10720.517576709066, 10869.29349592274, 2722.823016503921, 2738.5070687276284, 2760.7101254368404, 2795.4269374778123, 2799.076304819405, 61635.1761254894, 62224.37775622231, 63218.367816321705, 64732.20311867233, 64794.3520564793, 10431.269811113149, 10560.260781745357, 10576.682699519026, 10695.251332406657, 11205.383703896365, 19408.22367105328, 19614.411567652707, 19686.40940771779, 19712.802871971384, 19837.702434463496, 6162.753489860355, 6255.093011513396, 6263.937360626426, 6267.561270711098, 6267.811965641038, 3138.905832825019, 3140.5917230759856, 3182.268078341994, 3209.646938836726, 3213.453712868818, 3773.396256598931, 3841.385378339508, 3850.961490385116, 3862.9227415452096, 4041.191761647682, 748.547128986966, 750.2010358967682, 753.1038064702985, 770.1259070954507, 770.7918861907253, 499.42566049043165, 516.8010216298152, 522.9947700523022, 524.3890867139739, 527.082875796112, 7845.37237021037, 8404.537750423664, 8453.704254096658, 8604.698835540852, 8794.516301603346, 503.6575128912375, 521.2311840035917, 521.4524749013597, 525.6635753118007, 539.3689383421406, 3731.421629647409, 3822.2546603412065, 3981.3828856526707, 3997.140614767839, 4009.205366151898, 59191.112133318034, 61462.078068829, 62627.68686156631, 63734.68132659307, 64217.678911605144, 53051.469485305424, 53817.461825381244, 53874.46125538773, 54784.72607636936, 55074.17388739172, 48120.51879481161, 48435.51564484381, 48867.0, 49253.50746492489, 49824.75087624539, 7768.53388796674, 8081.0, 8088.797780055545, 8159.877601836096, 8272.875906861522, 26565.33586660349, 26707.19878403651, 26834.865825671528, 27090.593641095005, 27669.169924902286, 32470.35059298848, 32793.67206327906, 32805.50791738078, 33587.0, 33660.3267934635, 10077.344972576759, 10182.0, 10200.3369780964, 10388.37669739818, 10403.271770975958, 10066.798664026825, 11157.721056973316, 11338.60314888975, 11951.402429878644, 11956.282623042645, 11127.499262533367, 11659.76680466369, 11669.416529173337, 11964.0, 12371.75256494847, 12194.57318993831, 12226.816597751218, 12228.510859565873, 12527.310997895203, 12949.093563450468, 11517.712057198638, 11657.708557286136, 11711.88288117108, 11766.294022358687, 12020.278783273032, 8783.648654053766, 8818.691345802867, 8888.822223555622, 9030.135139823, 9214.216791572791, 9274.536273186179, 9428.15146636806, 9474.147326740627, 9667.371620844622, 10050.547725352231], "value": 6245.470034880172, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 111.0, 113.0, 113.0, 110.0, 112.0, 113.0, 111.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 130.0, 130.0, 166.0, 125.0, 128.0, 125.0, 126.0, 147.0, 133.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 167.0, 168.0, 165.0, 165.0, 164.0, 163.0, 165.0, 164.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 110.0, 75.0, 77.0, 76.0, 77.0, 94.0, 77.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 110.0, 86.0, 83.0, 85.0, 110.0, 84.0, 83.0, 108.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 115.0, 123.0, 115.0, 114.0, 114.0, 114.0, 114.0], "value": 115.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 152.0, 151.0, 151.0, 151.0, 151.0, 151.0, 151.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 66.0, 64.0, 67.0, 67.0, 66.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 56.0, 56.0, 55.0, 56.0, 56.0, 56.0, 56.0], "value": 55.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 90.0, 84.0, 84.0, 93.0, 91.0, 91.0, 83.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 142.0, 145.0, 136.0, 132.0, 143.0, 154.0, 133.0, 215.0, 141.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 198.0, 197.0, 196.0, 201.0, 200.0, 204.0, 202.0, 196.0, 194.0], "value": 198.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 72.0, 72.0, 71.0, 76.0, 72.0, 71.0, 69.0, 71.0], "value": 72.1, "unit": "score"}]}]} 05:00:20 INFO - Return code: 0 05:00:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:00:20 ERROR - # TBPL SUCCESS # 05:00:20 INFO - Running post-action listener: _resource_record_post_action 05:00:20 INFO - [mozharness: 2017-03-28 12:00:20.481992Z] Finished run-tests step (success) 05:00:20 INFO - Running post-run listener: _resource_record_post_run 05:00:20 INFO - Total resource usage - Wall time: 1207s; CPU: 13.0%; Read bytes: 4096; Write bytes: 257798144; Read time: 8; Write time: 184432 05:00:20 INFO - TinderboxPrint: CPU usage
13.0% 05:00:20 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 05:00:20 INFO - TinderboxPrint: I/O write bytes / time
257,798,144 / 184,432 05:00:20 INFO - TinderboxPrint: CPU idle
8,380.9 (87.0%) 05:00:20 INFO - TinderboxPrint: CPU user
1,205.9 (12.5%) 05:00:20 INFO - TinderboxPrint: Swap in / out
0 / 0 05:00:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 2342912; Read time: 0; Write time: 880 05:00:20 INFO - run-tests - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 255455232; Read time: 8; Write time: 183552 05:00:21 INFO - Running post-run listener: _upload_blobber_files 05:00:21 INFO - Blob upload gear active. 05:00:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:00:21 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 05:00:21 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:00:21 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:00:21 INFO - (blobuploader) - INFO - Open directory for files ... 05:00:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:00:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:00:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:22 INFO - (blobuploader) - INFO - Done attempting. 05:00:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:00:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:00:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:23 INFO - (blobuploader) - INFO - Done attempting. 05:00:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:00:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:00:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:24 INFO - (blobuploader) - INFO - Done attempting. 05:00:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:00:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:00:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:33 INFO - (blobuploader) - INFO - Done attempting. 05:00:33 INFO - (blobuploader) - INFO - Iteration through files over. 05:00:33 INFO - Return code: 0 05:00:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:00:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:00:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bdbe8243e8c8bcd5b97054cd5a63c0b6f15a528c89543512ab5e2319f2a11b9072564f1bd4acc9196dd3e8dddb8137a8402e29eb30f1ea51fa594a124b62d25c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34f298c99847fae6fd174a108a203adcb1e1d40b294f275775819dd7823d52d7609ce463cc22ff1eac39414e0768a5417dcc084eb93c502e8dc4b184ebf66fa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d96d7a133e8403a49610bde08a65d618723f1f27cd94cd9248f6c5a8ece06f46f2d1d4942468430983bd1b03845e104fb3a040a182cecf400230779f977a4e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2a2f312696b0fa72d2627722004e391f6e3448181914e40cf074fa15fae02de7d431c63957ab216c67fe8fec1a1b517fa9cadf32bb8d3dc98f7167e658c145b"} 05:00:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:00:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:00:33 INFO - Contents: 05:00:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bdbe8243e8c8bcd5b97054cd5a63c0b6f15a528c89543512ab5e2319f2a11b9072564f1bd4acc9196dd3e8dddb8137a8402e29eb30f1ea51fa594a124b62d25c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34f298c99847fae6fd174a108a203adcb1e1d40b294f275775819dd7823d52d7609ce463cc22ff1eac39414e0768a5417dcc084eb93c502e8dc4b184ebf66fa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d96d7a133e8403a49610bde08a65d618723f1f27cd94cd9248f6c5a8ece06f46f2d1d4942468430983bd1b03845e104fb3a040a182cecf400230779f977a4e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2a2f312696b0fa72d2627722004e391f6e3448181914e40cf074fa15fae02de7d431c63957ab216c67fe8fec1a1b517fa9cadf32bb8d3dc98f7167e658c145b"} 05:00:33 INFO - Running post-run listener: copy_logs_to_upload_dir 05:00:33 INFO - Copying logs to upload dir... 05:00:33 INFO - mkdir: /builds/slave/test/build/upload/logs 05:00:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1322.151165 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-03-28 05:00:32.942178) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:00:32.946456) ========= 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=db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bdbe8243e8c8bcd5b97054cd5a63c0b6f15a528c89543512ab5e2319f2a11b9072564f1bd4acc9196dd3e8dddb8137a8402e29eb30f1ea51fa594a124b62d25c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34f298c99847fae6fd174a108a203adcb1e1d40b294f275775819dd7823d52d7609ce463cc22ff1eac39414e0768a5417dcc084eb93c502e8dc4b184ebf66fa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d96d7a133e8403a49610bde08a65d618723f1f27cd94cd9248f6c5a8ece06f46f2d1d4942468430983bd1b03845e104fb3a040a182cecf400230779f977a4e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2a2f312696b0fa72d2627722004e391f6e3448181914e40cf074fa15fae02de7d431c63957ab216c67fe8fec1a1b517fa9cadf32bb8d3dc98f7167e658c145b"} build_url:https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011425 build_url: 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bdbe8243e8c8bcd5b97054cd5a63c0b6f15a528c89543512ab5e2319f2a11b9072564f1bd4acc9196dd3e8dddb8137a8402e29eb30f1ea51fa594a124b62d25c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34f298c99847fae6fd174a108a203adcb1e1d40b294f275775819dd7823d52d7609ce463cc22ff1eac39414e0768a5417dcc084eb93c502e8dc4b184ebf66fa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d96d7a133e8403a49610bde08a65d618723f1f27cd94cd9248f6c5a8ece06f46f2d1d4942468430983bd1b03845e104fb3a040a182cecf400230779f977a4e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2a2f312696b0fa72d2627722004e391f6e3448181914e40cf074fa15fae02de7d431c63957ab216c67fe8fec1a1b517fa9cadf32bb8d3dc98f7167e658c145b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:00:32.978199) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:00:32.978565) ========= 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=db7e7c15f3ca076d53731445000001ba-1490701101.109565-1017422808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:00:33.065789) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:00:33.066105) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:00:33.066421) ========= ========= Total master_lag: 0.18 =========