builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1490704071.82 results: success (0) revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:51.821196) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:51.821615) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:51.821885) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:51.836230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:51.836490) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-28 05:27:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.4M=0s 2017-03-28 05:27:52 (69.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119392 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:51.969616) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:51.969923) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016577 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:52.020247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 05:27:52.020588) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-28 05:27:52,174 truncating revision to first 12 chars 2017-03-28 05:27:52,174 Setting DEBUG logging. 2017-03-28 05:27:52,174 attempt 1/10 2017-03-28 05:27:52,174 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 05:27:53,450 unpacking tar archive at: mozilla-central-d4af7ec6cfcd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.585919 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 05:27:53.619879) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:53.620257) ========= script_repo_revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:53.620684) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:53.620952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 05:27:53.629641) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-28 05:27:53.629925) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:27:53 INFO - MultiFileLogger online at 20170328 05:27:53 in /builds/slave/test 05:27:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:27:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:27:53 INFO - {'append_to_log': False, 05:27:53 INFO - 'base_work_dir': '/builds/slave/test', 05:27:53 INFO - 'blob_upload_branch': 'Firefox', 05:27:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:27:53 INFO - 'branch': 'Firefox', 05:27:53 INFO - 'buildbot_json_path': 'buildprops.json', 05:27:53 INFO - 'config_files': ('talos/linux_config.py',), 05:27:53 INFO - 'default_actions': ('clobber', 05:27:53 INFO - 'read-buildbot-config', 05:27:53 INFO - 'download-and-extract', 05:27:53 INFO - 'populate-webroot', 05:27:53 INFO - 'create-virtualenv', 05:27:53 INFO - 'install', 05:27:53 INFO - 'run-tests'), 05:27:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:27:53 INFO - 'download_minidump_stackwalk': True, 05:27:53 INFO - 'download_symbols': 'ondemand', 05:27:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:27:53 INFO - 'tooltool.py': '/tools/tooltool.py', 05:27:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:27:53 INFO - '/tools/misc-python/virtualenv.py')}, 05:27:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:27:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:27:53 INFO - 'gecko_profile': False, 05:27:53 INFO - 'gecko_profile_interval': 0, 05:27:53 INFO - 'installer_path': 'installer.exe', 05:27:53 INFO - 'log_level': 'info', 05:27:53 INFO - 'log_name': 'talos', 05:27:53 INFO - 'log_to_console': True, 05:27:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:27:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:27:53 INFO - 'opt_config_files': (), 05:27:53 INFO - 'pip_index': False, 05:27:53 INFO - 'suite': 'dromaeojs', 05:27:53 INFO - 'system_bits': '32', 05:27:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:27:53 INFO - 'title': 'talos-linux64-ix-033', 05:27:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:27:53 INFO - 'use_talos_json': True, 05:27:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:27:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:27:53 INFO - 'work_dir': 'build'} 05:27:53 INFO - [mozharness: 2017-03-28 12:27:53.881224Z] Running clobber step. 05:27:53 INFO - Running pre-action listener: _resource_record_pre_action 05:27:53 INFO - Running main action method: clobber 05:27:53 INFO - rmtree: /builds/slave/test/build 05:27:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:27:54 INFO - Running post-action listener: _resource_record_post_action 05:27:54 INFO - [mozharness: 2017-03-28 12:27:54.813127Z] Finished clobber step (success) 05:27:54 INFO - [mozharness: 2017-03-28 12:27:54.813227Z] Running read-buildbot-config step. 05:27:54 INFO - Running pre-action listener: _resource_record_pre_action 05:27:54 INFO - Running main action method: read_buildbot_config 05:27:54 INFO - Using buildbot properties: 05:27:54 INFO - { 05:27:54 INFO - "project": "", 05:27:54 INFO - "product": "firefox", 05:27:54 INFO - "who": "cbook@mozilla.com", 05:27:54 INFO - "installer_path": "public/build/target.tar.bz2", 05:27:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:27:54 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:27:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:27:54 INFO - "stage_platform": "linux64-pgo", 05:27:54 INFO - "basedir": "/builds/slave/test", 05:27:54 INFO - "buildnumber": 96, 05:27:54 INFO - "platform": "ubuntu64_hw", 05:27:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:27:54 INFO - "slavebuilddir": "test", 05:27:54 INFO - "taskId": "AC6g9DPIQqauK2Vsc4k4UA", 05:27:54 INFO - "branch": "mozilla-central", 05:27:54 INFO - "script_repo_revision": "production", 05:27:54 INFO - "revision": "d4af7ec6cfcd9b81cd1f433a00b412de61e95b62", 05:27:54 INFO - "slavename": "talos-linux64-ix-033", 05:27:54 INFO - "repo_path": "mozilla-central" 05:27:54 INFO - } 05:27:54 INFO - Finding installer, test and symbols from parent task. 05:27:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AC6g9DPIQqauK2Vsc4k4UA'}, attempt #1 05:27:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AC6g9DPIQqauK2Vsc4k4UA'}, attempt #1 05:27:55 INFO - Task dependencies: LCFc1Oz0QoWzeg6hD58ePw 05:27:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw'}, attempt #1 05:27:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2 05:27:56 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/test_packages.json 05:27:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.crashreporter-symbols.zip 05:27:56 INFO - Running post-action listener: _resource_record_post_action 05:27:56 INFO - [mozharness: 2017-03-28 12:27:56.185573Z] Finished read-buildbot-config step (success) 05:27:56 INFO - [mozharness: 2017-03-28 12:27:56.185793Z] Running download-and-extract step. 05:27:56 INFO - Running pre-action listener: _resource_record_pre_action 05:27:56 INFO - Running main action method: download_and_extract 05:27:56 INFO - mkdir: /builds/slave/test/build/tests 05:27: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')]} 05:27:56 INFO - trying https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.test_packages.json 05:27:56 INFO - Downloading https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:27:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:27:56 INFO - Downloaded 986 bytes. 05:27:56 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:27:56 INFO - Using the following test package requirements: 05:27:56 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:27:56 INFO - u'common': [u'target.common.tests.zip'], 05:27:56 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:27:56 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:27:56 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:27:56 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:27:56 INFO - u'mozbase': [u'target.common.tests.zip'], 05:27:56 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:27:56 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:27:56 INFO - u'web-platform': [u'target.common.tests.zip', 05:27:56 INFO - u'target.web-platform.tests.zip'], 05:27:56 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:27:56 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:27:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.common.tests.zip 05:27:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:27:56 INFO - Fetch https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.common.tests.zip into memory 05:27:58 INFO - Expected file size: 60346246 05:27:58 INFO - Obtained file size: 60346246 05:28:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:28:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.common.tests.zip 05:28:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:28:08 INFO - Fetch https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.common.tests.zip into memory 05:28:09 INFO - Expected file size: 60346246 05:28:09 INFO - Obtained file size: 60346246 05:28:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.talos.tests.zip 05:28:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:28:20 INFO - Fetch https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.talos.tests.zip into memory 05:28:21 INFO - Expected file size: 14404171 05:28:21 INFO - Obtained file size: 14404171 05:28:21 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')]} 05:28:21 INFO - trying https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2 05:28:21 INFO - Downloading https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:28:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:28:22 INFO - Downloaded 68041174 bytes. 05:28:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2 05:28:22 INFO - mkdir: /builds/slave/test/properties 05:28:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:28:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:28:22 INFO - Contents: 05:28:22 INFO - build_url:https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2 05:28:22 INFO - Running post-action listener: _resource_record_post_action 05:28:22 INFO - Running post-action listener: set_extra_try_arguments 05:28:22 INFO - [mozharness: 2017-03-28 12:28:22.479369Z] Finished download-and-extract step (success) 05:28:22 INFO - [mozharness: 2017-03-28 12:28:22.479466Z] Running populate-webroot step. 05:28:22 INFO - Running pre-action listener: _resource_record_pre_action 05:28:22 INFO - Running main action method: populate_webroot 05:28:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:22 INFO - '--tppagecycles', 05:28:22 INFO - '7'], 05:28:22 INFO - 'tests': ['tsvgm']}}, 05:28:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:28:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:28:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:28:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:28:22 INFO - 'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:28:22 INFO - 'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['damp', 'tps']}, 05:28:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:28:22 INFO - 'tests': ['damp', 'tps']}, 05:28:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['dromaeo_dom']}, 05:28:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['basic_compositor_video']}, 05:28:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:28:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['a11yr', 05:28:22 INFO - 'ts_paint', 05:28:22 INFO - 'tpaint', 05:28:22 INFO - 'sessionrestore', 05:28:22 INFO - 'sessionrestore_no_auto_restore', 05:28:22 INFO - 'tabpaint']}, 05:28:22 INFO - 'other-e10s': {'tests': ['a11yr', 05:28:22 INFO - 'ts_paint', 05:28:22 INFO - 'tpaint', 05:28:22 INFO - 'sessionrestore', 05:28:22 INFO - 'sessionrestore_no_auto_restore', 05:28:22 INFO - 'tabpaint']}, 05:28:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:28:22 INFO - 'ts_paint', 05:28:22 INFO - 'tpaint', 05:28:22 INFO - 'sessionrestore', 05:28:22 INFO - 'sessionrestore_no_auto_restore', 05:28:22 INFO - 'tabpaint']}, 05:28:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:28:22 INFO - 'ts_paint', 05:28:22 INFO - 'tpaint', 05:28:22 INFO - 'sessionrestore', 05:28:22 INFO - 'sessionrestore_no_auto_restore', 05:28:22 INFO - 'tabpaint']}, 05:28:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['a11yr', 05:28:22 INFO - 'ts_paint', 05:28:22 INFO - 'tpaint', 05:28:22 INFO - 'sessionrestore', 05:28:22 INFO - 'sessionrestore_no_auto_restore', 05:28:22 INFO - 'tabpaint']}, 05:28:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['a11yr', 05:28:22 INFO - 'ts_paint', 05:28:22 INFO - 'tpaint', 05:28:22 INFO - 'sessionrestore', 05:28:22 INFO - 'sessionrestore_no_auto_restore', 05:28:22 INFO - 'tabpaint']}, 05:28:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['tsvgx', 05:28:22 INFO - 'tsvgr_opacity', 05:28:22 INFO - 'tart', 05:28:22 INFO - 'tscrollx', 05:28:22 INFO - 'cart', 05:28:22 INFO - 'tsvg_static']}, 05:28:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:28:22 INFO - 'tsvgr_opacity', 05:28:22 INFO - 'tart', 05:28:22 INFO - 'tscrollx', 05:28:22 INFO - 'cart', 05:28:22 INFO - 'tsvg_static']}, 05:28:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:28:22 INFO - 'talos_options': ['--disable-e10s'], 05:28:22 INFO - 'tests': ['tp5o']}, 05:28:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:28:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:28:22 INFO - 'talos_options': ['--disable-e10s', 05:28:22 INFO - '--xperf_path', 05:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:22 INFO - 'tests': ['tp5n']}, 05:28:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:28:22 INFO - 'talos_options': ['--xperf_path', 05:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:22 INFO - 'tests': ['tp5n']}}, 05:28:22 INFO - 'talos.zip': {'path': '', 05:28:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:22 INFO - Running post-action listener: _resource_record_post_action 05:28:22 INFO - [mozharness: 2017-03-28 12:28:22.492710Z] Finished populate-webroot step (success) 05:28:22 INFO - [mozharness: 2017-03-28 12:28:22.492802Z] Running create-virtualenv step. 05:28:22 INFO - Running pre-action listener: _resource_record_pre_action 05:28:22 INFO - Running main action method: create_virtualenv 05:28:22 INFO - Creating virtualenv /builds/slave/test/build/venv 05:28:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:28:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:28:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:22 INFO - Using real prefix '/usr' 05:28:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:28:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:25 INFO - Installing pip.................done. 05:28:25 INFO - Return code: 0 05:28:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:28:25 INFO - Reading from file tmpfile_stdout 05:28:25 INFO - Output received: 05:28:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:28:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:28:25 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')]} 05:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:25 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')]} 05:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:25 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 05:28:25 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 05:28:25 INFO - Using env: {'DISPLAY': ':0', 05:28:25 INFO - 'HOME': '/home/cltbld', 05:28:25 INFO - 'LANG': 'en_US.UTF-8', 05:28:25 INFO - 'LANGUAGE': 'en_US:en', 05:28:25 INFO - 'LOGNAME': 'cltbld', 05:28:25 INFO - 'MAIL': '/var/mail/cltbld', 05:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:25 INFO - 'MOZ_NO_REMOTE': '1', 05:28:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:25 INFO - 'NO_EM_RESTART': '1', 05:28:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:25 INFO - 'PWD': '/builds/slave/test', 05:28:25 INFO - 'SHELL': '/bin/bash', 05:28:25 INFO - 'SHLVL': '1', 05:28:25 INFO - 'TERM': 'linux', 05:28:25 INFO - 'TMOUT': '86400', 05:28:25 INFO - 'USER': 'cltbld', 05:28:25 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:25 INFO - '_': '/tools/buildbot/bin/python'} 05:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:28:25 INFO - Cleaning up... 05:28:25 INFO - Return code: 0 05:28:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:28:25 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')]} 05:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:25 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')]} 05:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:25 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 05:28:25 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 05:28:25 INFO - Using env: {'DISPLAY': ':0', 05:28:25 INFO - 'HOME': '/home/cltbld', 05:28:25 INFO - 'LANG': 'en_US.UTF-8', 05:28:25 INFO - 'LANGUAGE': 'en_US:en', 05:28:25 INFO - 'LOGNAME': 'cltbld', 05:28:25 INFO - 'MAIL': '/var/mail/cltbld', 05:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:25 INFO - 'MOZ_NO_REMOTE': '1', 05:28:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:25 INFO - 'NO_EM_RESTART': '1', 05:28:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:25 INFO - 'PWD': '/builds/slave/test', 05:28:25 INFO - 'SHELL': '/bin/bash', 05:28:25 INFO - 'SHLVL': '1', 05:28:25 INFO - 'TERM': 'linux', 05:28:25 INFO - 'TMOUT': '86400', 05:28:25 INFO - 'USER': 'cltbld', 05:28:25 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:25 INFO - '_': '/tools/buildbot/bin/python'} 05:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:25 INFO - Downloading/unpacking psutil>=3.1.1 05:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:25 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 05:28:25 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 05:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:28:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:27 INFO - Installing collected packages: psutil 05:28:27 INFO - Running setup.py install for psutil 05:28:27 INFO - building 'psutil._psutil_linux' extension 05:28:27 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 05:28:28 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 05:28:28 INFO - building 'psutil._psutil_posix' extension 05:28:28 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 05:28:28 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 05:28:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:28 INFO - Successfully installed psutil 05:28:28 INFO - Cleaning up... 05:28:28 INFO - Return code: 0 05:28:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:28:28 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')]} 05:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:28 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')]} 05:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:28 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 05:28:28 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 05:28:28 INFO - Using env: {'DISPLAY': ':0', 05:28:28 INFO - 'HOME': '/home/cltbld', 05:28:28 INFO - 'LANG': 'en_US.UTF-8', 05:28:28 INFO - 'LANGUAGE': 'en_US:en', 05:28:28 INFO - 'LOGNAME': 'cltbld', 05:28:28 INFO - 'MAIL': '/var/mail/cltbld', 05:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:28 INFO - 'MOZ_NO_REMOTE': '1', 05:28:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:28 INFO - 'NO_EM_RESTART': '1', 05:28:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:28 INFO - 'PWD': '/builds/slave/test', 05:28:28 INFO - 'SHELL': '/bin/bash', 05:28:28 INFO - 'SHLVL': '1', 05:28:28 INFO - 'TERM': 'linux', 05:28:28 INFO - 'TMOUT': '86400', 05:28:28 INFO - 'USER': 'cltbld', 05:28:28 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:28 INFO - '_': '/tools/buildbot/bin/python'} 05:28:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:28 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 05:28:28 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 05:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:28:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:28:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:28:30 INFO - Installing collected packages: mozsystemmonitor 05:28:30 INFO - Running setup.py install for mozsystemmonitor 05:28:30 INFO - Successfully installed mozsystemmonitor 05:28:30 INFO - Cleaning up... 05:28:30 INFO - Return code: 0 05:28:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:28:30 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')]} 05:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:30 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')]} 05:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:30 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 05:28:30 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 05:28:30 INFO - Using env: {'DISPLAY': ':0', 05:28:30 INFO - 'HOME': '/home/cltbld', 05:28:30 INFO - 'LANG': 'en_US.UTF-8', 05:28:30 INFO - 'LANGUAGE': 'en_US:en', 05:28:30 INFO - 'LOGNAME': 'cltbld', 05:28:30 INFO - 'MAIL': '/var/mail/cltbld', 05:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:30 INFO - 'MOZ_NO_REMOTE': '1', 05:28:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:30 INFO - 'NO_EM_RESTART': '1', 05:28:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:30 INFO - 'PWD': '/builds/slave/test', 05:28:30 INFO - 'SHELL': '/bin/bash', 05:28:30 INFO - 'SHLVL': '1', 05:28:30 INFO - 'TERM': 'linux', 05:28:30 INFO - 'TMOUT': '86400', 05:28:30 INFO - 'USER': 'cltbld', 05:28:30 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:30 INFO - '_': '/tools/buildbot/bin/python'} 05:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:31 INFO - Downloading/unpacking jsonschema==2.5.1 05:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:31 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 05:28:31 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 05:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:28:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:28:33 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 05:28:33 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 05:28:33 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 05:28:33 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 05:28:33 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 05:28:33 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 05:28:33 INFO - Downloading functools32-3.2.3-2.tar.gz 05:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:28:33 INFO - warning: no files found matching '*.txt' 05:28:33 INFO - no previously-included directories found matching 'build' 05:28:33 INFO - no previously-included directories found matching 'dist' 05:28:33 INFO - no previously-included directories found matching '.git*' 05:28:33 INFO - Installing collected packages: jsonschema, functools32 05:28:33 INFO - Running setup.py install for functools32 05:28:33 INFO - warning: no files found matching '*.txt' 05:28:33 INFO - no previously-included directories found matching 'build' 05:28:33 INFO - no previously-included directories found matching 'dist' 05:28:33 INFO - no previously-included directories found matching '.git*' 05:28:33 INFO - Successfully installed jsonschema functools32 05:28:33 INFO - Cleaning up... 05:28:33 INFO - Return code: 0 05:28:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:28:33 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')]} 05:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:33 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')]} 05:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:33 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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:33 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 05:28:33 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 05:28:33 INFO - Using env: {'DISPLAY': ':0', 05:28:33 INFO - 'HOME': '/home/cltbld', 05:28:33 INFO - 'LANG': 'en_US.UTF-8', 05:28:33 INFO - 'LANGUAGE': 'en_US:en', 05:28:33 INFO - 'LOGNAME': 'cltbld', 05:28:33 INFO - 'MAIL': '/var/mail/cltbld', 05:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:33 INFO - 'MOZ_NO_REMOTE': '1', 05:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:33 INFO - 'NO_EM_RESTART': '1', 05:28:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:33 INFO - 'PWD': '/builds/slave/test', 05:28:33 INFO - 'SHELL': '/bin/bash', 05:28:33 INFO - 'SHLVL': '1', 05:28:33 INFO - 'TERM': 'linux', 05:28:33 INFO - 'TMOUT': '86400', 05:28:33 INFO - 'USER': 'cltbld', 05:28:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:33 INFO - '_': '/tools/buildbot/bin/python'} 05:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:28:33 INFO - Cleaning up... 05:28:33 INFO - Return code: 0 05:28:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:28:33 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')]} 05:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:33 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')]} 05:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:33 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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:33 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 05:28:33 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 05:28:33 INFO - Using env: {'DISPLAY': ':0', 05:28:33 INFO - 'HOME': '/home/cltbld', 05:28:33 INFO - 'LANG': 'en_US.UTF-8', 05:28:33 INFO - 'LANGUAGE': 'en_US:en', 05:28:33 INFO - 'LOGNAME': 'cltbld', 05:28:33 INFO - 'MAIL': '/var/mail/cltbld', 05:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:33 INFO - 'MOZ_NO_REMOTE': '1', 05:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:33 INFO - 'NO_EM_RESTART': '1', 05:28:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:33 INFO - 'PWD': '/builds/slave/test', 05:28:33 INFO - 'SHELL': '/bin/bash', 05:28:33 INFO - 'SHLVL': '1', 05:28:33 INFO - 'TERM': 'linux', 05:28:33 INFO - 'TMOUT': '86400', 05:28:33 INFO - 'USER': 'cltbld', 05:28:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:33 INFO - '_': '/tools/buildbot/bin/python'} 05:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:34 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 05:28:34 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 05:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:28:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:28:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 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 05:28:36 INFO - Downloading docopt-0.6.1.tar.gz 05:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:28:36 INFO - Installing collected packages: blobuploader, requests, docopt 05:28:36 INFO - Running setup.py install for blobuploader 05:28:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:28:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:28:36 INFO - Running setup.py install for requests 05:28:37 INFO - Running setup.py install for docopt 05:28:37 INFO - Successfully installed blobuploader requests docopt 05:28:37 INFO - Cleaning up... 05:28:37 INFO - Return code: 0 05:28:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28: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')]} 05:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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')]} 05:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:37 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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:37 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 05:28:37 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 05:28:37 INFO - Using env: {'DISPLAY': ':0', 05:28:37 INFO - 'HOME': '/home/cltbld', 05:28:37 INFO - 'LANG': 'en_US.UTF-8', 05:28:37 INFO - 'LANGUAGE': 'en_US:en', 05:28:37 INFO - 'LOGNAME': 'cltbld', 05:28:37 INFO - 'MAIL': '/var/mail/cltbld', 05:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:37 INFO - 'MOZ_NO_REMOTE': '1', 05:28:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:37 INFO - 'NO_EM_RESTART': '1', 05:28:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:37 INFO - 'PWD': '/builds/slave/test', 05:28:37 INFO - 'SHELL': '/bin/bash', 05:28:37 INFO - 'SHLVL': '1', 05:28:37 INFO - 'TERM': 'linux', 05:28:37 INFO - 'TMOUT': '86400', 05:28:37 INFO - 'USER': 'cltbld', 05:28:37 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:37 INFO - '_': '/tools/buildbot/bin/python'} 05:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:37 INFO - Running setup.py (path:/tmp/pip-TjVbPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:37 INFO - Running setup.py (path:/tmp/pip-epCvPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:37 INFO - Running setup.py (path:/tmp/pip-AKAZB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:37 INFO - Running setup.py (path:/tmp/pip-otvg_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:38 INFO - Running setup.py (path:/tmp/pip-qZPstG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:38 INFO - Running setup.py (path:/tmp/pip-fmBfnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:38 INFO - Running setup.py (path:/tmp/pip-8CAiXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:38 INFO - Running setup.py (path:/tmp/pip-nLMFEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:38 INFO - Running setup.py (path:/tmp/pip-Eux_W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:38 INFO - Running setup.py (path:/tmp/pip-3Pgbs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:38 INFO - Running setup.py (path:/tmp/pip-1wRLDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:38 INFO - Running setup.py (path:/tmp/pip-EUgsqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:38 INFO - Running setup.py (path:/tmp/pip-PO3j2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:38 INFO - Running setup.py (path:/tmp/pip-WeAy3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:38 INFO - Running setup.py (path:/tmp/pip-hYwJv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:38 INFO - Running setup.py (path:/tmp/pip-nmFMne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:38 INFO - Running setup.py (path:/tmp/pip-X6_aeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:28:38 INFO - Running setup.py install for manifestparser 05:28:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Running setup.py install for mozcrash 05:28:39 INFO - Running setup.py install for mozdebug 05:28:39 INFO - Running setup.py install for mozdevice 05:28:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Running setup.py install for mozfile 05:28:39 INFO - Running setup.py install for mozhttpd 05:28:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Running setup.py install for mozinfo 05:28:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Running setup.py install for mozInstall 05:28:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Running setup.py install for mozleak 05:28:39 INFO - Running setup.py install for mozlog 05:28:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:28:39 INFO - Running setup.py install for moznetwork 05:28:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:28:40 INFO - Running setup.py install for mozprocess 05:28:40 INFO - Running setup.py install for mozprofile 05:28:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:28:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:28:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:28:40 INFO - Running setup.py install for mozrunner 05:28:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:28:40 INFO - Running setup.py install for mozscreenshot 05:28:40 INFO - Running setup.py install for moztest 05:28:40 INFO - Running setup.py install for mozversion 05:28:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:28:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:28:40 INFO - Cleaning up... 05:28:40 INFO - Return code: 0 05:28:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:40 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')]} 05:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:40 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')]} 05:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:40 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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:40 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 05:28:40 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 05:28:40 INFO - Using env: {'DISPLAY': ':0', 05:28:40 INFO - 'HOME': '/home/cltbld', 05:28:40 INFO - 'LANG': 'en_US.UTF-8', 05:28:40 INFO - 'LANGUAGE': 'en_US:en', 05:28:40 INFO - 'LOGNAME': 'cltbld', 05:28:40 INFO - 'MAIL': '/var/mail/cltbld', 05:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:40 INFO - 'MOZ_NO_REMOTE': '1', 05:28:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:40 INFO - 'NO_EM_RESTART': '1', 05:28:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:40 INFO - 'PWD': '/builds/slave/test', 05:28:40 INFO - 'SHELL': '/bin/bash', 05:28:40 INFO - 'SHLVL': '1', 05:28:40 INFO - 'TERM': 'linux', 05:28:40 INFO - 'TMOUT': '86400', 05:28:40 INFO - 'USER': 'cltbld', 05:28:40 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:40 INFO - '_': '/tools/buildbot/bin/python'} 05:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:40 INFO - Running setup.py (path:/tmp/pip-XZ88Im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:41 INFO - Running setup.py (path:/tmp/pip-wPXEKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:41 INFO - Running setup.py (path:/tmp/pip-qF0Ahg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:41 INFO - Running setup.py (path:/tmp/pip-QcC_2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:41 INFO - Running setup.py (path:/tmp/pip-alWiLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:41 INFO - Running setup.py (path:/tmp/pip-2dJYQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:41 INFO - Running setup.py (path:/tmp/pip-RBHZrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:41 INFO - Running setup.py (path:/tmp/pip-UILWs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:41 INFO - Running setup.py (path:/tmp/pip-P2AaAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:41 INFO - Running setup.py (path:/tmp/pip-fmt1Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:41 INFO - Running setup.py (path:/tmp/pip-kITfSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:41 INFO - Running setup.py (path:/tmp/pip-KZFdv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:41 INFO - Running setup.py (path:/tmp/pip-K_jk4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:41 INFO - Running setup.py (path:/tmp/pip-vZhbfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:41 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)) 05:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:41 INFO - Running setup.py (path:/tmp/pip-nfMZAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:42 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)) 05:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:42 INFO - Running setup.py (path:/tmp/pip-hyImqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:42 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)) 05:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:42 INFO - Running setup.py (path:/tmp/pip-An5Spt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:42 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)) 05:28:42 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)) 05:28:42 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)) 05:28:42 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)) 05:28:42 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)) 05:28:42 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)) 05:28:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:42 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 05:28:42 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 05:28:42 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 05:28:42 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 05:28:42 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 05:28:42 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 05:28:44 INFO - Downloading blessings-1.6.tar.gz 05:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:28:44 INFO - Installing collected packages: blessings 05:28:44 INFO - Running setup.py install for blessings 05:28:44 INFO - Successfully installed blessings 05:28:44 INFO - Cleaning up... 05:28:44 INFO - Return code: 0 05:28:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:44 INFO - Reading from file tmpfile_stdout 05:28:44 INFO - Current package versions: 05:28:44 INFO - argparse == 1.2.1 05:28:44 INFO - blessings == 1.6 05:28:44 INFO - blobuploader == 1.2.4 05:28:44 INFO - docopt == 0.6.1 05:28:44 INFO - functools32 == 3.2.3-2 05:28:44 INFO - jsonschema == 2.5.1 05:28:44 INFO - manifestparser == 1.1 05:28:44 INFO - mozInstall == 1.12 05:28:44 INFO - mozcrash == 1.0 05:28:44 INFO - mozdebug == 0.1 05:28:44 INFO - mozdevice == 0.49 05:28:44 INFO - mozfile == 1.2 05:28:44 INFO - mozhttpd == 0.7 05:28:44 INFO - mozinfo == 0.9 05:28:44 INFO - mozleak == 0.1 05:28:44 INFO - mozlog == 3.4 05:28:44 INFO - moznetwork == 0.27 05:28:44 INFO - mozprocess == 0.25 05:28:44 INFO - mozprofile == 0.28 05:28:44 INFO - mozrunner == 6.13 05:28:44 INFO - mozscreenshot == 0.1 05:28:44 INFO - mozsystemmonitor == 0.3 05:28:44 INFO - moztest == 0.8 05:28:44 INFO - mozversion == 1.4 05:28:44 INFO - psutil == 3.1.1 05:28:44 INFO - requests == 1.2.3 05:28:44 INFO - wsgiref == 0.1.2 05:28:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:44 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')]} 05:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:44 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')]} 05:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:44 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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:44 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 05:28:44 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 05:28:44 INFO - Using env: {'DISPLAY': ':0', 05:28:44 INFO - 'HOME': '/home/cltbld', 05:28:44 INFO - 'LANG': 'en_US.UTF-8', 05:28:44 INFO - 'LANGUAGE': 'en_US:en', 05:28:44 INFO - 'LOGNAME': 'cltbld', 05:28:44 INFO - 'MAIL': '/var/mail/cltbld', 05:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:44 INFO - 'MOZ_NO_REMOTE': '1', 05:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:44 INFO - 'NO_EM_RESTART': '1', 05:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:44 INFO - 'PWD': '/builds/slave/test', 05:28:44 INFO - 'SHELL': '/bin/bash', 05:28:44 INFO - 'SHLVL': '1', 05:28:44 INFO - 'TERM': 'linux', 05:28:44 INFO - 'TMOUT': '86400', 05:28:44 INFO - 'USER': 'cltbld', 05:28:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:44 INFO - '_': '/tools/buildbot/bin/python'} 05:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 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)) 05:28:44 INFO - Cleaning up... 05:28:44 INFO - Return code: 0 05:28:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:28:44 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')]} 05:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:44 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')]} 05:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:44 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 0x15811f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', '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 05:28:44 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 05:28:44 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 05:28:44 INFO - Using env: {'DISPLAY': ':0', 05:28:44 INFO - 'HOME': '/home/cltbld', 05:28:44 INFO - 'LANG': 'en_US.UTF-8', 05:28:44 INFO - 'LANGUAGE': 'en_US:en', 05:28:44 INFO - 'LOGNAME': 'cltbld', 05:28:44 INFO - 'MAIL': '/var/mail/cltbld', 05:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:44 INFO - 'MOZ_NO_REMOTE': '1', 05:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:44 INFO - 'NO_EM_RESTART': '1', 05:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:44 INFO - 'PWD': '/builds/slave/test', 05:28:44 INFO - 'SHELL': '/bin/bash', 05:28:44 INFO - 'SHLVL': '1', 05:28:44 INFO - 'TERM': 'linux', 05:28:44 INFO - 'TMOUT': '86400', 05:28:44 INFO - 'USER': 'cltbld', 05:28:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:44 INFO - '_': '/tools/buildbot/bin/python'} 05:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:28:45 INFO - Cleaning up... 05:28:45 INFO - Return code: 0 05:28:45 INFO - Running post-action listener: _resource_record_post_action 05:28:45 INFO - Running post-action listener: _start_resource_monitoring 05:28:45 INFO - Starting resource monitoring. 05:28:45 INFO - [mozharness: 2017-03-28 12:28:45.149958Z] Finished create-virtualenv step (success) 05:28:45 INFO - [mozharness: 2017-03-28 12:28:45.150426Z] Running install step. 05:28:45 INFO - Running pre-action listener: _resource_record_pre_action 05:28:45 INFO - Running main action method: install 05:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:45 INFO - Reading from file tmpfile_stdout 05:28:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:28:45 INFO - Reading from file tmpfile_stdout 05:28:45 INFO - Output received: 05:28:45 INFO - Usage: mozinstall [options] installer 05:28:45 INFO - Options: 05:28:45 INFO - -h, --help show this help message and exit 05:28:45 INFO - -d DEST, --destination=DEST 05:28:45 INFO - Directory to install application into. [default: 05:28:45 INFO - "/builds/slave/test"] 05:28:45 INFO - --app=APP Application being installed. [default: firefox] 05:28:45 INFO - mkdir: /builds/slave/test/build/application 05:28:45 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'] 05:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:29:02 INFO - Reading from file tmpfile_stdout 05:29:02 INFO - Output received: 05:29:02 INFO - /builds/slave/test/build/application/firefox/firefox 05:29:02 INFO - Running post-action listener: _resource_record_post_action 05:29:02 INFO - [mozharness: 2017-03-28 12:29:02.421164Z] Finished install step (success) 05:29:02 INFO - [mozharness: 2017-03-28 12:29:02.421505Z] Running run-tests step. 05:29:02 INFO - Running pre-action listener: _resource_record_pre_action 05:29:02 INFO - Running main action method: run_tests 05:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:29:02 INFO - Python 2.7.3 05:29:02 INFO - Return code: 0 05:29:02 INFO - grabbing minidump binary from tooltool 05:29:02 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')]} 05:29:02 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 0x181c400>, '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 0x181d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181d840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:29:02 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 05:29:02 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 05:29:02 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 05:29:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:02 INFO - Return code: 0 05:29:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:29:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:29:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:29:02 INFO - ENV: RUST_BACKTRACE is now 1 05:29:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:29:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 05:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 05:29:02 INFO - Using env: {'DISPLAY': ':0', 05:29:02 INFO - 'HOME': '/home/cltbld', 05:29:02 INFO - 'LANG': 'en_US.UTF-8', 05:29:02 INFO - 'LANGUAGE': 'en_US:en', 05:29:02 INFO - 'LOGNAME': 'cltbld', 05:29:02 INFO - 'MAIL': '/var/mail/cltbld', 05:29:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:29:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:02 INFO - 'MOZ_NO_REMOTE': '1', 05:29:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:02 INFO - 'NO_EM_RESTART': '1', 05:29:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:02 INFO - 'PWD': '/builds/slave/test', 05:29:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:29:02 INFO - 'RUST_BACKTRACE': '1', 05:29:02 INFO - 'SHELL': '/bin/bash', 05:29:02 INFO - 'SHLVL': '1', 05:29:02 INFO - 'TERM': 'linux', 05:29:02 INFO - 'TMOUT': '86400', 05:29:02 INFO - 'USER': 'cltbld', 05:29:02 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877', 05:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:02 INFO - '_': '/tools/buildbot/bin/python'} 05:29:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 05:29:02 INFO - mozversion application_buildid: 20170328110348 05:29:02 INFO - mozversion application_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 05:29:02 INFO - mozversion application_display_name: Nightly 05:29:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:02 INFO - mozversion application_name: Firefox 05:29:02 INFO - mozversion application_remotingname: firefox 05:29:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:29:02 INFO - mozversion application_vendor: Mozilla 05:29:02 INFO - mozversion application_version: 55.0a1 05:29:02 INFO - mozversion platform_buildid: 20170328110348 05:29:02 INFO - mozversion platform_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 05:29:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:29:02 INFO - mozversion platform_version: 55.0a1 05:29:02 INFO - using testdate: 1490704142 05:29:02 INFO - actual date: 1490704142 05:29:02 INFO - starting webserver on 'localhost:37360' 05:29:02 INFO - SUITE-START | Running 2 tests 05:29:02 INFO - TEST-START | dromaeo_css 05:29:02 INFO - operating with platform_type : linux_ 05:29:02 INFO - Initialising browser for dromaeo_css test... 05:29:02 INFO - TEST-INFO | started process 8941 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG8PXCY/profile http://localhost:37360/getInfo.html) 05:29:06 INFO - PID 8941 | __metrics Screen width/height:1600/1200 05:29:06 INFO - PID 8941 | colorDepth:24 05:29:06 INFO - PID 8941 | Browser inner width/height: 1024/697 05:29:06 INFO - PID 8941 | __metrics 05:29:08 ERROR - PID 8941 | 1490704148004 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 05:29:08 INFO - PID 8941 | 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" 05:29:08 INFO - PID 8941 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:29:09 INFO - TEST-INFO | 8941: exit 0 05:29:09 INFO - Browser initialized. 05:29:09 INFO - Running cycle 1/1 for dromaeo_css test... 05:29:09 INFO - TEST-INFO | started process 9037 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG8PXCY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:20 INFO - PID 9037 | RSS: Main: 145735680 05:29:20 INFO - PID 9037 | 05:32:23 INFO - PID 9037 | Cycle 1(1): loaded http://localhost:37360/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37360/tests/dromaeo/cssquery-ext.html) 05:32:24 INFO - PID 9037 | RSS: Main: 249135104 05:32:24 INFO - PID 9037 | 05:34:10 INFO - PID 9037 | ************************* 05:34:10 INFO - PID 9037 | A coding exception was thrown and uncaught in a Task. 05:34:10 INFO - PID 9037 | 05:34:10 ERROR - PID 9037 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:34:10 INFO - PID 9037 | Full stack: 05:34:10 INFO - PID 9037 | ************************* 05:34:10 INFO - PID 9037 | ************************* 05:34:10 INFO - PID 9037 | A coding exception was thrown and uncaught in a Task. 05:34:10 INFO - PID 9037 | 05:34:10 ERROR - PID 9037 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:34:10 INFO - PID 9037 | Full stack: 05:34:10 INFO - PID 9037 | ************************* 05:34:10 INFO - PID 9037 | ************************* 05:34:10 INFO - PID 9037 | A coding exception was thrown and uncaught in a Task. 05:34:10 INFO - PID 9037 | 05:34:10 ERROR - PID 9037 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:34:10 INFO - PID 9037 | Full stack: 05:34:10 INFO - PID 9037 | ************************* 05:34:10 INFO - PID 9037 | ************************* 05:34:10 INFO - PID 9037 | A coding exception was thrown and uncaught in a Task. 05:34:10 INFO - PID 9037 | 05:34:10 ERROR - PID 9037 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:34:10 INFO - PID 9037 | Full stack: 05:34:10 INFO - PID 9037 | ************************* 05:35:27 INFO - PID 9037 | Cycle 1(1): loaded http://localhost:37360/tests/dromaeo/cssquery-ext.html (next: http://localhost:37360/tests/dromaeo/cssquery-jquery.html) 05:35:27 INFO - PID 9037 | RSS: Main: 239738880 05:35:27 INFO - PID 9037 | 05:38:29 INFO - PID 9037 | Cycle 1(1): loaded http://localhost:37360/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37360/tests/dromaeo/cssquery-mootools.html) 05:38:30 INFO - PID 9037 | RSS: Main: 243154944 05:38:30 INFO - PID 9037 | 05:41:32 INFO - PID 9037 | Cycle 1(1): loaded http://localhost:37360/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37360/tests/dromaeo/cssquery-prototype.html) 05:41:32 INFO - PID 9037 | RSS: Main: 206000128 05:41:32 INFO - PID 9037 | 05:44:35 INFO - PID 9037 | Cycle 1(1): loaded http://localhost:37360/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37360/tests/dromaeo/cssquery-yui.html) 05:44:36 INFO - PID 9037 | RSS: Main: 186507264 05:44:36 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | Cycle 1(1): loaded http://localhost:37360/tests/dromaeo/cssquery-yui.html (next: http://localhost:37360/tests/dromaeo/cssquery-dojo.html) 05:47:39 INFO - PID 9037 | RSS: Main: 279068672 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | __start_tp_report 05:47:39 INFO - PID 9037 | _x_x_mozilla_page_load 05:47:39 INFO - PID 9037 | _x_x_mozilla_page_load_details 05:47:39 INFO - PID 9037 | |i|pagename|runs| 05:47:39 INFO - PID 9037 | |0;dojo.html;3290.3090351026285;3290.4735242361226;3323.900282991511;3328.4508056170725;3351.028201821472;5626.987142314381;5686.800961966332;5704.258446401972;5733.48398644123;5734.053881109617;5694.800681976131;5703.857403564913;5748.396418376073;5757.078867381219;5846.824595262149;4893.951060489403;5061.569766569846;5127.3078134451835;5157.742112894341;5170.974145129269;5181;5231.163013917774;5244.947550524484;5249.816256431026;5298;5096.261042148886;5102.948970510284;5122.743862806845;5136.357955255593;5172.379314482248;5123.692578445305;5143.048536020848;5143.12566863634;5144.768485418165;5149.433562308143;5057.898842023175;5080.441151473335;5094.1594636884865;5096.9490305097215;5105.055564720501;5667.4615911488345;5668.43315668434;5675.2054712340305;5680.715964201815;5693;6135.938640613582;6163.691815409212;6169.167162433059;6172.135900973823;6173.9073913891325;6092.205822586594;6110.050511076634;6136.27400570097;6156.7845125420845;6176.073588961647;5775.422457754233;5777.335606405233;5779.797707080274;5781.36404995446;5803.796867109673;6139.584134579375;6344.080108384258;6354.332795056545;6359.8092057238355;6364.809055728336;6002.069679199643;6019.067044608093;6093.969530152321;6102;6110.511159107261;6156.907646385308;6157.415045570664;6162.414570615784;6169.167162433014;6174.722137503823;6158.876822463615;6162.661053642093;6166.105914642441;6167.383261673886;6180.011198208355;6010.579259451883;6081.695915204222;6082.482988945901;6093.141993208983;6146.815595532141;4725.810967561328;4728.692634978715;4738.715677059387;4749;4772.546608072227;4176.4570605821655;4187.644050255702;4195.077083041726;4200.5799420058365;4222.303319952235;5679.914801277984;5694.174344720075;5801.593888427854;5803.042497987784;5803.361630220672;6081.483073938676;6084.574079814371;6127.693615319216;6131.14164017038;6134.509239260938;5785.652860828363;5805.332386775491;5839.445252700986;5842.269716285465;5843.065109582446;5103.591712662978;5278.392984806719;5290.206469029677;5290.735463226823;5301.443348448434;6322.462590679752;6336.683165841781;6435.678216089271;6448.097266382711;6450.9354906450335;6203.410675985774;6217.844553886189;6220.72006759706;6221.2845522763655;6228.22147231596;6205.627662340274;6212.47193988516;6341.714622842075;6359.904601431075;6445.838854028687;6112.746658179666;6114.479856340571;6114.996965385453;6115.149072927182;6122.428649698624;6078.787242446493;6100.338962862879;6139.6009259398;6173.259208894961;6187.319394866471;6033.034714445667;6050.183225264709;6058;6087.604305720114;6108.847278818065;6017.638941663514;6032.849178770566;6033.909491357721;6037.215162028908;6043.969780151247;5947.04847224442;6008.459238668541;6026.216591843032;6035.849103772441;6039.214902062704;6019.217501724747;6071.423214794588;6094.6223337267265;6137.7851775187655;6143.907841382472;5735.082386818089;5839.591228613956;5839.751695597311;5851.1223316502865;5903.321118071477 05:47:39 INFO - PID 9037 | |1;ext.html;687.9071325371074;738.412961695452;754.6038329876814;1113.2151832957766;1118.6308518188998;29308.85345573272;29928.551071733913;29942.85028574857;35885.64114358855;36694.63305366946;43166.35250471246;43565;43623.3456498152;43740.781296093475;43797.78101109498;8031.477953933005;8121.106678265386;8169.836603267931;8173;8179.059408168047;6086.147939288503;6184.628922264678;8058.35533157346;8213.630386632616;8215.424920255584;8252.504849709037;8324.83350332993;8332.375071869603;8349.33205343571;8350.206730360605;8191.50850948942;8294.8341033179;8309.293710034653;8310.127436619188;8323.500589964622;7878.330341920943;7919.96040019802;8083.070446898564;8094.919050809475;8101.893507688043;40143.7992810037;40392.39411408831;40454.595454045375;40487.392689109685;40558.769267690266;2926.8682909268923;2931.662858771247;2935.5890175375466;2974.657914339857;2985.925351866199;26193.476130477284;28245.15264542067;28535.714642853512;28553.14340569786;28612.713872861423;3557.1640664443835;3568.946465803015;3568.964310356889;3584.731145164123;3607.8556857725657;192130;192298;192493;192621;192933;46292.537074629494;46330.30504542434;46701;46724.7663761679;46728;48822;48881.26678099831;48889.26666100011;48896;48948.75525622349;13875.306234688225;14372.640683982983;14391.92804035994;14435.783463248059;14459.92770036164;11386.146039047104;11401.600943966921;11408.030317423112;11409;11413.543458261742;12386.938065309616;12617.684557885943;13833.930830345784;13913;14152.150870947777;5415.187721841755;5417.149478563142;5608.775648974077;5632.802851900164;5684.886302274014;1033.6537260017978;1048.260976011925;1067.4182570499097;1076.8869268726828;1095.4084794211037;25519.87240063825;25598.360040999123;25623.48753024929;25664.615030774552;25670.743292566836;1121.7476067884825;1140.6178930058522;1156.1271240213584;1157.1263695909533;1164.1501703756305;13000.934995324962;13209.273489957952;13348.532801351907;13441.193528388329;13453.663658408617;319685;324018.0173874775;324244;324568;324597;54077.1888421674;54233.20367574232;54416.18375724367;54450.455495445334;54480.727596361765;1383.7855132454486;1453.229788212249;1456.9271536423348;1472.0431719382486;1480.6224412774889;22443.520801845672;24060.37569988027;24682.13612523553;24776.256712297938;24951;38915.6108438912;38937;38939.80530097331;38976.2204755909;39054.414183787834;279711;280999;282909;283063;283126;26079.348016299744;34123.829380852934;34269.314613708084;34488;34489.65510344865;38300.23399531938;38500.42249366206;38833;38857.805710972396;39549.40675889922;29167.7083229165;29490.410191796473;29530.852345738134;29571.26071848221;30515.389692205586;28617.28456788597;29473.11580652584;29641.5553766698;29700.702992969793;30046;34456.13859653529;34663.30673386468;34823.303533929684;34847;35834.64165358313;28290.85854570714;28633.28416789597;28688.856555717088;29517.557236641038;31080.378392432478;24618.630720539564;25416.027233599718;25807.483850323264;25811.354716132246;26072.608910865973 05:47:39 INFO - PID 9037 | |2;jquery.html;394.2214127098982;425.72616578511236;447.06786350459294;452.36442797869006;454.5159405233427;18679.626407471842;19918.203271869126;20549.691754623655;20681.793182068173;20935.95320233988;20432;20619.484512887186;20934.790652093474;21346.57306853862;21567.67648485274;5825.475707186341;6004.549658775588;6008.789692360769;6013.368596297391;6013.518918486532;5738.913916291259;5915;5918.05311150216;5934.64392136471;5959.0763431668165;5809.912851307234;5889;5892.175095486614;5919.852003699921;5926.170336152942;5952.761889524414;6020.247469066367;6031.547633927429;6032.185654936571;6032.849178770522;5317.308749862493;5326.147816349404;5370.275012873251;5454.181872719084;5458.61789674723;26042.86978565114;26170.214893553224;26528.867355663097;27166.5925011125;27679.584806227922;1996.2114964589052;1996.8502362322884;2013.5872146210138;2037.5415531505441;2069.968950465744;15811.60470988223;16323.183840807913;16599.33602655892;17034.318627254896;17310.221040053228;1764.4353806781835;1775.4318618042232;1787.5084351803227;1799.433178548753;1832.6243120160468;104447;105452.47273763582;108109;108963.45518272437;110193.44903275513;10945.45272736376;11973.281603103842;12340.876591233973;12425.502979880885;12544.749105017849;15741.921290393475;17153.656926861393;17431.389901353392;17455.73816392755;17474.301027958994;8402.411831171881;8530.829383412422;8689.782755431039;8767.561621918878;8784.516851573097;7764.273148888452;7773.339266162327;7806.609669516615;7978.920210797818;8018.919810801817;7779.8833017504785;7924.881126783102;7935.56354400502;8028.4781489203015;8035.196480351918;2198.4943463003597;2230.8326875484404;2243.495213576965;2265.388345146834;2341.57851586716;963.2246041936309;1016.7712264740522;1017.5274083975862;1023.1047833145997;1023.2939271902363;20082.799172008385;20343.796562034488;20368.796312036986;20386.592268154553;20673.689894651594;1007.4487431802953;1011.911559525519;1030.0883717909553;1032.9225308101772;1048.3919326790444;10582;11004.229703920648;11050.889491104987;11070.114390848712;12297.631071067883;295428;302034;302098;302562;538898;81749.59125204336;106849;110521;112280.43859780813;113399;1137.0317640027297;1186.9821952670545;1226.5093962415106;1238.5355491690616;1246.1588427811373;20114.89942550278;20116;20902.16391344329;20984.160633574487;21072;36059.27881442409;37660;47435.288470672276;50319.245211321126;50442;67161.32838671551;67822.3217767816;67988.6600566994;68783.65608171927;69378;9213.76965575866;9330.626774928927;9345.626174952926;9383.436993780395;9532.473894417846;13909.513167039106;13917.234552099731;14244.07413518118;14253.572392828231;14311.928440357731;11870.703232419259;11899.405029748652;12095.461580708185;12223.022158227142;12336.321502317458;10762.192835537522;11382.317060976367;11411.315321080761;11586.420678966186;11607.245529040585;12014.33921134346;12066.517339306329;12371.443285052013;12514.12401131912;12807.29559874216;8905.154010369004;8925.928888533417;8960.641574336954;8961.893954920719;9211.309151813506;12357.505699771908;12513.749725005267;12515.12394132402;12567.748645027232;12862.848602092547 05:47:39 INFO - PID 9037 | |3;mootools.html;1289.1105137455156;1349.4534713441053;1350.291097173984;1360.5510181640059;1371.0265711344948;10218.795624087514;10514.369137851725;10559.894401055986;10639.734006649838;10646.414447205401;10219.080282774548;10277.434741089237;10337.431441270726;10337.431441270726;10441.269111162223;2285.919992800248;2320.7795259450327;2324.976750232493;2327.7090363704538;2330.88345582721;1995.2717258200726;2219.5782801267787;2312.0867257433306;2331.743508214095;2332.9533409331802;2031.2179810772884;2202.6695995600708;2240.439890027493;2248.213125406103;2249;2188.7701791312;2224.043661225672;2248.763879792631;2251.0320562158267;2255.9887200563976;2279.0428020231543;2287.8512896661664;2297.8506397084134;2299.7815207555254;2311.202635090891;9897.35667181638;9963.202943764481;10009.149222316111;10014.54934527948;10023.649172279009;5785.537157027427;6053.092036194606;6073.210482637273;6090.299615544225;6103.664298463583;3531.982340088309;3628.9294658075933;3741.4761933329355;3793.943090853639;3923.372260438316;1278.4183196645504;1284.223045057736;1314.2508770001102;1356.3964036003981;1400.3068481101818;14863.925680371529;15091.849081509265;15147.848521514865;15148.394064237527;15177.089374637446;4781.474037855833;5029.396472423259;5128.564072053917;5151.7166555839785;5164.664296820694;11715.472803723853;11720;11727.941360293144;11738.70653233676;11746.94126529379;5996.5502587305255;6008.939910600925;6015;6031.366706495843;6033.668148251888;5336.332958380202;5378.650387724863;5393.271908292409;5398.973005134949;5399.514043736082;3908.9804550977065;3984.541777695602;4041.1109555897656;4087.141700242923;4111.60939710727;1152.0840931459509;1162.4129814443652;1163;1170.5785917069848;1183.2308999150448;605.3734384911595;788.247223901173;793.2067932067932;795.7811601809433;820.4257020085963;6360.872782544323;6596.10952521413;6618.602883826986;6666.833329166712;6783.864322713518;927.332320729074;928.6563971330515;931.5807886451124;950.3775027357092;955.4840386191518;3977.3039718049226;4156.355764856452;4179.6029377209115;4187.350960601112;4327.632151267115;13827;14340.282985850667;14357.497487587885;14365.92817035929;14370.49703260381;13930.512432064828;14030.859691403159;14239.572812815632;14281.285935703174;14297.499587514387;9415.811683766286;9606.711798646053;9666.709998700051;9976.650817221362;10225;6485.8702825944165;6729.966350168218;6826.863462730618;6831.692573834289;6841.828954275984;11699.88300116988;11768.94115529417;11924.105692073303;12053.337066461429;12057.397130143283;12018.098642601664;12238.816417753918;12257.509699611915;12348.938255308665;12443.751124977269;9957.850632240667;10059.094681478702;10201.38791672502;10287.074163325038;10325.690229293134;9684.418934863648;9829;9923.156531694887;10138.138258247836;10251.846222306522;8140.511569305861;8275.62759675828;8465.61904714277;8517.190866867637;8587.398882078194;8184.959075204586;8514.191151840565;8524.403291769517;8555.187257210555;8620.267277281502;7813.41399395059;7821.92178078212;7856.057273127261;7881.211878812073;7948.562829044226;5434.212039254365;5439.5104440600535;5544.417836127149;5546.972265138648;5726.9713651431475;5869.706514674334;5938.4358485945495;5943.52451803846;5949.167116603766;6162.322144564184 05:47:39 INFO - PID 9037 | |4;prototype.html;388.7745107837455;405.1613160757234;417.60953508469584;418.75921345226493;434.0754193567701;5668.659880407178;5868.2371291732015;5939.52483801296;5972.402759724031;6019.879602407949;5640.605157638967;5859.531237501001;5869.1196320551935;5891.646501209931;5965;4617.330487079372;4617.5613316735025;4624.548989035799;4630.675852690313;4640.837570685027;4448;4591.278270648507;4622.1217968586025;4634.374359461463;4638.188317044521;4592.7014744041535;4604.585587297142;4604.894825241951;4606.838760643378;4615.238485649864;4563.7489938053395;4568.086382723452;4581.931271030937;4592.265237562007;4600.6319494440695;3865.16898866745;3940.862069827571;3949.183375647177;4124.876253712393;4146.647534959502;5689.317281926195;5722.113072473773;5728.570357223225;5728.914066289009;5755.280589926259;5240.318758561362;5249.947500524984;5279.6832190068335;5298.284731561267;5307.177387504943;5979.252593425822;6199.535034872402;6213.161223234896;6240.687965601747;6301.834160680289;4441.844535441276;4446.375769200028;4463.575960283768;4464.6874718769695;4477.664175186874;6249.468795152417;6522.902156467657;6574.671266436707;6581.440577550914;6595.604263744142;4477.485089214717;4512.2329204035395;4525.208088584551;4536.115457485758;4537.863864084083;4579.656525760514;4588.885277868013;4588.908221835612;4608.078384323148;4626.467956185082;5041.269015992661;5232.686038837682;5255;5265.52610265078;5271.973640131775;5995.48189760293;6183.721732522047;6214.25428948529;6277.96861015692;6310.810675679737;1516.5222954769213;1558.3610719604908;1563.3980917346903;1575.6454797670663;1579.289319806092;1040.2354269611828;1072.3351522056375;1073.1414868105485;1075.7848430313811;1079.0666073846066;5892.7642894283745;5908.4091590841435;5959.940400595939;5968.76124954997;5969.955257829863;6450.354964503512;6450.967745161244;6456.774012909619;6461.838454038592;6479.125318082093;5703.828885133453;5930.080837470199;5952.196453478811;5971.7611295547695;6020;3396.371671240828;3399.2351720862607;3407.114150320934;3420.572428446444;3426.880059197916;6536.411722944958;6665.666716664147;6725.428338591177;6729.6971636276485;6756.729730810811;6594.538382313288;6614.214162176186;6649.734010639617;6655.168103987002;6665.700043498055;6418.4865210783955;6643;6657.46740260778;6668.233153187445;6695.330466953363;6405.647689377129;6409.551331406757;6417.96791016042;6429.903551446639;6477.384648458389;6260.311365749672;6380.872382552416;6382.840428989312;6384.45732112757;6416.743330266737;6626.370494802986;6650.534462587766;6657.567258128206;6660.300668429745;6667.333266673488;4484.215262329145;4485.170243504962;4489.967307519354;4497.640188784824;4501.144782491448;6130.938690613037;6407.263164736115;6424.550281480251;6443.323451037761;6444.776552089522;6239.563230573998;6239.875202496016;6254.874902501833;6264.968675156777;6284.260864785445;6080.361562035922;6270.623762574261;6273.96863015682;6274.74901003973;6275.246970363585;6275.309715931153;6301.4643755279485;6305.374409714341;6313.463355614824;6320.841978950563;6315.410681620514;6318.557700960889;6370.649614271259;6372.904406434;6408.967955160194;5939.7921072763975;6205.627662340274;6215.725776215774;6217.875642487034;6241.937580624136 05:47:39 INFO - PID 9037 | |5;yui.html;735.889616557516;744.3040756892304;753.6909866954549;763.8052296664354;780.8672525670636;16065.75901361479;16301;16339.34642614294;16535.17324133793;16565.585860353465;15213.163276019815;15321.92339038306;15335.616609584766;15343.69312613747;15350.769738453931;821.765796747927;822.5434883639577;836.2139588786551;840.0297656207091;845.0028965820329;841.8526757817388;844.995775021124;845.344857735257;846.2299307630058;846.3609974469267;888.2006194425005;890.3856339126015;891.2825175733532;891.425030855098;892.3084609427681;886.091755950153;889.4174315823146;889.4263200235844;890.4078787606234;892.3173772064376;652.8367908022994;653.1737352249409;653.3531803514534;653.7123665587127;653.8071268975664;15789.763153552705;16067.678646427006;16150.192490375552;16806.15969201535;17113.91443042777;14394.424223031067;15670.451534196362;15747.68504629901;15918.9204053979;16444.75332870008;11538.59614913474;11724.772293264337;11965.282083074957;12277.938610306981;13028;3194.0577529628818;3196.216926852936;3253.024092772159;3263.461528847737;3294.4728843385183;84158.57920710418;84383.57808210982;84418.60118085412;84888.57555712244;85466.57266713689;12436.440360183813;12439.502419903283;12513.624591262094;12569.183003104768;12740.490380384867;24729.1344802931;25005.87497062503;25066.498670026496;25176.37059073538;25299;8038.799030024296;8130.512169269863;8270.586470676562;8311.95844020776;8505.489670619783;4217.578242175758;4243.108947121077;4339.956600434019;4355.368471571668;4429.689921705514;4138.91722165555;4147.605977432139;4158.480189976253;4171.91656166875;4218.156368726267;724.3372314332432;726.2592156000956;731.4952682648956;737.5832654550162;744.0624812735991;648.5071345777243;661.6724721262909;661.9309814649307;667.4660271782554;689.6344937183301;9567.665131720356;10193.541290641939;10215.846762298572;10327.483625818679;11007.284526505751;655.9311272316434;660.323168752033;662.986740265192;674.6862708840413;675.321302091395;4717.820544863784;4943.480934501826;4960.454350021495;4984.501549845059;4990.126727822617;76769;78913;79659.60170199112;79797;79950.20049799544;68199.65900170567;68710.31289687139;70136;70655.29344706488;71031;61482;61855.381446185864;62445.37554624487;62771.686141569;63065.369346305946;10675.519601618102;10689.839652405064;10719.196060295664;11249.71875703114;11668.416579170838;28825.42349153047;29238;29636.555451668675;30938.381232375676;31732.841335793175;51149.488505114474;51185;51345.743271283405;51580;53540.46459535355;13690.45238190501;13751.902663231318;13905.304734763424;13959.651008724457;14076.436942522594;15036.398544058553;15375.701360379302;15384.615384615474;15449.922750386177;15525.146116963675;14427.3507692152;14666.486672966395;15140.621484462976;15814.841851581337;15974.68050639004;13433.596992090253;13477.258750768802;13651.11267767592;13889.097208681404;14394.784078239045;14382.424703011762;14973.123759988412;15096.773548396563;15459.304331304893;15599.844001559839;9318.440893546409;9641.903580964381;9884.901150988399;9983.500824958577;10086.092251697382;10813.351198928089;11180.329180249211;11331;11425.37160456183;11484.712882178012 05:47:39 INFO - PID 9037 | __end_tp_report 05:47:39 INFO - PID 9037 | __start_cc_report 05:47:39 INFO - PID 9037 | _x_x_mozilla_cycle_collect,1560 05:47:39 INFO - PID 9037 | __end_cc_report 05:47:39 INFO - PID 9037 | __startTimestamp1490705259371__endTimestamp 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | ------- Summary: start ------- 05:47:39 INFO - PID 9037 | Number of tests: 6 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | [#0] dojo.html Cycles:165 Average:5699.88 Median:6008.46 stddev:663.26 (11.0%) stddev-sans-first:637.79 05:47:39 INFO - PID 9037 | Values: 3290.3 3290.5 3323.9 3328.5 3351.0 5627.0 5686.8 5704.3 5733.5 5734.1 5694.8 5703.9 5748.4 5757.1 5846.8 4894.0 5061.6 5127.3 5157.7 5171.0 5181.0 5231.2 5244.9 5249.8 5298.0 5096.3 5102.9 5122.7 5136.4 5172.4 5123.7 5143.0 5143.1 5144.8 5149.4 5057.9 5080.4 5094.2 5096.9 5105.1 5667.5 5668.4 5675.2 5680.7 5693.0 6135.9 6163.7 6169.2 6172.1 6173.9 6092.2 6110.1 6136.3 6156.8 6176.1 5775.4 5777.3 5779.8 5781.4 5803.8 6139.6 6344.1 6354.3 6359.8 6364.8 6002.1 6019.1 6094.0 6102.0 6110.5 6156.9 6157.4 6162.4 6169.2 6174.7 6158.9 6162.7 6166.1 6167.4 6180.0 6010.6 6081.7 6082.5 6093.1 6146.8 4725.8 4728.7 4738.7 4749.0 4772.5 4176.5 4187.6 4195.1 4200.6 4222.3 5679.9 5694.2 5801.6 5803.0 5803.4 6081.5 6084.6 6127.7 6131.1 6134.5 5785.7 5805.3 5839.4 5842.3 5843.1 5103.6 5278.4 5290.2 5290.7 5301.4 6322.5 6336.7 6435.7 6448.1 6450.9 6203.4 6217.8 6220.7 6221.3 6228.2 6205.6 6212.5 6341.7 6359.9 6445.8 6112.7 6114.5 6115.0 6115.1 6122.4 6078.8 6100.3 6139.6 6173.3 6187.3 6033.0 6050.2 6058.0 6087.6 6108.8 6017.6 6032.8 6033.9 6037.2 6044.0 5947.0 6008.5 6026.2 6035.8 6039.2 6019.2 6071.4 6094.6 6137.8 6143.9 5735.1 5839.6 5839.8 5851.1 5903.3 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | [#1] ext.html Cycles:180 Average:41951.67 Median:175382.69 stddev:71220.00 (40.6%) stddev-sans-first:71352.40 05:47:39 INFO - PID 9037 | Values: 687.9 738.4 754.6 1113.2 1118.6 29308.9 29928.6 29942.9 35885.6 36694.6 43166.4 43565.0 43623.3 43740.8 43797.8 8031.5 8121.1 8169.8 8173.0 8179.1 6086.1 6184.6 8058.4 8213.6 8215.4 8252.5 8324.8 8332.4 8349.3 8350.2 8191.5 8294.8 8309.3 8310.1 8323.5 7878.3 7920.0 8083.1 8094.9 8101.9 40143.8 40392.4 40454.6 40487.4 40558.8 2926.9 2931.7 2935.6 2974.7 2985.9 26193.5 28245.2 28535.7 28553.1 28612.7 3557.2 3568.9 3569.0 3584.7 3607.9 192130.0 192298.0 192493.0 192621.0 192933.0 46292.5 46330.3 46701.0 46724.8 46728.0 48822.0 48881.3 48889.3 48896.0 48948.8 13875.3 14372.6 14391.9 14435.8 14459.9 11386.1 11401.6 11408.0 11409.0 11413.5 12386.9 12617.7 13833.9 13913.0 14152.2 5415.2 5417.1 5608.8 5632.8 5684.9 1033.7 1048.3 1067.4 1076.9 1095.4 25519.9 25598.4 25623.5 25664.6 25670.7 1121.7 1140.6 1156.1 1157.1 1164.2 13000.9 13209.3 13348.5 13441.2 13453.7 319685.0 324018.0 324244.0 324568.0 324597.0 54077.2 54233.2 54416.2 54450.5 54480.7 1383.8 1453.2 1456.9 1472.0 1480.6 22443.5 24060.4 24682.1 24776.3 24951.0 38915.6 38937.0 38939.8 38976.2 39054.4 279711.0 280999.0 282909.0 283063.0 283126.0 26079.3 34123.8 34269.3 34488.0 34489.7 38300.2 38500.4 38833.0 38857.8 39549.4 29167.7 29490.4 29530.9 29571.3 30515.4 28617.3 29473.1 29641.6 29700.7 30046.0 34456.1 34663.3 34823.3 34847.0 35834.6 28290.9 28633.3 28688.9 29517.6 31080.4 24618.6 25416.0 25807.5 25811.4 26072.6 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | [#2] jquery.html Cycles:180 Average:27309.99 Median:11486.07 stddev:61961.00 (539.4%) stddev-sans-first:62101.86 05:47:39 INFO - PID 9037 | Values: 394.2 425.7 447.1 452.4 454.5 18679.6 19918.2 20549.7 20681.8 20936.0 20432.0 20619.5 20934.8 21346.6 21567.7 5825.5 6004.5 6008.8 6013.4 6013.5 5738.9 5915.0 5918.1 5934.6 5959.1 5809.9 5889.0 5892.2 5919.9 5926.2 5952.8 6020.2 6031.5 6032.2 6032.8 5317.3 5326.1 5370.3 5454.2 5458.6 26042.9 26170.2 26528.9 27166.6 27679.6 1996.2 1996.9 2013.6 2037.5 2070.0 15811.6 16323.2 16599.3 17034.3 17310.2 1764.4 1775.4 1787.5 1799.4 1832.6 104447.0 105452.5 108109.0 108963.5 110193.4 10945.5 11973.3 12340.9 12425.5 12544.7 15741.9 17153.7 17431.4 17455.7 17474.3 8402.4 8530.8 8689.8 8767.6 8784.5 7764.3 7773.3 7806.6 7978.9 8018.9 7779.9 7924.9 7935.6 8028.5 8035.2 2198.5 2230.8 2243.5 2265.4 2341.6 963.2 1016.8 1017.5 1023.1 1023.3 20082.8 20343.8 20368.8 20386.6 20673.7 1007.4 1011.9 1030.1 1032.9 1048.4 10582.0 11004.2 11050.9 11070.1 12297.6 295428.0 302034.0 302098.0 302562.0 538898.0 81749.6 106849.0 110521.0 112280.4 113399.0 1137.0 1187.0 1226.5 1238.5 1246.2 20114.9 20116.0 20902.2 20984.2 21072.0 36059.3 37660.0 47435.3 50319.2 50442.0 67161.3 67822.3 67988.7 68783.7 69378.0 9213.8 9330.6 9345.6 9383.4 9532.5 13909.5 13917.2 14244.1 14253.6 14311.9 11870.7 11899.4 12095.5 12223.0 12336.3 10762.2 11382.3 11411.3 11586.4 11607.2 12014.3 12066.5 12371.4 12514.1 12807.3 8905.2 8925.9 8960.6 8961.9 9211.3 12357.5 12513.7 12515.1 12567.7 12862.8 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | [#3] mootools.html Cycles:180 Average:6692.21 Median:3580.46 stddev:4194.49 (117.1%) stddev-sans-first:4186.60 05:47:39 INFO - PID 9037 | Values: 1289.1 1349.5 1350.3 1360.6 1371.0 10218.8 10514.4 10559.9 10639.7 10646.4 10219.1 10277.4 10337.4 10337.4 10441.3 2285.9 2320.8 2325.0 2327.7 2330.9 1995.3 2219.6 2312.1 2331.7 2333.0 2031.2 2202.7 2240.4 2248.2 2249.0 2188.8 2224.0 2248.8 2251.0 2256.0 2279.0 2287.9 2297.9 2299.8 2311.2 9897.4 9963.2 10009.1 10014.5 10023.6 5785.5 6053.1 6073.2 6090.3 6103.7 3532.0 3628.9 3741.5 3793.9 3923.4 1278.4 1284.2 1314.3 1356.4 1400.3 14863.9 15091.8 15147.8 15148.4 15177.1 4781.5 5029.4 5128.6 5151.7 5164.7 11715.5 11720.0 11727.9 11738.7 11746.9 5996.6 6008.9 6015.0 6031.4 6033.7 5336.3 5378.7 5393.3 5399.0 5399.5 3909.0 3984.5 4041.1 4087.1 4111.6 1152.1 1162.4 1163.0 1170.6 1183.2 605.4 788.2 793.2 795.8 820.4 6360.9 6596.1 6618.6 6666.8 6783.9 927.3 928.7 931.6 950.4 955.5 3977.3 4156.4 4179.6 4187.4 4327.6 13827.0 14340.3 14357.5 14365.9 14370.5 13930.5 14030.9 14239.6 14281.3 14297.5 9415.8 9606.7 9666.7 9976.7 10225.0 6485.9 6730.0 6826.9 6831.7 6841.8 11699.9 11768.9 11924.1 12053.3 12057.4 12018.1 12238.8 12257.5 12348.9 12443.8 9957.9 10059.1 10201.4 10287.1 10325.7 9684.4 9829.0 9923.2 10138.1 10251.8 8140.5 8275.6 8465.6 8517.2 8587.4 8185.0 8514.2 8524.4 8555.2 8620.3 7813.4 7821.9 7856.1 7881.2 7948.6 5434.2 5439.5 5544.4 5547.0 5727.0 5869.7 5938.4 5943.5 5949.2 6162.3 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | [#4] prototype.html Cycles:180 Average:5254.00 Median:5939.66 stddev:1559.27 (26.3%) stddev-sans-first:1520.28 05:47:39 INFO - PID 9037 | Values: 388.8 405.2 417.6 418.8 434.1 5668.7 5868.2 5939.5 5972.4 6019.9 5640.6 5859.5 5869.1 5891.6 5965.0 4617.3 4617.6 4624.5 4630.7 4640.8 4448.0 4591.3 4622.1 4634.4 4638.2 4592.7 4604.6 4604.9 4606.8 4615.2 4563.7 4568.1 4581.9 4592.3 4600.6 3865.2 3940.9 3949.2 4124.9 4146.6 5689.3 5722.1 5728.6 5728.9 5755.3 5240.3 5249.9 5279.7 5298.3 5307.2 5979.3 6199.5 6213.2 6240.7 6301.8 4441.8 4446.4 4463.6 4464.7 4477.7 6249.5 6522.9 6574.7 6581.4 6595.6 4477.5 4512.2 4525.2 4536.1 4537.9 4579.7 4588.9 4588.9 4608.1 4626.5 5041.3 5232.7 5255.0 5265.5 5272.0 5995.5 6183.7 6214.3 6278.0 6310.8 1516.5 1558.4 1563.4 1575.6 1579.3 1040.2 1072.3 1073.1 1075.8 1079.1 5892.8 5908.4 5959.9 5968.8 5970.0 6450.4 6451.0 6456.8 6461.8 6479.1 5703.8 5930.1 5952.2 5971.8 6020.0 3396.4 3399.2 3407.1 3420.6 3426.9 6536.4 6665.7 6725.4 6729.7 6756.7 6594.5 6614.2 6649.7 6655.2 6665.7 6418.5 6643.0 6657.5 6668.2 6695.3 6405.6 6409.6 6418.0 6429.9 6477.4 6260.3 6380.9 6382.8 6384.5 6416.7 6626.4 6650.5 6657.6 6660.3 6667.3 4484.2 4485.2 4490.0 4497.6 4501.1 6130.9 6407.3 6424.6 6443.3 6444.8 6239.6 6239.9 6254.9 6265.0 6284.3 6080.4 6270.6 6274.0 6274.7 6275.2 6275.3 6301.5 6305.4 6313.5 6320.8 6315.4 6318.6 6370.6 6372.9 6409.0 5939.8 6205.6 6215.7 6217.9 6241.9 05:47:39 INFO - PID 9037 | 05:47:39 INFO - PID 9037 | [#5] yui.html Cycles:180 Average:17733.62 Median:4230.63 stddev:22452.24 (530.7%) stddev-sans-first:22478.94 05:47:39 INFO - PID 9037 | Values: 735.9 744.3 753.7 763.8 780.9 16065.8 16301.0 16339.3 16535.2 16565.6 15213.2 15321.9 15335.6 15343.7 15350.8 821.8 822.5 836.2 840.0 845.0 841.9 845.0 845.3 846.2 846.4 888.2 890.4 891.3 891.4 892.3 886.1 889.4 889.4 890.4 892.3 652.8 653.2 653.4 653.7 653.8 15789.8 16067.7 16150.2 16806.2 17113.9 14394.4 15670.5 15747.7 15918.9 16444.8 11538.6 11724.8 11965.3 12277.9 13028.0 3194.1 3196.2 3253.0 3263.5 3294.5 84158.6 84383.6 84418.6 84888.6 85466.6 12436.4 12439.5 12513.6 12569.2 12740.5 24729.1 25005.9 25066.5 25176.4 25299.0 8038.8 8130.5 8270.6 8312.0 8505.5 4217.6 4243.1 4340.0 4355.4 4429.7 4138.9 4147.6 4158.5 4171.9 4218.2 724.3 726.3 731.5 737.6 744.1 648.5 661.7 661.9 667.5 689.6 9567.7 10193.5 10215.8 10327.5 11007.3 655.9 660.3 663.0 674.7 675.3 4717.8 4943.5 4960.5 4984.5 4990.1 76769.0 78913.0 79659.6 79797.0 79950.2 68199.7 68710.3 70136.0 70655.3 71031.0 61482.0 61855.4 62445.4 62771.7 63065.4 10675.5 10689.8 10719.2 11249.7 11668.4 28825.4 29238.0 29636.6 30938.4 31732.8 51149.5 51185.0 51345.7 51580.0 53540.5 13690.5 13751.9 13905.3 13959.7 14076.4 15036.4 15375.7 15384.6 15449.9 15525.1 14427.4 14666.5 15140.6 15814.8 15974.7 13433.6 13477.3 13651.1 13889.1 14394.8 14382.4 14973.1 15096.8 15459.3 15599.8 9318.4 9641.9 9884.9 9983.5 10086.1 10813.4 11180.3 11331.0 11425.4 11484.7 05:47:39 INFO - PID 9037 | -------- Summary: end -------- 05:47:39 INFO - PID 9037 | 05:47:40 INFO - TEST-INFO | 9037: exit 0 05:47:40 INFO - TEST-OK | dromaeo_css | took 1117528ms 05:47:40 INFO - TEST-START | kraken 05:47:40 INFO - operating with platform_type : linux_ 05:47:40 INFO - Initialising browser for kraken test... 05:47:40 INFO - TEST-INFO | started process 9362 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYboDcz/profile http://localhost:37360/getInfo.html) 05:47:44 INFO - PID 9362 | __metrics Screen width/height:1600/1200 05:47:44 INFO - PID 9362 | colorDepth:24 05:47:44 INFO - PID 9362 | Browser inner width/height: 1024/697 05:47:44 INFO - PID 9362 | __metrics 05:47:45 ERROR - PID 9362 | 1490705265128 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 05:47:45 INFO - PID 9362 | 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" 05:47:45 INFO - PID 9362 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:47:46 INFO - TEST-INFO | 9362: exit 0 05:47:46 INFO - Browser initialized. 05:47:46 INFO - Running cycle 1/1 for kraken test... 05:47:46 INFO - TEST-INFO | started process 9457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYboDcz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:58 INFO - PID 9457 | RSS: Main: 145801216 05:47:58 INFO - PID 9457 | 05:48:00 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37360/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:01 INFO - PID 9457 | RSS: Main: 201535488 05:48:01 INFO - PID 9457 | 05:48:04 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37360/tests/kraken/driver.html?testName=audio-dft) 05:48:04 INFO - PID 9457 | RSS: Main: 181227520 05:48:04 INFO - PID 9457 | 05:48:07 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37360/tests/kraken/driver.html?testName=audio-fft) 05:48:08 INFO - PID 9457 | RSS: Main: 262565888 05:48:08 INFO - PID 9457 | 05:48:10 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37360/tests/kraken/driver.html?testName=audio-oscillator) 05:48:11 INFO - PID 9457 | RSS: Main: 173068288 05:48:11 INFO - PID 9457 | 05:48:13 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37360/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:48:13 INFO - PID 9457 | RSS: Main: 177328128 05:48:13 INFO - PID 9457 | 05:48:16 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37360/tests/kraken/driver.html?testName=imaging-darkroom) 05:48:17 INFO - PID 9457 | RSS: Main: 216260608 05:48:17 INFO - PID 9457 | 05:48:20 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37360/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:21 INFO - PID 9457 | RSS: Main: 230203392 05:48:21 INFO - PID 9457 | 05:48:24 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37360/tests/kraken/driver.html?testName=json-parse-financial) 05:48:25 INFO - PID 9457 | RSS: Main: 242003968 05:48:25 INFO - PID 9457 | 05:48:26 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37360/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:27 INFO - PID 9457 | RSS: Main: 179613696 05:48:27 INFO - PID 9457 | 05:48:28 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:29 INFO - PID 9457 | RSS: Main: 186159104 05:48:29 INFO - PID 9457 | 05:48:31 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:48:32 INFO - PID 9457 | RSS: Main: 195424256 05:48:32 INFO - PID 9457 | 05:48:34 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:48:35 INFO - PID 9457 | RSS: Main: 205012992 05:48:35 INFO - PID 9457 | 05:48:38 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:48:39 INFO - PID 9457 | RSS: Main: 201232384 05:48:39 INFO - PID 9457 | 05:48:40 INFO - PID 9457 | Cycle 1(1): loaded http://localhost:37360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37360/tests/kraken/driver.html?testName=ai-astar) 05:48:41 INFO - PID 9457 | RSS: Main: 270008320 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | __start_tp_report 05:48:41 INFO - PID 9457 | _x_x_mozilla_page_load 05:48:41 INFO - PID 9457 | _x_x_mozilla_page_load_details 05:48:41 INFO - PID 9457 | |i|pagename|runs| 05:48:41 INFO - PID 9457 | |0;ai-astar;103;110;110;110;110;110;109;111;110;110 05:48:41 INFO - PID 9457 | |1;audio-beat-detection;145;119;117;155;120;119;127;118;142;120 05:48:41 INFO - PID 9457 | |2;audio-dft;162;165;165;163;165;162;164;164;165;164 05:48:41 INFO - PID 9457 | |3;audio-fft;74;75;77;102;76;75;75;75;96;75 05:48:41 INFO - PID 9457 | |4;audio-oscillator;91;91;104;84;84;82;100;84;84;111 05:48:41 INFO - PID 9457 | |5;imaging-gaussian-blur;114;114;114;114;114;114;114;114;114;114 05:48:41 INFO - PID 9457 | |6;imaging-darkroom;149;149;148;147;149;148;150;148;149;147 05:48:41 INFO - PID 9457 | |7;imaging-desaturate;95;95;94;94;94;101;94;94;94;94 05:48:41 INFO - PID 9457 | |8;json-parse-financial;60;57;58;58;58;58;58;58;58;57 05:48:41 INFO - PID 9457 | |9;json-stringify-tinderbox;55;54;57;54;54;54;54;55;55;58 05:48:41 INFO - PID 9457 | |10;stanford-crypto-aes;81;81;89;80;83;80;82;82;83;88 05:48:41 INFO - PID 9457 | |11;stanford-crypto-ccm;132;133;128;130;148;130;126;129;170;140 05:48:41 INFO - PID 9457 | |12;stanford-crypto-pbkdf2;183;184;187;186;177;186;184;187;182;185 05:48:41 INFO - PID 9457 | |13;stanford-crypto-sha256-iterative;68;66;66;67;65;66;66;65;65;64 05:48:41 INFO - PID 9457 | __end_tp_report 05:48:41 INFO - PID 9457 | __start_cc_report 05:48:41 INFO - PID 9457 | _x_x_mozilla_cycle_collect,1755 05:48:41 INFO - PID 9457 | __end_cc_report 05:48:41 INFO - PID 9457 | __startTimestamp1490705321238__endTimestamp 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | ------- Summary: start ------- 05:48:41 INFO - PID 9457 | Number of tests: 14 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:2.26 (2.1%) stddev-sans-first:0.50 05:48:41 INFO - PID 9457 | Values: 103.0 110.0 110.0 110.0 110.0 110.0 109.0 111.0 110.0 110.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:123.50 stddev:13.85 (11.2%) stddev-sans-first:13.29 05:48:41 INFO - PID 9457 | Values: 145.0 119.0 117.0 155.0 120.0 119.0 127.0 118.0 142.0 120.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.50 stddev:1.20 (0.7%) stddev-sans-first:1.05 05:48:41 INFO - PID 9457 | Values: 162.0 165.0 165.0 163.0 165.0 162.0 164.0 164.0 165.0 164.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#3] audio-fft Cycles:10 Average:80.00 Median:75.00 stddev:10.14 (13.5%) stddev-sans-first:10.52 05:48:41 INFO - PID 9457 | Values: 74.0 75.0 77.0 102.0 76.0 75.0 75.0 75.0 96.0 75.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:84.00 stddev:10.14 (12.1%) stddev-sans-first:10.75 05:48:41 INFO - PID 9457 | Values: 91.0 91.0 104.0 84.0 84.0 82.0 100.0 84.0 84.0 111.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 05:48:41 INFO - PID 9457 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:149.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 05:48:41 INFO - PID 9457 | Values: 149.0 149.0 148.0 147.0 149.0 148.0 150.0 148.0 149.0 147.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:2.18 (2.3%) stddev-sans-first:2.32 05:48:41 INFO - PID 9457 | Values: 95.0 95.0 94.0 94.0 94.0 101.0 94.0 94.0 94.0 94.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.00 stddev:0.82 (1.4%) stddev-sans-first:0.44 05:48:41 INFO - PID 9457 | Values: 60.0 57.0 58.0 58.0 58.0 58.0 58.0 58.0 58.0 57.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#9] json-stringify-tinderbox Cycles:10 Average:55.00 Median:55.00 stddev:1.41 (2.6%) stddev-sans-first:1.50 05:48:41 INFO - PID 9457 | Values: 55.0 54.0 57.0 54.0 54.0 54.0 54.0 55.0 55.0 58.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:82.50 stddev:3.14 (3.8%) stddev-sans-first:3.26 05:48:41 INFO - PID 9457 | Values: 81.0 81.0 89.0 80.0 83.0 80.0 82.0 82.0 83.0 88.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:132.50 stddev:13.43 (10.1%) stddev-sans-first:14.14 05:48:41 INFO - PID 9457 | Values: 132.0 133.0 128.0 130.0 148.0 130.0 126.0 129.0 170.0 140.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:185.50 stddev:3.00 (1.6%) stddev-sans-first:3.15 05:48:41 INFO - PID 9457 | Values: 183.0 184.0 187.0 186.0 177.0 186.0 184.0 187.0 182.0 185.0 05:48:41 INFO - PID 9457 | 05:48:41 INFO - PID 9457 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.14 (1.7%) stddev-sans-first:0.88 05:48:41 INFO - PID 9457 | Values: 68.0 66.0 66.0 67.0 65.0 66.0 66.0 65.0 65.0 64.0 05:48:41 INFO - PID 9457 | -------- Summary: end -------- 05:48:41 INFO - PID 9457 | 05:48:42 INFO - TEST-INFO | 9457: exit 0 05:48:42 INFO - TEST-OK | kraken | took 62178ms 05:48:42 INFO - SUITE-END | took 1179s 05:48:42 INFO - Completed test suite (00:19:40) 05:48:42 INFO - javascript benchmark 05:48:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7456.856685091268, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.3090351026285, 3290.4735242361226, 3323.900282991511, 3328.4508056170725, 3351.028201821472, 5626.987142314381, 5686.800961966332, 5704.258446401972, 5733.48398644123, 5734.053881109617, 5694.800681976131, 5703.857403564913, 5748.396418376073, 5757.078867381219, 5846.824595262149, 4893.951060489403, 5061.569766569846, 5127.3078134451835, 5157.742112894341, 5170.974145129269, 5181.0, 5231.163013917774, 5244.947550524484, 5249.816256431026, 5298.0, 5096.261042148886, 5102.948970510284, 5122.743862806845, 5136.357955255593, 5172.379314482248, 5123.692578445305, 5143.048536020848, 5143.12566863634, 5144.768485418165, 5149.433562308143, 5057.898842023175, 5080.441151473335, 5094.1594636884865, 5096.9490305097215, 5105.055564720501, 5667.4615911488345, 5668.43315668434, 5675.2054712340305, 5680.715964201815, 5693.0, 6135.938640613582, 6163.691815409212, 6169.167162433059, 6172.135900973823, 6173.9073913891325, 6092.205822586594, 6110.050511076634, 6136.27400570097, 6156.7845125420845, 6176.073588961647, 5775.422457754233, 5777.335606405233, 5779.797707080274, 5781.36404995446, 5803.796867109673, 6139.584134579375, 6344.080108384258, 6354.332795056545, 6359.8092057238355, 6364.809055728336, 6002.069679199643, 6019.067044608093, 6093.969530152321, 6102.0, 6110.511159107261, 6156.907646385308, 6157.415045570664, 6162.414570615784, 6169.167162433014, 6174.722137503823, 6158.876822463615, 6162.661053642093, 6166.105914642441, 6167.383261673886, 6180.011198208355, 6010.579259451883, 6081.695915204222, 6082.482988945901, 6093.141993208983, 6146.815595532141, 4725.810967561328, 4728.692634978715, 4738.715677059387, 4749.0, 4772.546608072227, 4176.4570605821655, 4187.644050255702, 4195.077083041726, 4200.5799420058365, 4222.303319952235, 5679.914801277984, 5694.174344720075, 5801.593888427854, 5803.042497987784, 5803.361630220672, 6081.483073938676, 6084.574079814371, 6127.693615319216, 6131.14164017038, 6134.509239260938, 5785.652860828363, 5805.332386775491, 5839.445252700986, 5842.269716285465, 5843.065109582446, 5103.591712662978, 5278.392984806719, 5290.206469029677, 5290.735463226823, 5301.443348448434, 6322.462590679752, 6336.683165841781, 6435.678216089271, 6448.097266382711, 6450.9354906450335, 6203.410675985774, 6217.844553886189, 6220.72006759706, 6221.2845522763655, 6228.22147231596, 6205.627662340274, 6212.47193988516, 6341.714622842075, 6359.904601431075, 6445.838854028687, 6112.746658179666, 6114.479856340571, 6114.996965385453, 6115.149072927182, 6122.428649698624, 6078.787242446493, 6100.338962862879, 6139.6009259398, 6173.259208894961, 6187.319394866471, 6033.034714445667, 6050.183225264709, 6058.0, 6087.604305720114, 6108.847278818065, 6017.638941663514, 6032.849178770566, 6033.909491357721, 6037.215162028908, 6043.969780151247, 5947.04847224442, 6008.459238668541, 6026.216591843032, 6035.849103772441, 6039.214902062704, 6019.217501724747, 6071.423214794588, 6094.6223337267265, 6137.7851775187655, 6143.907841382472, 5735.082386818089, 5839.591228613956, 5839.751695597311, 5851.1223316502865, 5903.321118071477], "value": 5654.23056940664, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.9071325371074, 738.412961695452, 754.6038329876814, 1113.2151832957766, 1118.6308518188998, 29308.85345573272, 29928.551071733913, 29942.85028574857, 35885.64114358855, 36694.63305366946, 43166.35250471246, 43565.0, 43623.3456498152, 43740.781296093475, 43797.78101109498, 8031.477953933005, 8121.106678265386, 8169.836603267931, 8173.0, 8179.059408168047, 6086.147939288503, 6184.628922264678, 8058.35533157346, 8213.630386632616, 8215.424920255584, 8252.504849709037, 8324.83350332993, 8332.375071869603, 8349.33205343571, 8350.206730360605, 8191.50850948942, 8294.8341033179, 8309.293710034653, 8310.127436619188, 8323.500589964622, 7878.330341920943, 7919.96040019802, 8083.070446898564, 8094.919050809475, 8101.893507688043, 40143.7992810037, 40392.39411408831, 40454.595454045375, 40487.392689109685, 40558.769267690266, 2926.8682909268923, 2931.662858771247, 2935.5890175375466, 2974.657914339857, 2985.925351866199, 26193.476130477284, 28245.15264542067, 28535.714642853512, 28553.14340569786, 28612.713872861423, 3557.1640664443835, 3568.946465803015, 3568.964310356889, 3584.731145164123, 3607.8556857725657, 192130.0, 192298.0, 192493.0, 192621.0, 192933.0, 46292.537074629494, 46330.30504542434, 46701.0, 46724.7663761679, 46728.0, 48822.0, 48881.26678099831, 48889.26666100011, 48896.0, 48948.75525622349, 13875.306234688225, 14372.640683982983, 14391.92804035994, 14435.783463248059, 14459.92770036164, 11386.146039047104, 11401.600943966921, 11408.030317423112, 11409.0, 11413.543458261742, 12386.938065309616, 12617.684557885943, 13833.930830345784, 13913.0, 14152.150870947777, 5415.187721841755, 5417.149478563142, 5608.775648974077, 5632.802851900164, 5684.886302274014, 1033.6537260017978, 1048.260976011925, 1067.4182570499097, 1076.8869268726828, 1095.4084794211037, 25519.87240063825, 25598.360040999123, 25623.48753024929, 25664.615030774552, 25670.743292566836, 1121.7476067884825, 1140.6178930058522, 1156.1271240213584, 1157.1263695909533, 1164.1501703756305, 13000.934995324962, 13209.273489957952, 13348.532801351907, 13441.193528388329, 13453.663658408617, 319685.0, 324018.0173874775, 324244.0, 324568.0, 324597.0, 54077.1888421674, 54233.20367574232, 54416.18375724367, 54450.455495445334, 54480.727596361765, 1383.7855132454486, 1453.229788212249, 1456.9271536423348, 1472.0431719382486, 1480.6224412774889, 22443.520801845672, 24060.37569988027, 24682.13612523553, 24776.256712297938, 24951.0, 38915.6108438912, 38937.0, 38939.80530097331, 38976.2204755909, 39054.414183787834, 279711.0, 280999.0, 282909.0, 283063.0, 283126.0, 26079.348016299744, 34123.829380852934, 34269.314613708084, 34488.0, 34489.65510344865, 38300.23399531938, 38500.42249366206, 38833.0, 38857.805710972396, 39549.40675889922, 29167.7083229165, 29490.410191796473, 29530.852345738134, 29571.26071848221, 30515.389692205586, 28617.28456788597, 29473.11580652584, 29641.5553766698, 29700.702992969793, 30046.0, 34456.13859653529, 34663.30673386468, 34823.303533929684, 34847.0, 35834.64165358313, 28290.85854570714, 28633.28416789597, 28688.856555717088, 29517.557236641038, 31080.378392432478, 24618.630720539564, 25416.027233599718, 25807.483850323264, 25811.354716132246, 26072.608910865973], "value": 17036.32003057343, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.2214127098982, 425.72616578511236, 447.06786350459294, 452.36442797869006, 454.5159405233427, 18679.626407471842, 19918.203271869126, 20549.691754623655, 20681.793182068173, 20935.95320233988, 20432.0, 20619.484512887186, 20934.790652093474, 21346.57306853862, 21567.67648485274, 5825.475707186341, 6004.549658775588, 6008.789692360769, 6013.368596297391, 6013.518918486532, 5738.913916291259, 5915.0, 5918.05311150216, 5934.64392136471, 5959.0763431668165, 5809.912851307234, 5889.0, 5892.175095486614, 5919.852003699921, 5926.170336152942, 5952.761889524414, 6020.247469066367, 6031.547633927429, 6032.185654936571, 6032.849178770522, 5317.308749862493, 5326.147816349404, 5370.275012873251, 5454.181872719084, 5458.61789674723, 26042.86978565114, 26170.214893553224, 26528.867355663097, 27166.5925011125, 27679.584806227922, 1996.2114964589052, 1996.8502362322884, 2013.5872146210138, 2037.5415531505441, 2069.968950465744, 15811.60470988223, 16323.183840807913, 16599.33602655892, 17034.318627254896, 17310.221040053228, 1764.4353806781835, 1775.4318618042232, 1787.5084351803227, 1799.433178548753, 1832.6243120160468, 104447.0, 105452.47273763582, 108109.0, 108963.45518272437, 110193.44903275513, 10945.45272736376, 11973.281603103842, 12340.876591233973, 12425.502979880885, 12544.749105017849, 15741.921290393475, 17153.656926861393, 17431.389901353392, 17455.73816392755, 17474.301027958994, 8402.411831171881, 8530.829383412422, 8689.782755431039, 8767.561621918878, 8784.516851573097, 7764.273148888452, 7773.339266162327, 7806.609669516615, 7978.920210797818, 8018.919810801817, 7779.8833017504785, 7924.881126783102, 7935.56354400502, 8028.4781489203015, 8035.196480351918, 2198.4943463003597, 2230.8326875484404, 2243.495213576965, 2265.388345146834, 2341.57851586716, 963.2246041936309, 1016.7712264740522, 1017.5274083975862, 1023.1047833145997, 1023.2939271902363, 20082.799172008385, 20343.796562034488, 20368.796312036986, 20386.592268154553, 20673.689894651594, 1007.4487431802953, 1011.911559525519, 1030.0883717909553, 1032.9225308101772, 1048.3919326790444, 10582.0, 11004.229703920648, 11050.889491104987, 11070.114390848712, 12297.631071067883, 295428.0, 302034.0, 302098.0, 302562.0, 538898.0, 81749.59125204336, 106849.0, 110521.0, 112280.43859780813, 113399.0, 1137.0317640027297, 1186.9821952670545, 1226.5093962415106, 1238.5355491690616, 1246.1588427811373, 20114.89942550278, 20116.0, 20902.16391344329, 20984.160633574487, 21072.0, 36059.27881442409, 37660.0, 47435.288470672276, 50319.245211321126, 50442.0, 67161.32838671551, 67822.3217767816, 67988.6600566994, 68783.65608171927, 69378.0, 9213.76965575866, 9330.626774928927, 9345.626174952926, 9383.436993780395, 9532.473894417846, 13909.513167039106, 13917.234552099731, 14244.07413518118, 14253.572392828231, 14311.928440357731, 11870.703232419259, 11899.405029748652, 12095.461580708185, 12223.022158227142, 12336.321502317458, 10762.192835537522, 11382.317060976367, 11411.315321080761, 11586.420678966186, 11607.245529040585, 12014.33921134346, 12066.517339306329, 12371.443285052013, 12514.12401131912, 12807.29559874216, 8905.154010369004, 8925.928888533417, 8960.641574336954, 8961.893954920719, 9211.309151813506, 12357.505699771908, 12513.749725005267, 12515.12394132402, 12567.748645027232, 12862.848602092547], "value": 9965.807629390523, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.1105137455156, 1349.4534713441053, 1350.291097173984, 1360.5510181640059, 1371.0265711344948, 10218.795624087514, 10514.369137851725, 10559.894401055986, 10639.734006649838, 10646.414447205401, 10219.080282774548, 10277.434741089237, 10337.431441270726, 10337.431441270726, 10441.269111162223, 2285.919992800248, 2320.7795259450327, 2324.976750232493, 2327.7090363704538, 2330.88345582721, 1995.2717258200726, 2219.5782801267787, 2312.0867257433306, 2331.743508214095, 2332.9533409331802, 2031.2179810772884, 2202.6695995600708, 2240.439890027493, 2248.213125406103, 2249.0, 2188.7701791312, 2224.043661225672, 2248.763879792631, 2251.0320562158267, 2255.9887200563976, 2279.0428020231543, 2287.8512896661664, 2297.8506397084134, 2299.7815207555254, 2311.202635090891, 9897.35667181638, 9963.202943764481, 10009.149222316111, 10014.54934527948, 10023.649172279009, 5785.537157027427, 6053.092036194606, 6073.210482637273, 6090.299615544225, 6103.664298463583, 3531.982340088309, 3628.9294658075933, 3741.4761933329355, 3793.943090853639, 3923.372260438316, 1278.4183196645504, 1284.223045057736, 1314.2508770001102, 1356.3964036003981, 1400.3068481101818, 14863.925680371529, 15091.849081509265, 15147.848521514865, 15148.394064237527, 15177.089374637446, 4781.474037855833, 5029.396472423259, 5128.564072053917, 5151.7166555839785, 5164.664296820694, 11715.472803723853, 11720.0, 11727.941360293144, 11738.70653233676, 11746.94126529379, 5996.5502587305255, 6008.939910600925, 6015.0, 6031.366706495843, 6033.668148251888, 5336.332958380202, 5378.650387724863, 5393.271908292409, 5398.973005134949, 5399.514043736082, 3908.9804550977065, 3984.541777695602, 4041.1109555897656, 4087.141700242923, 4111.60939710727, 1152.0840931459509, 1162.4129814443652, 1163.0, 1170.5785917069848, 1183.2308999150448, 605.3734384911595, 788.247223901173, 793.2067932067932, 795.7811601809433, 820.4257020085963, 6360.872782544323, 6596.10952521413, 6618.602883826986, 6666.833329166712, 6783.864322713518, 927.332320729074, 928.6563971330515, 931.5807886451124, 950.3775027357092, 955.4840386191518, 3977.3039718049226, 4156.355764856452, 4179.6029377209115, 4187.350960601112, 4327.632151267115, 13827.0, 14340.282985850667, 14357.497487587885, 14365.92817035929, 14370.49703260381, 13930.512432064828, 14030.859691403159, 14239.572812815632, 14281.285935703174, 14297.499587514387, 9415.811683766286, 9606.711798646053, 9666.709998700051, 9976.650817221362, 10225.0, 6485.8702825944165, 6729.966350168218, 6826.863462730618, 6831.692573834289, 6841.828954275984, 11699.88300116988, 11768.94115529417, 11924.105692073303, 12053.337066461429, 12057.397130143283, 12018.098642601664, 12238.816417753918, 12257.509699611915, 12348.938255308665, 12443.751124977269, 9957.850632240667, 10059.094681478702, 10201.38791672502, 10287.074163325038, 10325.690229293134, 9684.418934863648, 9829.0, 9923.156531694887, 10138.138258247836, 10251.846222306522, 8140.511569305861, 8275.62759675828, 8465.61904714277, 8517.190866867637, 8587.398882078194, 8184.959075204586, 8514.191151840565, 8524.403291769517, 8555.187257210555, 8620.267277281502, 7813.41399395059, 7821.92178078212, 7856.057273127261, 7881.211878812073, 7948.562829044226, 5434.212039254365, 5439.5104440600535, 5544.417836127149, 5546.972265138648, 5726.9713651431475, 5869.706514674334, 5938.4358485945495, 5943.52451803846, 5949.167116603766, 6162.322144564184], "value": 5073.912174945558, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.7745107837455, 405.1613160757234, 417.60953508469584, 418.75921345226493, 434.0754193567701, 5668.659880407178, 5868.2371291732015, 5939.52483801296, 5972.402759724031, 6019.879602407949, 5640.605157638967, 5859.531237501001, 5869.1196320551935, 5891.646501209931, 5965.0, 4617.330487079372, 4617.5613316735025, 4624.548989035799, 4630.675852690313, 4640.837570685027, 4448.0, 4591.278270648507, 4622.1217968586025, 4634.374359461463, 4638.188317044521, 4592.7014744041535, 4604.585587297142, 4604.894825241951, 4606.838760643378, 4615.238485649864, 4563.7489938053395, 4568.086382723452, 4581.931271030937, 4592.265237562007, 4600.6319494440695, 3865.16898866745, 3940.862069827571, 3949.183375647177, 4124.876253712393, 4146.647534959502, 5689.317281926195, 5722.113072473773, 5728.570357223225, 5728.914066289009, 5755.280589926259, 5240.318758561362, 5249.947500524984, 5279.6832190068335, 5298.284731561267, 5307.177387504943, 5979.252593425822, 6199.535034872402, 6213.161223234896, 6240.687965601747, 6301.834160680289, 4441.844535441276, 4446.375769200028, 4463.575960283768, 4464.6874718769695, 4477.664175186874, 6249.468795152417, 6522.902156467657, 6574.671266436707, 6581.440577550914, 6595.604263744142, 4477.485089214717, 4512.2329204035395, 4525.208088584551, 4536.115457485758, 4537.863864084083, 4579.656525760514, 4588.885277868013, 4588.908221835612, 4608.078384323148, 4626.467956185082, 5041.269015992661, 5232.686038837682, 5255.0, 5265.52610265078, 5271.973640131775, 5995.48189760293, 6183.721732522047, 6214.25428948529, 6277.96861015692, 6310.810675679737, 1516.5222954769213, 1558.3610719604908, 1563.3980917346903, 1575.6454797670663, 1579.289319806092, 1040.2354269611828, 1072.3351522056375, 1073.1414868105485, 1075.7848430313811, 1079.0666073846066, 5892.7642894283745, 5908.4091590841435, 5959.940400595939, 5968.76124954997, 5969.955257829863, 6450.354964503512, 6450.967745161244, 6456.774012909619, 6461.838454038592, 6479.125318082093, 5703.828885133453, 5930.080837470199, 5952.196453478811, 5971.7611295547695, 6020.0, 3396.371671240828, 3399.2351720862607, 3407.114150320934, 3420.572428446444, 3426.880059197916, 6536.411722944958, 6665.666716664147, 6725.428338591177, 6729.6971636276485, 6756.729730810811, 6594.538382313288, 6614.214162176186, 6649.734010639617, 6655.168103987002, 6665.700043498055, 6418.4865210783955, 6643.0, 6657.46740260778, 6668.233153187445, 6695.330466953363, 6405.647689377129, 6409.551331406757, 6417.96791016042, 6429.903551446639, 6477.384648458389, 6260.311365749672, 6380.872382552416, 6382.840428989312, 6384.45732112757, 6416.743330266737, 6626.370494802986, 6650.534462587766, 6657.567258128206, 6660.300668429745, 6667.333266673488, 4484.215262329145, 4485.170243504962, 4489.967307519354, 4497.640188784824, 4501.144782491448, 6130.938690613037, 6407.263164736115, 6424.550281480251, 6443.323451037761, 6444.776552089522, 6239.563230573998, 6239.875202496016, 6254.874902501833, 6264.968675156777, 6284.260864785445, 6080.361562035922, 6270.623762574261, 6273.96863015682, 6274.74901003973, 6275.246970363585, 6275.309715931153, 6301.4643755279485, 6305.374409714341, 6313.463355614824, 6320.841978950563, 6315.410681620514, 6318.557700960889, 6370.649614271259, 6372.904406434, 6408.967955160194, 5939.7921072763975, 6205.627662340274, 6215.725776215774, 6217.875642487034, 6241.937580624136], "value": 4768.363632393862, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.889616557516, 744.3040756892304, 753.6909866954549, 763.8052296664354, 780.8672525670636, 16065.75901361479, 16301.0, 16339.34642614294, 16535.17324133793, 16565.585860353465, 15213.163276019815, 15321.92339038306, 15335.616609584766, 15343.69312613747, 15350.769738453931, 821.765796747927, 822.5434883639577, 836.2139588786551, 840.0297656207091, 845.0028965820329, 841.8526757817388, 844.995775021124, 845.344857735257, 846.2299307630058, 846.3609974469267, 888.2006194425005, 890.3856339126015, 891.2825175733532, 891.425030855098, 892.3084609427681, 886.091755950153, 889.4174315823146, 889.4263200235844, 890.4078787606234, 892.3173772064376, 652.8367908022994, 653.1737352249409, 653.3531803514534, 653.7123665587127, 653.8071268975664, 15789.763153552705, 16067.678646427006, 16150.192490375552, 16806.15969201535, 17113.91443042777, 14394.424223031067, 15670.451534196362, 15747.68504629901, 15918.9204053979, 16444.75332870008, 11538.59614913474, 11724.772293264337, 11965.282083074957, 12277.938610306981, 13028.0, 3194.0577529628818, 3196.216926852936, 3253.024092772159, 3263.461528847737, 3294.4728843385183, 84158.57920710418, 84383.57808210982, 84418.60118085412, 84888.57555712244, 85466.57266713689, 12436.440360183813, 12439.502419903283, 12513.624591262094, 12569.183003104768, 12740.490380384867, 24729.1344802931, 25005.87497062503, 25066.498670026496, 25176.37059073538, 25299.0, 8038.799030024296, 8130.512169269863, 8270.586470676562, 8311.95844020776, 8505.489670619783, 4217.578242175758, 4243.108947121077, 4339.956600434019, 4355.368471571668, 4429.689921705514, 4138.91722165555, 4147.605977432139, 4158.480189976253, 4171.91656166875, 4218.156368726267, 724.3372314332432, 726.2592156000956, 731.4952682648956, 737.5832654550162, 744.0624812735991, 648.5071345777243, 661.6724721262909, 661.9309814649307, 667.4660271782554, 689.6344937183301, 9567.665131720356, 10193.541290641939, 10215.846762298572, 10327.483625818679, 11007.284526505751, 655.9311272316434, 660.323168752033, 662.986740265192, 674.6862708840413, 675.321302091395, 4717.820544863784, 4943.480934501826, 4960.454350021495, 4984.501549845059, 4990.126727822617, 76769.0, 78913.0, 79659.60170199112, 79797.0, 79950.20049799544, 68199.65900170567, 68710.31289687139, 70136.0, 70655.29344706488, 71031.0, 61482.0, 61855.381446185864, 62445.37554624487, 62771.686141569, 63065.369346305946, 10675.519601618102, 10689.839652405064, 10719.196060295664, 11249.71875703114, 11668.416579170838, 28825.42349153047, 29238.0, 29636.555451668675, 30938.381232375676, 31732.841335793175, 51149.488505114474, 51185.0, 51345.743271283405, 51580.0, 53540.46459535355, 13690.45238190501, 13751.902663231318, 13905.304734763424, 13959.651008724457, 14076.436942522594, 15036.398544058553, 15375.701360379302, 15384.615384615474, 15449.922750386177, 15525.146116963675, 14427.3507692152, 14666.486672966395, 15140.621484462976, 15814.841851581337, 15974.68050639004, 13433.596992090253, 13477.258750768802, 13651.11267767592, 13889.097208681404, 14394.784078239045, 14382.424703011762, 14973.123759988412, 15096.773548396563, 15459.304331304893, 15599.844001559839, 9318.440893546409, 9641.903580964381, 9884.901150988399, 9983.500824958577, 10086.092251697382, 10813.351198928089, 11180.329180249211, 11331.0, 11425.37160456183, 11484.712882178012], "value": 7401.65586586354, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 111.0, 110.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 117.0, 155.0, 120.0, 119.0, 127.0, 118.0, 142.0, 120.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 165.0, 163.0, 165.0, 162.0, 164.0, 164.0, 165.0, 164.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 102.0, 76.0, 75.0, 75.0, 75.0, 96.0, 75.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 104.0, 84.0, 84.0, 82.0, 100.0, 84.0, 84.0, 111.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 147.0, 149.0, 148.0, 150.0, 148.0, 149.0, 147.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 101.0, 94.0, 94.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 57.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 57.0, 54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 58.0], "value": 55.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 89.0, 80.0, 83.0, 80.0, 82.0, 82.0, 83.0, 88.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 133.0, 128.0, 130.0, 148.0, 130.0, 126.0, 129.0, 170.0, 140.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 187.0, 186.0, 177.0, 186.0, 184.0, 187.0, 182.0, 185.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 67.0, 65.0, 66.0, 66.0, 65.0, 65.0, 64.0], "value": 65.8, "unit": "score"}]}]} 05:48:43 INFO - Return code: 0 05:48:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:48:43 ERROR - # TBPL SUCCESS # 05:48:43 INFO - Running post-action listener: _resource_record_post_action 05:48:43 INFO - [mozharness: 2017-03-28 12:48:43.072816Z] Finished run-tests step (success) 05:48:43 INFO - Running post-run listener: _resource_record_post_run 05:48:43 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 0; Write bytes: 369876992; Read time: 0; Write time: 254440 05:48:43 INFO - TinderboxPrint: CPU usage
13.0% 05:48:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:48:43 INFO - TinderboxPrint: I/O write bytes / time
369,876,992 / 254,440 05:48:43 INFO - TinderboxPrint: CPU idle
8,309.4 (87.0%) 05:48:43 INFO - TinderboxPrint: CPU user
1,195.2 (12.5%) 05:48:43 INFO - TinderboxPrint: Swap in / out
0 / 0 05:48:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112050176; Read time: 0; Write time: 102636 05:48:43 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 257826816; Read time: 0; Write time: 151804 05:48:43 INFO - Running post-run listener: _upload_blobber_files 05:48:43 INFO - Blob upload gear active. 05:48:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:48:43 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:48:43 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:48:43 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:48:44 INFO - (blobuploader) - INFO - Open directory for files ... 05:48:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:48:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:48:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:44 INFO - (blobuploader) - INFO - Done attempting. 05:48:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:48:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:48:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:48 INFO - (blobuploader) - INFO - Done attempting. 05:48:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:48:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:48:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:49 INFO - (blobuploader) - INFO - Done attempting. 05:48:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:48:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:48:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:50 INFO - (blobuploader) - INFO - Done attempting. 05:48:50 INFO - (blobuploader) - INFO - Iteration through files over. 05:48:50 INFO - Return code: 0 05:48:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:48:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:48:50 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91f918e6d1b68c165353221a372dae18809486aa2c732d03ec7e4cb59dfa0a2886b75bb2c7d65d705a0e9c5b6d5767e1e137c2a95ccdfc2d132e3fa7eae0bbc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8a6582b5f10c2c5fd0c2b35ed813f99c8f038e5a6cc101fc81b72d2616c2a5abe73778748476d1afc91812f7d951db7f3ced62a11b21afa307ddf4dcb7e8720", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13fbacadd2ef22fbd1fabeb7ce29c091f2cd360d4ac3bd63ad2dcb277e70acd9fd49d6acf43db3950ad856391b5fc8a395315dd3cd38bc48241cb7217fba46c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7b028f633f8b3c5ba336496a423e964b4a128ca02a97330aab3a5afc2188a1e2ef0fe17b3756a7dda90d3cfd5c56cf89c0e1f34dce808d1be8799db46acd19"} 05:48:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:48:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:48:50 INFO - Contents: 05:48:50 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91f918e6d1b68c165353221a372dae18809486aa2c732d03ec7e4cb59dfa0a2886b75bb2c7d65d705a0e9c5b6d5767e1e137c2a95ccdfc2d132e3fa7eae0bbc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8a6582b5f10c2c5fd0c2b35ed813f99c8f038e5a6cc101fc81b72d2616c2a5abe73778748476d1afc91812f7d951db7f3ced62a11b21afa307ddf4dcb7e8720", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13fbacadd2ef22fbd1fabeb7ce29c091f2cd360d4ac3bd63ad2dcb277e70acd9fd49d6acf43db3950ad856391b5fc8a395315dd3cd38bc48241cb7217fba46c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7b028f633f8b3c5ba336496a423e964b4a128ca02a97330aab3a5afc2188a1e2ef0fe17b3756a7dda90d3cfd5c56cf89c0e1f34dce808d1be8799db46acd19"} 05:48:50 INFO - Running post-run listener: copy_logs_to_upload_dir 05:48:50 INFO - Copying logs to upload dir... 05:48:50 INFO - mkdir: /builds/slave/test/build/upload/logs 05:48:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.531073 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-28 05:48:50.182714) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:48:50.186448) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91f918e6d1b68c165353221a372dae18809486aa2c732d03ec7e4cb59dfa0a2886b75bb2c7d65d705a0e9c5b6d5767e1e137c2a95ccdfc2d132e3fa7eae0bbc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8a6582b5f10c2c5fd0c2b35ed813f99c8f038e5a6cc101fc81b72d2616c2a5abe73778748476d1afc91812f7d951db7f3ced62a11b21afa307ddf4dcb7e8720", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13fbacadd2ef22fbd1fabeb7ce29c091f2cd360d4ac3bd63ad2dcb277e70acd9fd49d6acf43db3950ad856391b5fc8a395315dd3cd38bc48241cb7217fba46c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7b028f633f8b3c5ba336496a423e964b4a128ca02a97330aab3a5afc2188a1e2ef0fe17b3756a7dda90d3cfd5c56cf89c0e1f34dce808d1be8799db46acd19"} build_url:https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011137 build_url: 'https://queue.taskcluster.net/v1/task/LCFc1Oz0QoWzeg6hD58ePw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91f918e6d1b68c165353221a372dae18809486aa2c732d03ec7e4cb59dfa0a2886b75bb2c7d65d705a0e9c5b6d5767e1e137c2a95ccdfc2d132e3fa7eae0bbc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8a6582b5f10c2c5fd0c2b35ed813f99c8f038e5a6cc101fc81b72d2616c2a5abe73778748476d1afc91812f7d951db7f3ced62a11b21afa307ddf4dcb7e8720", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13fbacadd2ef22fbd1fabeb7ce29c091f2cd360d4ac3bd63ad2dcb277e70acd9fd49d6acf43db3950ad856391b5fc8a395315dd3cd38bc48241cb7217fba46c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea7b028f633f8b3c5ba336496a423e964b4a128ca02a97330aab3a5afc2188a1e2ef0fe17b3756a7dda90d3cfd5c56cf89c0e1f34dce808d1be8799db46acd19"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:48:50.210951) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:48:50.211250) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1490704071.286432-1860950877 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:48:50.293834) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:48:50.294149) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:48:50.294440) ========= ========= Total master_lag: 0.17 =========