builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-046 starttime: 1490721920.62 results: success (0) revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.624400) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.624853) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.625135) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.640480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.640992) ========= 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=652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-28 10:25:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.7M=0s 2017-03-28 10:25:20 (35.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084106 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.741997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.742495) ========= 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=652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013801 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:20.794749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 10:25:20.795108) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --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 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --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=652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-28 10:25:20,920 truncating revision to first 12 chars 2017-03-28 10:25:20,920 Setting DEBUG logging. 2017-03-28 10:25:20,920 attempt 1/10 2017-03-28 10:25:20,920 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0e0eb96528a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 10:25:22,287 unpacking tar archive at: mozilla-central-0e0eb96528a1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.638477 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 10:25:22.450482) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:22.450815) ========= script_repo_revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:22.451276) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:22.451681) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 10:25:22.463143) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-03-28 10:25:22.463759) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:25:22 INFO - MultiFileLogger online at 20170328 10:25:22 in /builds/slave/test 10:25:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 10:25:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:25:22 INFO - {'append_to_log': False, 10:25:22 INFO - 'base_work_dir': '/builds/slave/test', 10:25:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 10:25:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:25:22 INFO - 'branch': 'Firefox-Non-PGO', 10:25:22 INFO - 'buildbot_json_path': 'buildprops.json', 10:25:22 INFO - 'config_files': ('talos/linux_config.py',), 10:25:22 INFO - 'default_actions': ('clobber', 10:25:22 INFO - 'read-buildbot-config', 10:25:22 INFO - 'download-and-extract', 10:25:22 INFO - 'populate-webroot', 10:25:22 INFO - 'create-virtualenv', 10:25:22 INFO - 'install', 10:25:22 INFO - 'run-tests'), 10:25:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:25:22 INFO - 'download_minidump_stackwalk': True, 10:25:22 INFO - 'download_symbols': 'ondemand', 10:25:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:25:22 INFO - 'tooltool.py': '/tools/tooltool.py', 10:25:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:25:22 INFO - '/tools/misc-python/virtualenv.py')}, 10:25:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:25:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:25:22 INFO - 'gecko_profile': False, 10:25:22 INFO - 'gecko_profile_interval': 0, 10:25:22 INFO - 'installer_path': 'installer.exe', 10:25:22 INFO - 'log_level': 'info', 10:25:22 INFO - 'log_name': 'talos', 10:25:22 INFO - 'log_to_console': True, 10:25:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:25:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:25:22 INFO - 'opt_config_files': (), 10:25:22 INFO - 'pip_index': False, 10:25:22 INFO - 'suite': 'dromaeojs', 10:25:22 INFO - 'system_bits': '32', 10:25:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:25:22 INFO - 'title': 'talos-linux64-ix-046', 10:25:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:25:22 INFO - 'use_talos_json': True, 10:25:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:25:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:25:22 INFO - 'work_dir': 'build'} 10:25:22 INFO - [mozharness: 2017-03-28 17:25:22.698181Z] Running clobber step. 10:25:22 INFO - Running pre-action listener: _resource_record_pre_action 10:25:22 INFO - Running main action method: clobber 10:25:22 INFO - rmtree: /builds/slave/test/build 10:25:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:25:23 INFO - Running post-action listener: _resource_record_post_action 10:25:23 INFO - [mozharness: 2017-03-28 17:25:23.631898Z] Finished clobber step (success) 10:25:23 INFO - [mozharness: 2017-03-28 17:25:23.631997Z] Running read-buildbot-config step. 10:25:23 INFO - Running pre-action listener: _resource_record_pre_action 10:25:23 INFO - Running main action method: read_buildbot_config 10:25:23 INFO - Using buildbot properties: 10:25:23 INFO - { 10:25:23 INFO - "project": "", 10:25:23 INFO - "product": "firefox", 10:25:23 INFO - "who": "ryanvm@gmail.com", 10:25:23 INFO - "installer_path": "public/build/target.tar.bz2", 10:25:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:25:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:25:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 10:25:23 INFO - "stage_platform": "linux64", 10:25:23 INFO - "basedir": "/builds/slave/test", 10:25:23 INFO - "buildnumber": 902, 10:25:23 INFO - "platform": "ubuntu64_hw", 10:25:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:25:23 INFO - "slavebuilddir": "test", 10:25:23 INFO - "taskId": "IoFAOOvWT0O0D7WWMvvDMw", 10:25:23 INFO - "branch": "mozilla-central", 10:25:23 INFO - "script_repo_revision": "production", 10:25:23 INFO - "revision": "0e0eb96528a1d032fe6ed54f67d32290d533fbfd", 10:25:23 INFO - "slavename": "talos-linux64-ix-046", 10:25:23 INFO - "repo_path": "mozilla-central" 10:25:23 INFO - } 10:25:23 INFO - Finding installer, test and symbols from parent task. 10:25:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IoFAOOvWT0O0D7WWMvvDMw'}, attempt #1 10:25:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IoFAOOvWT0O0D7WWMvvDMw'}, attempt #1 10:25:24 INFO - Task dependencies: BcCkWdkuRpqW29hYoa9Olg 10:25:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg'}, attempt #1 10:25:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2 10:25:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/test_packages.json 10:25:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.crashreporter-symbols.zip 10:25:25 INFO - Running post-action listener: _resource_record_post_action 10:25:25 INFO - [mozharness: 2017-03-28 17:25:25.012445Z] Finished read-buildbot-config step (success) 10:25:25 INFO - [mozharness: 2017-03-28 17:25:25.012662Z] Running download-and-extract step. 10:25:25 INFO - Running pre-action listener: _resource_record_pre_action 10:25:25 INFO - Running main action method: download_and_extract 10:25:25 INFO - mkdir: /builds/slave/test/build/tests 10:25: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')]} 10:25:25 INFO - trying https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.test_packages.json 10:25:25 INFO - Downloading https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:25:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:25:25 INFO - Downloaded 986 bytes. 10:25:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:25:25 INFO - Using the following test package requirements: 10:25:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 10:25:25 INFO - u'common': [u'target.common.tests.zip'], 10:25:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:25:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:25:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:25:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:25:25 INFO - u'mozbase': [u'target.common.tests.zip'], 10:25:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:25:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:25:25 INFO - u'web-platform': [u'target.common.tests.zip', 10:25:25 INFO - u'target.web-platform.tests.zip'], 10:25:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:25:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:25:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.common.tests.zip 10:25:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:25:25 INFO - Fetch https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.common.tests.zip into memory 10:25:26 INFO - Expected file size: 58228675 10:25:26 INFO - Obtained file size: 58228675 10:25:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:25:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.common.tests.zip 10:25:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:25:36 INFO - Fetch https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.common.tests.zip into memory 10:25:37 INFO - Expected file size: 58228675 10:25:37 INFO - Obtained file size: 58228675 10:25:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.talos.tests.zip 10:25:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:25:48 INFO - Fetch https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.talos.tests.zip into memory 10:25:49 INFO - Expected file size: 14404171 10:25:49 INFO - Obtained file size: 14404171 10:25:49 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')]} 10:25:49 INFO - trying https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2 10:25:49 INFO - Downloading https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:25:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:25:50 INFO - Downloaded 62642355 bytes. 10:25:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2 10:25:50 INFO - mkdir: /builds/slave/test/properties 10:25:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:25:50 INFO - Writing to file /builds/slave/test/properties/build_url 10:25:50 INFO - Contents: 10:25:50 INFO - build_url:https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2 10:25:50 INFO - Running post-action listener: _resource_record_post_action 10:25:50 INFO - Running post-action listener: set_extra_try_arguments 10:25:50 INFO - [mozharness: 2017-03-28 17:25:50.563424Z] Finished download-and-extract step (success) 10:25:50 INFO - [mozharness: 2017-03-28 17:25:50.563520Z] Running populate-webroot step. 10:25:50 INFO - Running pre-action listener: _resource_record_pre_action 10:25:50 INFO - Running main action method: populate_webroot 10:25:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:25:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:25:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:25:50 INFO - '--tppagecycles', 10:25:50 INFO - '7'], 10:25:50 INFO - 'tests': ['tsvgm']}}, 10:25:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:25:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:25:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:25:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:25:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:25:50 INFO - 'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:25:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:25:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:25:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:25:50 INFO - 'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['damp', 'tps']}, 10:25:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:25:50 INFO - 'tests': ['damp', 'tps']}, 10:25:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['dromaeo_dom']}, 10:25:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:25:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['basic_compositor_video']}, 10:25:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:25:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['a11yr', 10:25:50 INFO - 'ts_paint', 10:25:50 INFO - 'tpaint', 10:25:50 INFO - 'sessionrestore', 10:25:50 INFO - 'sessionrestore_no_auto_restore', 10:25:50 INFO - 'tabpaint']}, 10:25:50 INFO - 'other-e10s': {'tests': ['a11yr', 10:25:50 INFO - 'ts_paint', 10:25:50 INFO - 'tpaint', 10:25:50 INFO - 'sessionrestore', 10:25:50 INFO - 'sessionrestore_no_auto_restore', 10:25:50 INFO - 'tabpaint']}, 10:25:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:25:50 INFO - 'ts_paint', 10:25:50 INFO - 'tpaint', 10:25:50 INFO - 'sessionrestore', 10:25:50 INFO - 'sessionrestore_no_auto_restore', 10:25:50 INFO - 'tabpaint']}, 10:25:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:25:50 INFO - 'ts_paint', 10:25:50 INFO - 'tpaint', 10:25:50 INFO - 'sessionrestore', 10:25:50 INFO - 'sessionrestore_no_auto_restore', 10:25:50 INFO - 'tabpaint']}, 10:25:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['a11yr', 10:25:50 INFO - 'ts_paint', 10:25:50 INFO - 'tpaint', 10:25:50 INFO - 'sessionrestore', 10:25:50 INFO - 'sessionrestore_no_auto_restore', 10:25:50 INFO - 'tabpaint']}, 10:25:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['a11yr', 10:25:50 INFO - 'ts_paint', 10:25:50 INFO - 'tpaint', 10:25:50 INFO - 'sessionrestore', 10:25:50 INFO - 'sessionrestore_no_auto_restore', 10:25:50 INFO - 'tabpaint']}, 10:25:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['tsvgx', 10:25:50 INFO - 'tsvgr_opacity', 10:25:50 INFO - 'tart', 10:25:50 INFO - 'tscrollx', 10:25:50 INFO - 'cart', 10:25:50 INFO - 'tsvg_static']}, 10:25:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:25:50 INFO - 'tsvgr_opacity', 10:25:50 INFO - 'tart', 10:25:50 INFO - 'tscrollx', 10:25:50 INFO - 'cart', 10:25:50 INFO - 'tsvg_static']}, 10:25:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:25:50 INFO - 'talos_options': ['--disable-e10s'], 10:25:50 INFO - 'tests': ['tp5o']}, 10:25:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:25:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:25:50 INFO - 'talos_options': ['--disable-e10s', 10:25:50 INFO - '--xperf_path', 10:25:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:25:50 INFO - 'tests': ['tp5n']}, 10:25:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:25:50 INFO - 'talos_options': ['--xperf_path', 10:25:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:25:50 INFO - 'tests': ['tp5n']}}, 10:25:50 INFO - 'talos.zip': {'path': '', 10:25:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:25:50 INFO - Running post-action listener: _resource_record_post_action 10:25:50 INFO - [mozharness: 2017-03-28 17:25:50.576651Z] Finished populate-webroot step (success) 10:25:50 INFO - [mozharness: 2017-03-28 17:25:50.576744Z] Running create-virtualenv step. 10:25:50 INFO - Running pre-action listener: _resource_record_pre_action 10:25:50 INFO - Running main action method: create_virtualenv 10:25:50 INFO - Creating virtualenv /builds/slave/test/build/venv 10:25:50 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 10:25:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:25:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:25:50 INFO - Using real prefix '/usr' 10:25:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:25:51 INFO - Installing distribute.............................................................................................................................................................................................done. 10:25:53 INFO - Installing pip.................done. 10:25:53 INFO - Return code: 0 10:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:25:53 INFO - Reading from file tmpfile_stdout 10:25:53 INFO - Output received: 10:25:53 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) 10:25:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:25:53 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')]} 10:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:53 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')]} 10:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:53 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 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:25:53 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 10:25:53 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 10:25:53 INFO - Using env: {'DISPLAY': ':0', 10:25:53 INFO - 'HOME': '/home/cltbld', 10:25:53 INFO - 'LANG': 'en_US.UTF-8', 10:25:53 INFO - 'LANGUAGE': 'en_US:en', 10:25:53 INFO - 'LOGNAME': 'cltbld', 10:25:53 INFO - 'MAIL': '/var/mail/cltbld', 10:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:53 INFO - 'MOZ_NO_REMOTE': '1', 10:25:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:53 INFO - 'NO_EM_RESTART': '1', 10:25:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:53 INFO - 'PWD': '/builds/slave/test', 10:25:53 INFO - 'SHELL': '/bin/bash', 10:25:53 INFO - 'SHLVL': '1', 10:25:53 INFO - 'TERM': 'linux', 10:25:53 INFO - 'TMOUT': '86400', 10:25:53 INFO - 'USER': 'cltbld', 10:25:53 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:53 INFO - '_': '/tools/buildbot/bin/python'} 10:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:53 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 10:25:53 INFO - Cleaning up... 10:25:53 INFO - Return code: 0 10:25:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:25:53 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')]} 10:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:53 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')]} 10:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:53 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 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:25:53 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 10:25:53 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 10:25:53 INFO - Using env: {'DISPLAY': ':0', 10:25:53 INFO - 'HOME': '/home/cltbld', 10:25:53 INFO - 'LANG': 'en_US.UTF-8', 10:25:53 INFO - 'LANGUAGE': 'en_US:en', 10:25:53 INFO - 'LOGNAME': 'cltbld', 10:25:53 INFO - 'MAIL': '/var/mail/cltbld', 10:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:53 INFO - 'MOZ_NO_REMOTE': '1', 10:25:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:53 INFO - 'NO_EM_RESTART': '1', 10:25:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:53 INFO - 'PWD': '/builds/slave/test', 10:25:53 INFO - 'SHELL': '/bin/bash', 10:25:53 INFO - 'SHLVL': '1', 10:25:53 INFO - 'TERM': 'linux', 10:25:53 INFO - 'TMOUT': '86400', 10:25:53 INFO - 'USER': 'cltbld', 10:25:53 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:53 INFO - '_': '/tools/buildbot/bin/python'} 10:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:53 INFO - Downloading/unpacking psutil>=3.1.1 10:25:53 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 10:25:53 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 10:25:53 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 10:25:53 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 10:25:53 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 10:25:53 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 10:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:25:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:25:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:25:55 INFO - Installing collected packages: psutil 10:25:55 INFO - Running setup.py install for psutil 10:25:56 INFO - building 'psutil._psutil_linux' extension 10:25:56 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 10:25:56 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 10:25:56 INFO - building 'psutil._psutil_posix' extension 10:25:56 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 10:25:56 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 10:25:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:25:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:25:56 INFO - Successfully installed psutil 10:25:56 INFO - Cleaning up... 10:25:56 INFO - Return code: 0 10:25:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:25: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')]} 10:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25: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')]} 10:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:25:56 INFO - Using env: {'DISPLAY': ':0', 10:25:56 INFO - 'HOME': '/home/cltbld', 10:25:56 INFO - 'LANG': 'en_US.UTF-8', 10:25:56 INFO - 'LANGUAGE': 'en_US:en', 10:25:56 INFO - 'LOGNAME': 'cltbld', 10:25:56 INFO - 'MAIL': '/var/mail/cltbld', 10:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:56 INFO - 'MOZ_NO_REMOTE': '1', 10:25:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:56 INFO - 'NO_EM_RESTART': '1', 10:25:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:56 INFO - 'PWD': '/builds/slave/test', 10:25:56 INFO - 'SHELL': '/bin/bash', 10:25:56 INFO - 'SHLVL': '1', 10:25:56 INFO - 'TERM': 'linux', 10:25:56 INFO - 'TMOUT': '86400', 10:25:56 INFO - 'USER': 'cltbld', 10:25:56 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:56 INFO - '_': '/tools/buildbot/bin/python'} 10:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:25:56 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 10:25:56 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 10:25:56 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 10:25:56 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 10:25:56 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 10:25:56 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 10:25:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:25:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:25:58 INFO - Installing collected packages: mozsystemmonitor 10:25:58 INFO - Running setup.py install for mozsystemmonitor 10:25:58 INFO - Successfully installed mozsystemmonitor 10:25:58 INFO - Cleaning up... 10:25:58 INFO - Return code: 0 10:25:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:25:58 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')]} 10:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:58 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')]} 10:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:58 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 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:25:58 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 10:25:58 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 10:25:58 INFO - Using env: {'DISPLAY': ':0', 10:25:58 INFO - 'HOME': '/home/cltbld', 10:25:58 INFO - 'LANG': 'en_US.UTF-8', 10:25:58 INFO - 'LANGUAGE': 'en_US:en', 10:25:58 INFO - 'LOGNAME': 'cltbld', 10:25:58 INFO - 'MAIL': '/var/mail/cltbld', 10:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:58 INFO - 'MOZ_NO_REMOTE': '1', 10:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:58 INFO - 'NO_EM_RESTART': '1', 10:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:58 INFO - 'PWD': '/builds/slave/test', 10:25:58 INFO - 'SHELL': '/bin/bash', 10:25:58 INFO - 'SHLVL': '1', 10:25:58 INFO - 'TERM': 'linux', 10:25:58 INFO - 'TMOUT': '86400', 10:25:58 INFO - 'USER': 'cltbld', 10:25:58 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:58 INFO - '_': '/tools/buildbot/bin/python'} 10:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:59 INFO - Downloading/unpacking jsonschema==2.5.1 10:25:59 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 10:25:59 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 10:25:59 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 10:25:59 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 10:25:59 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 10:25:59 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 10:26:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:26:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:26:01 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 10:26:01 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 10:26:01 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 10:26:01 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 10:26:01 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 10:26:01 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 10:26:01 INFO - Downloading functools32-3.2.3-2.tar.gz 10:26:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:26:01 INFO - warning: no files found matching '*.txt' 10:26:01 INFO - no previously-included directories found matching 'build' 10:26:01 INFO - no previously-included directories found matching 'dist' 10:26:01 INFO - no previously-included directories found matching '.git*' 10:26:01 INFO - Installing collected packages: jsonschema, functools32 10:26:01 INFO - Running setup.py install for functools32 10:26:01 INFO - warning: no files found matching '*.txt' 10:26:01 INFO - no previously-included directories found matching 'build' 10:26:01 INFO - no previously-included directories found matching 'dist' 10:26:01 INFO - no previously-included directories found matching '.git*' 10:26:01 INFO - Successfully installed jsonschema functools32 10:26:01 INFO - Cleaning up... 10:26:01 INFO - Return code: 0 10:26:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:26:01 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')]} 10:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:01 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')]} 10:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:01 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 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:26:01 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 10:26:01 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 10:26:01 INFO - Using env: {'DISPLAY': ':0', 10:26:01 INFO - 'HOME': '/home/cltbld', 10:26:01 INFO - 'LANG': 'en_US.UTF-8', 10:26:01 INFO - 'LANGUAGE': 'en_US:en', 10:26:01 INFO - 'LOGNAME': 'cltbld', 10:26:01 INFO - 'MAIL': '/var/mail/cltbld', 10:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:01 INFO - 'MOZ_NO_REMOTE': '1', 10:26:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:26:01 INFO - 'NO_EM_RESTART': '1', 10:26:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:01 INFO - 'PWD': '/builds/slave/test', 10:26:01 INFO - 'SHELL': '/bin/bash', 10:26:01 INFO - 'SHLVL': '1', 10:26:01 INFO - 'TERM': 'linux', 10:26:01 INFO - 'TMOUT': '86400', 10:26:01 INFO - 'USER': 'cltbld', 10:26:01 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:01 INFO - '_': '/tools/buildbot/bin/python'} 10:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:26:01 INFO - Cleaning up... 10:26:01 INFO - Return code: 0 10:26:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:26:01 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')]} 10:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:01 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')]} 10:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:01 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 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:26:01 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 10:26:01 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 10:26:01 INFO - Using env: {'DISPLAY': ':0', 10:26:01 INFO - 'HOME': '/home/cltbld', 10:26:01 INFO - 'LANG': 'en_US.UTF-8', 10:26:01 INFO - 'LANGUAGE': 'en_US:en', 10:26:01 INFO - 'LOGNAME': 'cltbld', 10:26:01 INFO - 'MAIL': '/var/mail/cltbld', 10:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:01 INFO - 'MOZ_NO_REMOTE': '1', 10:26:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:26:01 INFO - 'NO_EM_RESTART': '1', 10:26:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:01 INFO - 'PWD': '/builds/slave/test', 10:26:01 INFO - 'SHELL': '/bin/bash', 10:26:01 INFO - 'SHLVL': '1', 10:26:01 INFO - 'TERM': 'linux', 10:26:01 INFO - 'TMOUT': '86400', 10:26:01 INFO - 'USER': 'cltbld', 10:26:01 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:01 INFO - '_': '/tools/buildbot/bin/python'} 10:26:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:02 INFO - Downloading/unpacking blobuploader==1.2.4 10:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:04 INFO - Downloading blobuploader-1.2.4.tar.gz 10:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:26:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:26:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 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 10:26:04 INFO - Downloading docopt-0.6.1.tar.gz 10:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:26:04 INFO - Installing collected packages: blobuploader, requests, docopt 10:26:04 INFO - Running setup.py install for blobuploader 10:26:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:26:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:26:05 INFO - Running setup.py install for requests 10:26:05 INFO - Running setup.py install for docopt 10:26:05 INFO - Successfully installed blobuploader requests docopt 10:26:05 INFO - Cleaning up... 10:26:05 INFO - Return code: 0 10:26:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:05 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')]} 10:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:05 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')]} 10:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:05 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 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:26:05 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 10:26:05 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 10:26:05 INFO - Using env: {'DISPLAY': ':0', 10:26:05 INFO - 'HOME': '/home/cltbld', 10:26:05 INFO - 'LANG': 'en_US.UTF-8', 10:26:05 INFO - 'LANGUAGE': 'en_US:en', 10:26:05 INFO - 'LOGNAME': 'cltbld', 10:26:05 INFO - 'MAIL': '/var/mail/cltbld', 10:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:05 INFO - 'MOZ_NO_REMOTE': '1', 10:26:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:26:05 INFO - 'NO_EM_RESTART': '1', 10:26:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:05 INFO - 'PWD': '/builds/slave/test', 10:26:05 INFO - 'SHELL': '/bin/bash', 10:26:05 INFO - 'SHLVL': '1', 10:26:05 INFO - 'TERM': 'linux', 10:26:05 INFO - 'TMOUT': '86400', 10:26:05 INFO - 'USER': 'cltbld', 10:26:05 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:05 INFO - '_': '/tools/buildbot/bin/python'} 10:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:05 INFO - Running setup.py (path:/tmp/pip-A120pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:05 INFO - Running setup.py (path:/tmp/pip-fteufS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:05 INFO - Running setup.py (path:/tmp/pip-rZE4ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:05 INFO - Running setup.py (path:/tmp/pip-Mp9PrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:06 INFO - Running setup.py (path:/tmp/pip-s0XAT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:06 INFO - Running setup.py (path:/tmp/pip-e6kgbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:06 INFO - Running setup.py (path:/tmp/pip-cr3lRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:06 INFO - Running setup.py (path:/tmp/pip-MLHIVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:06 INFO - Running setup.py (path:/tmp/pip-mcI4PH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:06 INFO - Running setup.py (path:/tmp/pip-m7rtv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:06 INFO - Running setup.py (path:/tmp/pip-vNsraH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:06 INFO - Running setup.py (path:/tmp/pip-uCN_Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:06 INFO - Running setup.py (path:/tmp/pip-_W8dP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:06 INFO - Running setup.py (path:/tmp/pip-sUVsms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:06 INFO - Running setup.py (path:/tmp/pip-CvvCnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:06 INFO - Running setup.py (path:/tmp/pip-zLTF6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:06 INFO - Running setup.py (path:/tmp/pip-wwSEL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:26:06 INFO - Running setup.py install for manifestparser 10:26:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Running setup.py install for mozcrash 10:26:07 INFO - Running setup.py install for mozdebug 10:26:07 INFO - Running setup.py install for mozdevice 10:26:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Running setup.py install for mozfile 10:26:07 INFO - Running setup.py install for mozhttpd 10:26:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Running setup.py install for mozinfo 10:26:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Running setup.py install for mozInstall 10:26:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Running setup.py install for mozleak 10:26:07 INFO - Running setup.py install for mozlog 10:26:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:26:07 INFO - Running setup.py install for moznetwork 10:26:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:26:08 INFO - Running setup.py install for mozprocess 10:26:08 INFO - Running setup.py install for mozprofile 10:26:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:26:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:26:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:26:08 INFO - Running setup.py install for mozrunner 10:26:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:26:08 INFO - Running setup.py install for mozscreenshot 10:26:08 INFO - Running setup.py install for moztest 10:26:08 INFO - Running setup.py install for mozversion 10:26:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:26:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:26:08 INFO - Cleaning up... 10:26:08 INFO - Return code: 0 10:26:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:08 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')]} 10:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:08 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')]} 10:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:08 INFO - Using env: {'DISPLAY': ':0', 10:26:08 INFO - 'HOME': '/home/cltbld', 10:26:08 INFO - 'LANG': 'en_US.UTF-8', 10:26:08 INFO - 'LANGUAGE': 'en_US:en', 10:26:08 INFO - 'LOGNAME': 'cltbld', 10:26:08 INFO - 'MAIL': '/var/mail/cltbld', 10:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:08 INFO - 'MOZ_NO_REMOTE': '1', 10:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:26:08 INFO - 'NO_EM_RESTART': '1', 10:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:08 INFO - 'PWD': '/builds/slave/test', 10:26:08 INFO - 'SHELL': '/bin/bash', 10:26:08 INFO - 'SHLVL': '1', 10:26:08 INFO - 'TERM': 'linux', 10:26:08 INFO - 'TMOUT': '86400', 10:26:08 INFO - 'USER': 'cltbld', 10:26:08 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:08 INFO - '_': '/tools/buildbot/bin/python'} 10:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:08 INFO - Running setup.py (path:/tmp/pip-qY3ABz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:09 INFO - Running setup.py (path:/tmp/pip-0888LS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:09 INFO - Running setup.py (path:/tmp/pip-bRFUDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:09 INFO - Running setup.py (path:/tmp/pip-3_Obzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:09 INFO - Running setup.py (path:/tmp/pip-fLjVVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:09 INFO - Running setup.py (path:/tmp/pip-Wd4RUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:09 INFO - Running setup.py (path:/tmp/pip-ICYzcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:09 INFO - Running setup.py (path:/tmp/pip-MmQrBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:09 INFO - Running setup.py (path:/tmp/pip-AJe0YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:09 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)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:09 INFO - Running setup.py (path:/tmp/pip-RY9nFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:09 INFO - Running setup.py (path:/tmp/pip-RSVz1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:09 INFO - Running setup.py (path:/tmp/pip-kms_vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:09 INFO - Running setup.py (path:/tmp/pip-EXgG5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:09 INFO - Running setup.py (path:/tmp/pip-ViE7_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:09 INFO - Running setup.py (path:/tmp/pip-5ORpke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:10 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)) 10:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:10 INFO - Running setup.py (path:/tmp/pip-CgF0nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:10 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)) 10:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:10 INFO - Running setup.py (path:/tmp/pip-gs_Hzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:10 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)) 10:26:10 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)) 10:26:10 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)) 10:26:10 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)) 10:26:10 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)) 10:26:10 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)) 10:26:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:10 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 10:26:10 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 10:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:12 INFO - Downloading blessings-1.6.tar.gz 10:26:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:26:12 INFO - Installing collected packages: blessings 10:26:12 INFO - Running setup.py install for blessings 10:26:12 INFO - Successfully installed blessings 10:26:12 INFO - Cleaning up... 10:26:12 INFO - Return code: 0 10:26:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:26:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:12 INFO - Reading from file tmpfile_stdout 10:26:12 INFO - Current package versions: 10:26:12 INFO - argparse == 1.2.1 10:26:12 INFO - blessings == 1.6 10:26:12 INFO - blobuploader == 1.2.4 10:26:12 INFO - docopt == 0.6.1 10:26:12 INFO - functools32 == 3.2.3-2 10:26:12 INFO - jsonschema == 2.5.1 10:26:12 INFO - manifestparser == 1.1 10:26:12 INFO - mozInstall == 1.12 10:26:12 INFO - mozcrash == 1.0 10:26:12 INFO - mozdebug == 0.1 10:26:12 INFO - mozdevice == 0.49 10:26:12 INFO - mozfile == 1.2 10:26:12 INFO - mozhttpd == 0.7 10:26:12 INFO - mozinfo == 0.9 10:26:12 INFO - mozleak == 0.1 10:26:12 INFO - mozlog == 3.4 10:26:12 INFO - moznetwork == 0.27 10:26:12 INFO - mozprocess == 0.25 10:26:12 INFO - mozprofile == 0.28 10:26:12 INFO - mozrunner == 6.13 10:26:12 INFO - mozscreenshot == 0.1 10:26:12 INFO - mozsystemmonitor == 0.3 10:26:12 INFO - moztest == 0.8 10:26:12 INFO - mozversion == 1.4 10:26:12 INFO - psutil == 3.1.1 10:26:12 INFO - requests == 1.2.3 10:26:12 INFO - wsgiref == 0.1.2 10:26:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:26:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:12 INFO - Using env: {'DISPLAY': ':0', 10:26:12 INFO - 'HOME': '/home/cltbld', 10:26:12 INFO - 'LANG': 'en_US.UTF-8', 10:26:12 INFO - 'LANGUAGE': 'en_US:en', 10:26:12 INFO - 'LOGNAME': 'cltbld', 10:26:12 INFO - 'MAIL': '/var/mail/cltbld', 10:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:12 INFO - 'MOZ_NO_REMOTE': '1', 10:26:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:26:12 INFO - 'NO_EM_RESTART': '1', 10:26:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:12 INFO - 'PWD': '/builds/slave/test', 10:26:12 INFO - 'SHELL': '/bin/bash', 10:26:12 INFO - 'SHLVL': '1', 10:26:12 INFO - 'TERM': 'linux', 10:26:12 INFO - 'TMOUT': '86400', 10:26:12 INFO - 'USER': 'cltbld', 10:26:12 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:12 INFO - '_': '/tools/buildbot/bin/python'} 10:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:26:12 INFO - Cleaning up... 10:26:12 INFO - Return code: 0 10:26:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26331f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28d0b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, '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': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', '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 10:26:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:26:12 INFO - Using env: {'DISPLAY': ':0', 10:26:12 INFO - 'HOME': '/home/cltbld', 10:26:12 INFO - 'LANG': 'en_US.UTF-8', 10:26:12 INFO - 'LANGUAGE': 'en_US:en', 10:26:12 INFO - 'LOGNAME': 'cltbld', 10:26:12 INFO - 'MAIL': '/var/mail/cltbld', 10:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:12 INFO - 'MOZ_NO_REMOTE': '1', 10:26:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:26:12 INFO - 'NO_EM_RESTART': '1', 10:26:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:12 INFO - 'PWD': '/builds/slave/test', 10:26:12 INFO - 'SHELL': '/bin/bash', 10:26:12 INFO - 'SHLVL': '1', 10:26:12 INFO - 'TERM': 'linux', 10:26:12 INFO - 'TMOUT': '86400', 10:26:12 INFO - 'USER': 'cltbld', 10:26:12 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:12 INFO - '_': '/tools/buildbot/bin/python'} 10:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:26:13 INFO - Cleaning up... 10:26:13 INFO - Return code: 0 10:26:13 INFO - Running post-action listener: _resource_record_post_action 10:26:13 INFO - Running post-action listener: _start_resource_monitoring 10:26:13 INFO - Starting resource monitoring. 10:26:13 INFO - [mozharness: 2017-03-28 17:26:13.194429Z] Finished create-virtualenv step (success) 10:26:13 INFO - [mozharness: 2017-03-28 17:26:13.194897Z] Running install step. 10:26:13 INFO - Running pre-action listener: _resource_record_pre_action 10:26:13 INFO - Running main action method: install 10:26:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:13 INFO - Reading from file tmpfile_stdout 10:26:13 INFO - Detecting whether we're running mozinstall >=1.0... 10:26:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:26:13 INFO - Reading from file tmpfile_stdout 10:26:13 INFO - Output received: 10:26:13 INFO - Usage: mozinstall [options] installer 10:26:13 INFO - Options: 10:26:13 INFO - -h, --help show this help message and exit 10:26:13 INFO - -d DEST, --destination=DEST 10:26:13 INFO - Directory to install application into. [default: 10:26:13 INFO - "/builds/slave/test"] 10:26:13 INFO - --app=APP Application being installed. [default: firefox] 10:26:13 INFO - mkdir: /builds/slave/test/build/application 10:26:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:26:29 INFO - Reading from file tmpfile_stdout 10:26:29 INFO - Output received: 10:26:29 INFO - /builds/slave/test/build/application/firefox/firefox 10:26:29 INFO - Running post-action listener: _resource_record_post_action 10:26:29 INFO - [mozharness: 2017-03-28 17:26:29.764444Z] Finished install step (success) 10:26:29 INFO - [mozharness: 2017-03-28 17:26:29.764716Z] Running run-tests step. 10:26:29 INFO - Running pre-action listener: _resource_record_pre_action 10:26:29 INFO - Running main action method: run_tests 10:26:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:26:29 INFO - Python 2.7.3 10:26:29 INFO - Return code: 0 10:26:29 INFO - grabbing minidump binary from tooltool 10:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ce290>, '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 0x28ceb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cf040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:26:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:26:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:26:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:26:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:26:29 INFO - Return code: 0 10:26:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:26:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:26:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:26:29 INFO - ENV: RUST_BACKTRACE is now 1 10:26:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:26:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:26:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 10:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-046 --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 10:26:29 INFO - Using env: {'DISPLAY': ':0', 10:26:29 INFO - 'HOME': '/home/cltbld', 10:26:29 INFO - 'LANG': 'en_US.UTF-8', 10:26:29 INFO - 'LANGUAGE': 'en_US:en', 10:26:29 INFO - 'LOGNAME': 'cltbld', 10:26:29 INFO - 'MAIL': '/var/mail/cltbld', 10:26:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:26:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:29 INFO - 'MOZ_NO_REMOTE': '1', 10:26:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:26:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:26:29 INFO - 'NO_EM_RESTART': '1', 10:26:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:29 INFO - 'PWD': '/builds/slave/test', 10:26:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:26:29 INFO - 'RUST_BACKTRACE': '1', 10:26:29 INFO - 'SHELL': '/bin/bash', 10:26:29 INFO - 'SHLVL': '1', 10:26:29 INFO - 'TERM': 'linux', 10:26:29 INFO - 'TMOUT': '86400', 10:26:29 INFO - 'USER': 'cltbld', 10:26:29 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802', 10:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:29 INFO - '_': '/tools/buildbot/bin/python'} 10:26:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 10:26:30 INFO - mozversion application_buildid: 20170328165232 10:26:30 INFO - mozversion application_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 10:26:30 INFO - mozversion application_display_name: Nightly 10:26:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:26:30 INFO - mozversion application_name: Firefox 10:26:30 INFO - mozversion application_remotingname: firefox 10:26:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:26:30 INFO - mozversion application_vendor: Mozilla 10:26:30 INFO - mozversion application_version: 55.0a1 10:26:30 INFO - mozversion platform_buildid: 20170328165232 10:26:30 INFO - mozversion platform_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 10:26:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:26:30 INFO - mozversion platform_version: 55.0a1 10:26:30 INFO - using testdate: 1490721990 10:26:30 INFO - actual date: 1490721990 10:26:30 INFO - starting webserver on 'localhost:34897' 10:26:30 INFO - SUITE-START | Running 2 tests 10:26:30 INFO - TEST-START | dromaeo_css 10:26:30 INFO - operating with platform_type : linux_ 10:26:30 INFO - Initialising browser for dromaeo_css test... 10:26:30 INFO - TEST-INFO | started process 9455 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOui3_y/profile http://localhost:34897/getInfo.html) 10:26:34 INFO - PID 9455 | __metrics Screen width/height:1600/1200 10:26:34 INFO - PID 9455 | colorDepth:24 10:26:34 INFO - PID 9455 | Browser inner width/height: 1024/697 10:26:34 INFO - PID 9455 | __metrics 10:26:35 ERROR - PID 9455 | 1490721995064 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 10:26:35 INFO - PID 9455 | 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" 10:26:35 INFO - PID 9455 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:26:36 INFO - TEST-INFO | 9455: exit 0 10:26:36 INFO - Browser initialized. 10:26:36 INFO - Running cycle 1/1 for dromaeo_css test... 10:26:36 INFO - TEST-INFO | started process 9548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOui3_y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:26:48 INFO - PID 9548 | RSS: Main: 150048768 10:26:48 INFO - PID 9548 | 10:29:56 INFO - PID 9548 | Cycle 1(1): loaded http://localhost:34897/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34897/tests/dromaeo/cssquery-ext.html) 10:29:57 INFO - PID 9548 | RSS: Main: 308002816 10:29:57 INFO - PID 9548 | 10:31:38 INFO - PID 9548 | ************************* 10:31:38 INFO - PID 9548 | A coding exception was thrown and uncaught in a Task. 10:31:38 INFO - PID 9548 | 10:31:38 ERROR - PID 9548 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:31:38 INFO - PID 9548 | Full stack: 10:31:38 INFO - PID 9548 | ************************* 10:31:38 INFO - PID 9548 | ************************* 10:31:38 INFO - PID 9548 | A coding exception was thrown and uncaught in a Task. 10:31:38 INFO - PID 9548 | 10:31:38 ERROR - PID 9548 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:31:38 INFO - PID 9548 | Full stack: 10:31:38 INFO - PID 9548 | ************************* 10:31:38 INFO - PID 9548 | ************************* 10:31:38 INFO - PID 9548 | A coding exception was thrown and uncaught in a Task. 10:31:38 INFO - PID 9548 | 10:31:38 ERROR - PID 9548 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:31:38 INFO - PID 9548 | Full stack: 10:31:38 INFO - PID 9548 | ************************* 10:31:38 INFO - PID 9548 | ************************* 10:31:38 INFO - PID 9548 | A coding exception was thrown and uncaught in a Task. 10:31:38 INFO - PID 9548 | 10:31:38 ERROR - PID 9548 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:31:38 INFO - PID 9548 | Full stack: 10:31:38 INFO - PID 9548 | ************************* 10:33:00 INFO - PID 9548 | Cycle 1(1): loaded http://localhost:34897/tests/dromaeo/cssquery-ext.html (next: http://localhost:34897/tests/dromaeo/cssquery-jquery.html) 10:33:00 INFO - PID 9548 | RSS: Main: 236113920 10:33:00 INFO - PID 9548 | 10:36:03 INFO - PID 9548 | Cycle 1(1): loaded http://localhost:34897/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34897/tests/dromaeo/cssquery-mootools.html) 10:36:03 INFO - PID 9548 | RSS: Main: 180695040 10:36:03 INFO - PID 9548 | 10:39:05 INFO - PID 9548 | Cycle 1(1): loaded http://localhost:34897/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34897/tests/dromaeo/cssquery-prototype.html) 10:39:06 INFO - PID 9548 | RSS: Main: 204279808 10:39:06 INFO - PID 9548 | 10:42:09 INFO - PID 9548 | Cycle 1(1): loaded http://localhost:34897/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34897/tests/dromaeo/cssquery-yui.html) 10:42:10 INFO - PID 9548 | RSS: Main: 188690432 10:42:10 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | Cycle 1(1): loaded http://localhost:34897/tests/dromaeo/cssquery-yui.html (next: http://localhost:34897/tests/dromaeo/cssquery-dojo.html) 10:45:13 INFO - PID 9548 | RSS: Main: 279052288 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | __start_tp_report 10:45:13 INFO - PID 9548 | _x_x_mozilla_page_load 10:45:13 INFO - PID 9548 | _x_x_mozilla_page_load_details 10:45:13 INFO - PID 9548 | |i|pagename|runs| 10:45:13 INFO - PID 9548 | |0;dojo.html;2660.2684261827994;2668.6530751002365;2701.149138021523;2709.0382914065494;2712.8236664616807;4878.219484882419;4974.303597496353;5155.200943853709;5172.456892026339;5204.219367094937;5030.924536131952;5056.039352523018;5069.978080358702;5078.136716758151;5090.898182036366;4608.884777880555;4650.4186976627925;4674.0651869626045;4679.60223381013;4713.9057218855605;4676.766161691919;4687.343771871941;4713.410823647044;4714.976425117869;4773.451053128882;4754.690945088575;4781.091592597413;4782.306565547993;4791.425028996526;4810.9037819243595;4741.454732705749;4758.904821903542;4795.304680821261;4808.326834243208;4845.466998630165;4770.522947705236;4791.832285869978;4800.207965685658;4803.159447096744;4846.6365022623095;5005.399352077774;5080.870354480475;5211.24436956643;5215.139501982168;5263.105272103751;4911.533404326583;5349.919751203735;5420.13277875538;5487.506124448779;5577.2191893135;5638.379778224392;5640;5652.06740887753;5667.971660141714;5685.346185188673;5208.635395522315;5233.241180028875;5237.135872580981;5237.816676416307;5248.580113590903;5582.7766889323975;5639.407862174495;5757.366689664133;5763.625364351304;5791.102379131242;5474.09677403224;5477.1510415885605;5513.889722205534;5532.419095994943;5536.197251398525;5531.640443371168;5550.139728342114;5550.666959982414;5556;5558.055130627722;5374.543163831118;5497.780088796483;5508.614396992172;5513.934075555362;5564.860878478071;5270.657407268515;5492.697901615449;5550.389457159709;5601.495865372054;5609.326880774333;4621.468531118897;4650.86047418578;4659.510751371125;4666.020135771526;4703.270992996091;3908.3746600544346;3986.9003274917777;3994.1612261425425;4003.319435695939;4036.394540818842;4571.542845715402;4685.437747470325;4729.337892695025;4730.597899178609;4743.098811225925;5520.1167813149705;5570.777168913279;5572.972135139379;5576.1356989666665;5580.49775520197;4649.232876575327;4651.837185698552;4654.464736555272;4660.1145782301255;4687.39063921688;4758.572428271505;4816.638752093607;4927.211646136672;4988.5260900214425;4993.375828021497;5187.429382767817;5187.92218116721;5191.558717509054;5196.844094677166;5215.061288968022;5599.468050535193;5602.775888964396;5606.747696353592;5610.747516361692;5656.0667489865355;4999;5078.162103252922;5119.129747942784;5162.716050617252;5163.896722065613;5492.835214943558;5519.061759500974;5528.170774383874;5534.833954981357;5556.694381809039;5445.754941027584;5499.7250137492165;5601.775928962796;5603.691796951207;5605.635633683961;5244.5279924806955;5297.073012222922;5329.413764485981;5347.545458636059;5348.839534813962;5329.307190065267;5338.97330513345;5343.866403339947;5347.705876176848;5353.946460535345;5253.737313134405;5261.237120617412;5267.631265811357;5277.630565860353;5340.279062326692;5272.9736351318;5335.493128152819;5338.652987555796;5339.492748188916;5344.893102137858;5142.76857541404;5166.405863325615;5205.063088643929;5216.739163041909;5217.373915130209 10:45:13 INFO - PID 9548 | |1;ext.html;648.3386945315779;733.1495465260298;749.8050506868215;1074.5809134437566;1091.7488977535172;25417;30900.227494312625;31336.373272534565;31416.52875206868;31469.842650786715;32256.354872902528;32650.83674581624;32669.50995735066;32775.836120819426;32786.344273114526;5550.862073274989;5691.4877661010505;7147.428205743541;7350.522216055966;7357.5217610855125;6958.478114141434;7110.928890711104;7111.85776284474;7120.786376408691;7122.216556178816;7190.065291512122;7300.379467745248;7303.087114110736;7307.19620841707;7307.8538429231385;7220.638968051603;7277.854442911165;7292.270772922719;7294.744683936036;7311.122665280147;7292.927070729331;7353.227911069314;7353.411727061823;7357.668904899238;7358.41132709382;33222.16944576381;33390.83304583486;33603.495947560805;33603.83198084019;33660.663393366;2682.5841994490884;2710.8644567771535;2727.5090483712925;2729.044834307996;2748.3541367778557;22774.316770496913;23017.76982230173;23186.53626927469;23514.64728029081;24179.274621761375;2940.970590294091;2944.146197602714;2962.318666706648;2979.4041191761735;3035.9544606830914;150197;150397.24801375924;150435.24782376128;150501;150719;42273.788631057265;42339;42387.57612423898;42449.151016979486;42481.36277955833;43473.565264347584;43478;43495.56504434979;43546.78226608847;43564.346534802;11943.940280298542;12185.756284874431;12251.754964900652;12259.387030648431;12277.20198187115;7851;7968.083670377865;9668.9033109668;9732.659356922473;9733;11021.504032318566;11145.554177832959;11181.944090279496;11309.09527237819;11347;4819.373481447459;4846.539578740014;4862.270659401047;4924.778384972685;5088.847334579968;891.4383938119073;895.4582477600958;900.5767289373983;911.4485736129678;916.0701887584107;23320.416989575395;24047.759522404904;24054.39864003379;24054.87972560126;24100.276991690276;958.5494817435933;958.6692591056075;960.2406578246228;978.5107446276861;985.3841349156777;11324;13278.66803329905;13845.584632461043;14301.64245893861;14327.704627935633;202068;202953;203082;203251;203390;41408.1718365631;42173;42190.15619687589;42209.57790422118;42264.366034509505;1193.91045671575;1243.5088140184575;1248.2572869142846;1251.0992085698465;1265.9620211393674;21764.564708706053;21899.890500548034;22720.318390448312;22941.655875161552;22974.540509189388;28547.42905141844;28557.143285701462;28568.57147142836;28573.42853142884;28584.714152858207;195676;195813;196032;196504;196527;21158.47103822417;25098.37254068663;25803.483930321665;25940.740592593833;26002;38175.12172998637;38693.22613547657;38711.806440967615;39274.34150340656;39475.210495790496;27507.862460687567;29004.41991160207;29449;29578.852105739334;29909.401811964075;29036.7096329034;29088.27279318034;29244;30222.54666179965;30288.84855575708;32210.516842246918;32404.35191296208;32649;32653.673463266012;32855.67144328526;28946.13161605071;29161.416771664874;29239.561406578494;29421;29568;22514.437139071655;22795.31614051581;23233.63112348734;23243;23255.534889302457 10:45:13 INFO - PID 9548 | |2;jquery.html;415.40182137721695;418.4288446270841;428.1330306130086;428.6742076022222;443.79807187729585;11754;16664.00015999041;17307.22117504712;17310.134493275335;18956.146973386196;18739.313461788435;18932.526686832836;19025.33411330604;19089.427317180507;19197.712034319462;5583.832485025456;5672.801451949183;5749.942500575004;5788.450097240777;5792.884142317151;5630.127330263796;5731.340895796995;5742.913856292159;5743.081107022877;5744.454276843693;5449.509544141027;5647.548196144319;5662.207290979266;5662.575306851982;5662.858428539278;5575.968445837492;5623.184638227454;5660.094384898417;5664.745086471119;5676.063449530822;5045.318881950926;5140.1518749406305;5150.253213284091;5154.871128221787;5156.510131537498;22214;22429.21497747571;22616.321510354715;22755.88622056896;22881.771182288132;1510.1995942150568;1524.4588171199287;1530.9234538273154;1571.418575127199;1575.8739300855905;13245.735085298336;13845.100068495516;13987.440502379994;14435.711285774225;14500.419983200765;1646.4813583721088;1688.3753011385743;1689.8394652508116;1704.284200635736;1771.654527367151;87747;90189.54905225433;90677;91002;91065;10617.787644247226;10738.409387483764;10776.461176941122;11040.723981900517;11061.391623460788;13241.602751917459;15470.5358839235;15486.148261845481;15510.767338489932;15513.922430387776;6078.665673388006;6908;7093.113360829896;7182.102237220348;7240.782776516713;6302.1177035215105;6348.682565871688;6490.837729056811;6502.341902814846;6508.511861610398;6800.115984922028;6887.827804304932;6906.482013848881;6914.446844252448;7016.193137789118;1965.9901700491405;1966;2022.8887411192231;2056.9280075197594;2092.1735914313763;815.5351449673748;842.7176895739996;879.2526352600239;885.1856292211181;889.3729920406098;16809.83190168107;17394.47816565505;17500.474985750447;17598.29606815713;17851.107444627978;870.8911386076741;877.5656050255164;883.4257732473943;884.7080463447047;900.8828852249165;9472.431654100637;9503.334766566273;9594.376365536358;9595.568199430903;9680.27397945143;433909;479305;494327;509124;517055;90527.54736226409;91428;92256.53871730599;94238;94399;1034.4310629154086;1073.538378497238;1099.2195541165702;1102.5038732570376;1104.7293413113678;14800.7779883304;15497.767533486782;17990.820091799436;18375.540611484284;18431.631367372847;27594.187861948994;41433.58566414297;42970.35544466898;44202.55797441985;46076.30885536787;45513.544864552256;45870.77064614656;46825.76587117043;46952.7652361736;46975.53024469712;7795.688172473037;7816.151648922285;8009.359251260002;8057.234562716532;8068.273855353046;12075.27548347102;12092.032637389164;12268.693282668004;12292.815607765711;12391.822776836187;9516.286278529;9541.952290238782;9662.323637345318;9729.610815567297;9976.401415915068;9426.151646351862;9827.26295527824;9869.506524673594;9888.604455821687;10139;10412.479376031017;10537.367757934242;10556.89443105559;10590.517327573993;11012.449377531251;7872.488288261245;7873.133955265031;7899.644515996679;8009.797305740968;8315.708950186714;10760.89239107599;10860.239783215415;10923.672289831318;11044.668659940215;11202.66392008241 10:45:13 INFO - PID 9548 | |3;mootools.html;1257.101172661547;1315.7368526294738;1329.5878277733902;1339.5780329196295;1344.4622151139542;9263.258939284859;9624.951875240631;9632.903670963296;9635.759106022353;9639.036096390357;9441.81116377672;9466;9737.269704772136;9747.317687761843;9759.56081976311;2261.0955617752866;2302.1366987379733;2303.57383883982;2305.8385912986096;2307.9423014424606;2088.812006919377;2254.7068881045525;2257.3905045637666;2276.7495575486764;2278.963071802334;1857.3406440713554;1917.8178073083104;1920.42387283815;1921.34674210769;1922.1446456326871;2163.902624381899;2238.4515793630585;2244.6857439958417;2248.730152381708;2248.7526372099055;2127.6702111172795;2158.6114499390105;2162.7188465499544;2165.577712346093;2166.534195148051;9283.953580232055;9507.524623768784;9713.805723885553;9714.22286217101;9823.312368134233;5481.862953426196;5661.094224923992;5695.060315048054;5696.487316141567;5704.629199102086;3352.0781785009317;3362.041818081835;3403.8808641697424;3439.9656003439895;3443.9483407748653;986.4426598971559;1076.617800680755;1091.5579190427889;1091.8034753744323;1121.3328069798456;14763.26183690822;14824.184669843155;14836.629084272872;14861.405543778235;14880.851191488164;4755.809767609257;4999.65002449825;5030.170021946411;5053.317802096743;5056.367954005749;10939.23425360233;10992.835107473395;11002.614908478165;11004.614838480615;11010.614628488125;5615.606907516516;5718.828435146952;5720.998825205573;5721.084626459747;5730.627509211971;5023.296738456619;5096.9490305097215;5134.460881607378;5159.277701121846;5190.792368305226;3765.811709414518;3880.282147802609;3907.081835768592;3916.3538016227003;3934.9606503935165;1126.1610100475034;1139.4872307461676;1156.196443471779;1159.0727418065514;1172.5192671004847;677.5426587053719;690.975815846443;693.0816667914931;700.0758998122429;719.0005891810388;4626.653001024936;5848.1227815827115;5888.970555147197;6019.187409699722;6134.263888333428;803.1887793328664;806.9596520174085;808.3533173461208;810.0926961802819;812.4353574265829;3392.1010932103013;3469.1847415857255;3471.479278108304;3565.964340356615;3593.7484376093944;12421.254724716366;12763.170393924365;12978.675533111558;13134.27761473109;13167.078304518784;12620.62138135839;12943.5469758558;12943.805842912363;12947.741045179233;12969;9302;9485.193758530464;9488.102379524124;9489.57296921654;9490.050994900455;5319.760610772602;5378.919316210182;5410.729463526886;5452.236686863763;5483.506484416422;11318.717032073935;11657.941710291394;11755.647330580096;11807.291562506278;11818.172727908965;11740.647780566254;11783.351915644722;11787.233829801036;11887.76224475523;11888.28670279786;7687.192844751445;7833.295003449717;7837.882431763414;7838.804029899298;7839.294463498312;9275.86086208694;9289.535523223676;9343.859842102238;9347.205487533636;9349.57926893305;7547.962260188664;7585.658645360862;7598.12621548529;7605.809854753454;7842.29419352261;5741.5406767459335;7537.397008239437;7584;7907.446478746432;7918.287354138155;7286.417086633162;7391.4086873048955;7396.371308438843;7401.5189012714;7404.185539590533;4920.5571498565305;5114.181730923035;5146.819861304836;5298.072837253389;5369.167778994262;5099.942091495503;5182.844514664566;5247.2129180623215;5249;5271.393789714232 10:45:13 INFO - PID 9548 | |4;prototype.html;379.45927053948134;399.63233824881104;400.2335527464904;404.5448870021226;406.6746602717825;5302.4697530247;5358;5384.542313903318;5389.892202155955;5410.890767392685;4999.525045120708;5184.1705327147565;5205.375354957401;5234.816781412651;5318.574514038877;4098.344264917614;4103.835846566134;4111;4111.239420707178;4120.340745480724;4129.421880936671;4129.752214867102;4136.420901073852;4149.7302675326155;4153.771542565158;3954.2882281189372;4070.5929407059352;4078.9796051019703;4089.7750623715683;4098.282800509899;4066.7966601670096;4070.7354021988476;4084.4690190275073;4090.386442033689;4091.3862920562005;3586.982065089684;3587.65917237862;3592.515010473579;3593.1376469647357;3616.0959760059986;5053.620978426633;5114.33513643224;5139.203423469368;5144.408393034774;5153.896922061575;4667.6732628716;4702.988857395642;4706.482286948433;4714.5521175488275;4733.148033353995;5455;5516.531094856902;5529.391766905637;5539.806106786284;5544.584156188302;3902.785169416371;3955.406688996645;3958.050067983692;3962.583928687504;3963;5507.5593952483705;5681.772729090872;5715.8571035724435;5718.113692377647;5878.853028674274;4043.3934909763184;4055.7363771354767;4065.6137666921595;4070.3080476319096;4077.7553346799286;4115.526714427879;4120.381942708557;4124.52567954689;4138.089620283533;4140.91718165635;4425.535318791481;4451.59935605797;4542.227821270392;4686.484486706459;4695.929561056587;5499.780008799603;5539.41836107211;5554.805581804618;5558.610897237155;5566.972165139148;1414.3918115210352;1427.2221639206657;1429.3782204740971;1432.1836553164596;1435.2034620785355;934.7008957133788;967.579103090158;968.8256113899565;970.8495183246608;986.8355340698046;4730.645201609824;4733.863872670603;4741.312509686076;4775.785089670973;4810.95189048112;5708.885822283531;5739.913901291484;5742.913856292159;5750.30996280449;5762.7694892203845;4667.299905014275;4742.620590352763;4764;4772.809087636456;4798.73606951621;2988.745956593714;3017.743491803177;3035.347400308944;3043;3049.7255247027874;5265.341832270966;5323.414424413311;5331.760070796823;5334.666333416646;5346;5701.287339082614;5853.122031695195;5866.560007999417;5874.295084589877;5902.675352855548;5323.680579165262;5329;5333.839984800462;5367.436419176009;5374.596905232123;5542.528885044816;5681.857953551194;5740.31116266034;5740.799072032459;5756.453136951983;5652.208690783212;5660.54715622741;5661.207430959751;5663.4053424391495;5665.943340566541;5071.847844564669;5240.764165612481;5259.737013149251;5260.710660913686;5343.278657381359;3769.9246015078998;3941.1526521796804;3948.427478015729;4061.918761624692;4067.9626695192537;5733.71331433435;5755.71221438918;5760.798372056958;5764.913526297025;5792.189093526994;5417;5557.194206840067;5579.330480342385;5582.748776304995;5603.719814009365;5513.476219759116;5539.476643922953;5558.777648893999;5570.498655121059;5580.246666700106;5376.381716102698;5447.1829225616475;5473.781048758005;5493.23094766725;5529;5553.389127196086;5570.610057296112;5575.665460072247;5580.107182850725;5595.496405323378;5376.838694839161;5414.29614150158;5473.042217611982;5491.697956612425;5492.313460817398 10:45:13 INFO - PID 9548 | |5;yui.html;691.4157536881337;700.1807884774813;700.2257830294711;700.9053777740005;708.8936659501074;12543.874561254384;12917.483300667962;12918.677033074155;12927.482900683974;12941.870581294184;12411.379431028436;12630.684232894182;14014.439422423116;14533.854661453383;14562.708745825077;848.766589187972;851.1403482482675;873.0134947509306;874.8600223964168;877.398981697536;848.9957550212271;870.2428886868433;871.3116637856086;871.4814685262259;873.0614589316478;901.1079031758577;909.4452384045727;909.7043460875242;909.8362294786936;910.535626830318;892.901780804111;897.2239013253554;904.4437670832391;904.4618452021069;905.8822353094056;670.7317073170722;671.768239957214;671.9865602687968;672.7342699633617;672.7376323233942;14198.787018194735;14492.927535362362;14500.637484062878;14503.057301275383;14712.411503539846;13411.932940335333;14102.576922692335;14107.78838317426;14236.857631423656;14254.291684579897;10238.710960811602;10394.63618773347;10441.269111162223;10543.367397956146;10654.78690426195;2748.8350698957925;2782.2627003844;2789.665240171172;2790.7069757675354;2869.426114777032;66279.33720662828;66341;66403.33596664068;66447.66776166088;67353.66323168353;9694.51527423626;9825.361351512129;9827.508624568743;10060;10081.49592520371;20807;20812.58374832495;21026.2640807571;21037.05333260007;21185.78814211869;6102.877942441126;6298.433141017331;6305.684715764193;6387.297397286295;6486.902696459557;3288.309455014426;3342.247994201285;3353.916152096217;3398.1164897126923;3432.4164891968426;3563;3610.6208848070573;3629.528161339009;3643.544556930384;3675.7794532328144;656.6552559905992;657.4477438951304;662.0367365483306;675.2032601530143;676.3507033248038;531.598643024514;544.9209864569694;553.3525774843442;564.3960961770865;575.689127870945;8641.697540586176;8670.656048312396;8759.386842921062;8894.59974301158;9114.13415725505;549.3956647687592;555.4612026334449;559.105431309901;565.7821738630657;569.7464628240393;3981.6217459341315;4021.5375231848125;4155.3766934959995;4156.361671862523;4279.9786001070415;59986.10020849691;60645.69677151674;61590.38409615936;62506.687466562376;63077;53270.73364633152;53904;54110.72944635252;54237.45762542324;54890.451095489334;46318.53681463142;47965.5203447961;49045.5095449041;49504.50495495071;50013.499865000886;7934.404919631166;8399.580020998803;8405.888317773379;8429.578521074045;8483.236508714246;26130.346741330857;26893.596596050684;26921.192364229104;27075.1877443677;27197.456050878474;32347.838260809487;32676.34647307088;32766.017019489453;32899;33239.33521329512;10196.949015255173;10248.897511024794;10275.948620256851;10514.106300964504;10599.364038157735;10161.695149145537;10238.692839214835;10459.110975566859;10654.946725266323;10703.143748500257;9948.79297732265;10157.534212354061;10179.277721463543;10249.38503689781;10287.794244115226;9611.711648650551;9655.9034409655;9657.420554766737;9928.503574821085;10095.141912937483;9473.384230025027;9701.902980970382;9785.412875227508;9874.85187722199;10256.794864102825;7816.84366312682;7943.44395892282;8057.5165490070785;8095.757127286191;8182.263596276363;8220.876686849582;8242.109468637538;8584.570771461276;8743.169398907348;8767.868481972648 10:45:13 INFO - PID 9548 | __end_tp_report 10:45:13 INFO - PID 9548 | __start_cc_report 10:45:13 INFO - PID 9548 | _x_x_mozilla_cycle_collect,1749 10:45:13 INFO - PID 9548 | __end_cc_report 10:45:13 INFO - PID 9548 | __startTimestamp1490723113732__endTimestamp 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | ------- Summary: start ------- 10:45:13 INFO - PID 9548 | Number of tests: 6 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | [#0] dojo.html Cycles:165 Average:5080.20 Median:5215.06 stddev:576.80 (11.1%) stddev-sans-first:546.44 10:45:13 INFO - PID 9548 | Values: 2660.3 2668.7 2701.1 2709.0 2712.8 4878.2 4974.3 5155.2 5172.5 5204.2 5030.9 5056.0 5070.0 5078.1 5090.9 4608.9 4650.4 4674.1 4679.6 4713.9 4676.8 4687.3 4713.4 4715.0 4773.5 4754.7 4781.1 4782.3 4791.4 4810.9 4741.5 4758.9 4795.3 4808.3 4845.5 4770.5 4791.8 4800.2 4803.2 4846.6 5005.4 5080.9 5211.2 5215.1 5263.1 4911.5 5349.9 5420.1 5487.5 5577.2 5638.4 5640.0 5652.1 5668.0 5685.3 5208.6 5233.2 5237.1 5237.8 5248.6 5582.8 5639.4 5757.4 5763.6 5791.1 5474.1 5477.2 5513.9 5532.4 5536.2 5531.6 5550.1 5550.7 5556.0 5558.1 5374.5 5497.8 5508.6 5513.9 5564.9 5270.7 5492.7 5550.4 5601.5 5609.3 4621.5 4650.9 4659.5 4666.0 4703.3 3908.4 3986.9 3994.2 4003.3 4036.4 4571.5 4685.4 4729.3 4730.6 4743.1 5520.1 5570.8 5573.0 5576.1 5580.5 4649.2 4651.8 4654.5 4660.1 4687.4 4758.6 4816.6 4927.2 4988.5 4993.4 5187.4 5187.9 5191.6 5196.8 5215.1 5599.5 5602.8 5606.7 5610.7 5656.1 4999.0 5078.2 5119.1 5162.7 5163.9 5492.8 5519.1 5528.2 5534.8 5556.7 5445.8 5499.7 5601.8 5603.7 5605.6 5244.5 5297.1 5329.4 5347.5 5348.8 5329.3 5339.0 5343.9 5347.7 5353.9 5253.7 5261.2 5267.6 5277.6 5340.3 5273.0 5335.5 5338.7 5339.5 5344.9 5142.8 5166.4 5205.1 5216.7 5217.4 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | [#1] ext.html Cycles:180 Average:32641.63 Median:30255.70 stddev:47874.72 (158.2%) stddev-sans-first:47948.75 10:45:13 INFO - PID 9548 | Values: 648.3 733.1 749.8 1074.6 1091.7 25417.0 30900.2 31336.4 31416.5 31469.8 32256.4 32650.8 32669.5 32775.8 32786.3 5550.9 5691.5 7147.4 7350.5 7357.5 6958.5 7110.9 7111.9 7120.8 7122.2 7190.1 7300.4 7303.1 7307.2 7307.9 7220.6 7277.9 7292.3 7294.7 7311.1 7292.9 7353.2 7353.4 7357.7 7358.4 33222.2 33390.8 33603.5 33603.8 33660.7 2682.6 2710.9 2727.5 2729.0 2748.4 22774.3 23017.8 23186.5 23514.6 24179.3 2941.0 2944.1 2962.3 2979.4 3036.0 150197.0 150397.2 150435.2 150501.0 150719.0 42273.8 42339.0 42387.6 42449.2 42481.4 43473.6 43478.0 43495.6 43546.8 43564.3 11943.9 12185.8 12251.8 12259.4 12277.2 7851.0 7968.1 9668.9 9732.7 9733.0 11021.5 11145.6 11181.9 11309.1 11347.0 4819.4 4846.5 4862.3 4924.8 5088.8 891.4 895.5 900.6 911.4 916.1 23320.4 24047.8 24054.4 24054.9 24100.3 958.5 958.7 960.2 978.5 985.4 11324.0 13278.7 13845.6 14301.6 14327.7 202068.0 202953.0 203082.0 203251.0 203390.0 41408.2 42173.0 42190.2 42209.6 42264.4 1193.9 1243.5 1248.3 1251.1 1266.0 21764.6 21899.9 22720.3 22941.7 22974.5 28547.4 28557.1 28568.6 28573.4 28584.7 195676.0 195813.0 196032.0 196504.0 196527.0 21158.5 25098.4 25803.5 25940.7 26002.0 38175.1 38693.2 38711.8 39274.3 39475.2 27507.9 29004.4 29449.0 29578.9 29909.4 29036.7 29088.3 29244.0 30222.5 30288.8 32210.5 32404.4 32649.0 32653.7 32855.7 28946.1 29161.4 29239.6 29421.0 29568.0 22514.4 22795.3 23233.6 23243.0 23255.5 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | [#2] jquery.html Cycles:180 Average:28446.18 Median:46964.15 stddev:80599.40 (171.6%) stddev-sans-first:80798.03 10:45:13 INFO - PID 9548 | Values: 415.4 418.4 428.1 428.7 443.8 11754.0 16664.0 17307.2 17310.1 18956.1 18739.3 18932.5 19025.3 19089.4 19197.7 5583.8 5672.8 5749.9 5788.5 5792.9 5630.1 5731.3 5742.9 5743.1 5744.5 5449.5 5647.5 5662.2 5662.6 5662.9 5576.0 5623.2 5660.1 5664.7 5676.1 5045.3 5140.2 5150.3 5154.9 5156.5 22214.0 22429.2 22616.3 22755.9 22881.8 1510.2 1524.5 1530.9 1571.4 1575.9 13245.7 13845.1 13987.4 14435.7 14500.4 1646.5 1688.4 1689.8 1704.3 1771.7 87747.0 90189.5 90677.0 91002.0 91065.0 10617.8 10738.4 10776.5 11040.7 11061.4 13241.6 15470.5 15486.1 15510.8 15513.9 6078.7 6908.0 7093.1 7182.1 7240.8 6302.1 6348.7 6490.8 6502.3 6508.5 6800.1 6887.8 6906.5 6914.4 7016.2 1966.0 1966.0 2022.9 2056.9 2092.2 815.5 842.7 879.3 885.2 889.4 16809.8 17394.5 17500.5 17598.3 17851.1 870.9 877.6 883.4 884.7 900.9 9472.4 9503.3 9594.4 9595.6 9680.3 433909.0 479305.0 494327.0 509124.0 517055.0 90527.5 91428.0 92256.5 94238.0 94399.0 1034.4 1073.5 1099.2 1102.5 1104.7 14800.8 15497.8 17990.8 18375.5 18431.6 27594.2 41433.6 42970.4 44202.6 46076.3 45513.5 45870.8 46825.8 46952.8 46975.5 7795.7 7816.2 8009.4 8057.2 8068.3 12075.3 12092.0 12268.7 12292.8 12391.8 9516.3 9542.0 9662.3 9729.6 9976.4 9426.2 9827.3 9869.5 9888.6 10139.0 10412.5 10537.4 10556.9 10590.5 11012.4 7872.5 7873.1 7899.6 8009.8 8315.7 10760.9 10860.2 10923.7 11044.7 11202.7 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | [#3] mootools.html Cycles:180 Average:6177.92 Median:5054.84 stddev:3937.96 (77.9%) stddev-sans-first:3931.65 10:45:13 INFO - PID 9548 | Values: 1257.1 1315.7 1329.6 1339.6 1344.5 9263.3 9625.0 9632.9 9635.8 9639.0 9441.8 9466.0 9737.3 9747.3 9759.6 2261.1 2302.1 2303.6 2305.8 2307.9 2088.8 2254.7 2257.4 2276.7 2279.0 1857.3 1917.8 1920.4 1921.3 1922.1 2163.9 2238.5 2244.7 2248.7 2248.8 2127.7 2158.6 2162.7 2165.6 2166.5 9284.0 9507.5 9713.8 9714.2 9823.3 5481.9 5661.1 5695.1 5696.5 5704.6 3352.1 3362.0 3403.9 3440.0 3443.9 986.4 1076.6 1091.6 1091.8 1121.3 14763.3 14824.2 14836.6 14861.4 14880.9 4755.8 4999.7 5030.2 5053.3 5056.4 10939.2 10992.8 11002.6 11004.6 11010.6 5615.6 5718.8 5721.0 5721.1 5730.6 5023.3 5096.9 5134.5 5159.3 5190.8 3765.8 3880.3 3907.1 3916.4 3935.0 1126.2 1139.5 1156.2 1159.1 1172.5 677.5 691.0 693.1 700.1 719.0 4626.7 5848.1 5889.0 6019.2 6134.3 803.2 807.0 808.4 810.1 812.4 3392.1 3469.2 3471.5 3566.0 3593.7 12421.3 12763.2 12978.7 13134.3 13167.1 12620.6 12943.5 12943.8 12947.7 12969.0 9302.0 9485.2 9488.1 9489.6 9490.1 5319.8 5378.9 5410.7 5452.2 5483.5 11318.7 11657.9 11755.6 11807.3 11818.2 11740.6 11783.4 11787.2 11887.8 11888.3 7687.2 7833.3 7837.9 7838.8 7839.3 9275.9 9289.5 9343.9 9347.2 9349.6 7548.0 7585.7 7598.1 7605.8 7842.3 5741.5 7537.4 7584.0 7907.4 7918.3 7286.4 7391.4 7396.4 7401.5 7404.2 4920.6 5114.2 5146.8 5298.1 5369.2 5099.9 5182.8 5247.2 5249.0 5271.4 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | [#4] prototype.html Cycles:180 Average:4591.58 Median:5250.25 stddev:1329.60 (25.3%) stddev-sans-first:1295.19 10:45:13 INFO - PID 9548 | Values: 379.5 399.6 400.2 404.5 406.7 5302.5 5358.0 5384.5 5389.9 5410.9 4999.5 5184.2 5205.4 5234.8 5318.6 4098.3 4103.8 4111.0 4111.2 4120.3 4129.4 4129.8 4136.4 4149.7 4153.8 3954.3 4070.6 4079.0 4089.8 4098.3 4066.8 4070.7 4084.5 4090.4 4091.4 3587.0 3587.7 3592.5 3593.1 3616.1 5053.6 5114.3 5139.2 5144.4 5153.9 4667.7 4703.0 4706.5 4714.6 4733.1 5455.0 5516.5 5529.4 5539.8 5544.6 3902.8 3955.4 3958.1 3962.6 3963.0 5507.6 5681.8 5715.9 5718.1 5878.9 4043.4 4055.7 4065.6 4070.3 4077.8 4115.5 4120.4 4124.5 4138.1 4140.9 4425.5 4451.6 4542.2 4686.5 4695.9 5499.8 5539.4 5554.8 5558.6 5567.0 1414.4 1427.2 1429.4 1432.2 1435.2 934.7 967.6 968.8 970.8 986.8 4730.6 4733.9 4741.3 4775.8 4811.0 5708.9 5739.9 5742.9 5750.3 5762.8 4667.3 4742.6 4764.0 4772.8 4798.7 2988.7 3017.7 3035.3 3043.0 3049.7 5265.3 5323.4 5331.8 5334.7 5346.0 5701.3 5853.1 5866.6 5874.3 5902.7 5323.7 5329.0 5333.8 5367.4 5374.6 5542.5 5681.9 5740.3 5740.8 5756.5 5652.2 5660.5 5661.2 5663.4 5665.9 5071.8 5240.8 5259.7 5260.7 5343.3 3769.9 3941.2 3948.4 4061.9 4068.0 5733.7 5755.7 5760.8 5764.9 5792.2 5417.0 5557.2 5579.3 5582.7 5603.7 5513.5 5539.5 5558.8 5570.5 5580.2 5376.4 5447.2 5473.8 5493.2 5529.0 5553.4 5570.6 5575.7 5580.1 5595.5 5376.8 5414.3 5473.0 5491.7 5492.3 10:45:13 INFO - PID 9548 | 10:45:13 INFO - PID 9548 | [#5] yui.html Cycles:180 Average:13830.31 Median:551.37 stddev:17299.45 (3137.5%) stddev-sans-first:17319.84 10:45:13 INFO - PID 9548 | Values: 691.4 700.2 700.2 700.9 708.9 12543.9 12917.5 12918.7 12927.5 12941.9 12411.4 12630.7 14014.4 14533.9 14562.7 848.8 851.1 873.0 874.9 877.4 849.0 870.2 871.3 871.5 873.1 901.1 909.4 909.7 909.8 910.5 892.9 897.2 904.4 904.5 905.9 670.7 671.8 672.0 672.7 672.7 14198.8 14492.9 14500.6 14503.1 14712.4 13411.9 14102.6 14107.8 14236.9 14254.3 10238.7 10394.6 10441.3 10543.4 10654.8 2748.8 2782.3 2789.7 2790.7 2869.4 66279.3 66341.0 66403.3 66447.7 67353.7 9694.5 9825.4 9827.5 10060.0 10081.5 20807.0 20812.6 21026.3 21037.1 21185.8 6102.9 6298.4 6305.7 6387.3 6486.9 3288.3 3342.2 3353.9 3398.1 3432.4 3563.0 3610.6 3629.5 3643.5 3675.8 656.7 657.4 662.0 675.2 676.4 531.6 544.9 553.4 564.4 575.7 8641.7 8670.7 8759.4 8894.6 9114.1 549.4 555.5 559.1 565.8 569.7 3981.6 4021.5 4155.4 4156.4 4280.0 59986.1 60645.7 61590.4 62506.7 63077.0 53270.7 53904.0 54110.7 54237.5 54890.5 46318.5 47965.5 49045.5 49504.5 50013.5 7934.4 8399.6 8405.9 8429.6 8483.2 26130.3 26893.6 26921.2 27075.2 27197.5 32347.8 32676.3 32766.0 32899.0 33239.3 10196.9 10248.9 10275.9 10514.1 10599.4 10161.7 10238.7 10459.1 10654.9 10703.1 9948.8 10157.5 10179.3 10249.4 10287.8 9611.7 9655.9 9657.4 9928.5 10095.1 9473.4 9701.9 9785.4 9874.9 10256.8 7816.8 7943.4 8057.5 8095.8 8182.3 8220.9 8242.1 8584.6 8743.2 8767.9 10:45:13 INFO - PID 9548 | -------- Summary: end -------- 10:45:13 INFO - PID 9548 | 10:45:14 INFO - TEST-INFO | 9548: exit 0 10:45:14 INFO - TEST-OK | dromaeo_css | took 1124507ms 10:45:14 INFO - TEST-START | kraken 10:45:14 INFO - operating with platform_type : linux_ 10:45:14 INFO - Initialising browser for kraken test... 10:45:14 INFO - TEST-INFO | started process 9874 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8H7Bet/profile http://localhost:34897/getInfo.html) 10:45:18 INFO - PID 9874 | __metrics Screen width/height:1600/1200 10:45:18 INFO - PID 9874 | colorDepth:24 10:45:18 INFO - PID 9874 | Browser inner width/height: 1024/697 10:45:18 INFO - PID 9874 | __metrics 10:45:19 ERROR - PID 9874 | 1490723119723 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 10:45:19 INFO - PID 9874 | 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" 10:45:19 INFO - PID 9874 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:45:20 INFO - TEST-INFO | 9874: exit 0 10:45:20 INFO - Browser initialized. 10:45:20 INFO - Running cycle 1/1 for kraken test... 10:45:20 INFO - TEST-INFO | started process 9965 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8H7Bet/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:45:33 INFO - PID 9965 | RSS: Main: 149409792 10:45:33 INFO - PID 9965 | 10:45:35 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34897/tests/kraken/driver.html?testName=audio-beat-detection) 10:45:36 INFO - PID 9965 | RSS: Main: 205127680 10:45:36 INFO - PID 9965 | 10:45:39 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34897/tests/kraken/driver.html?testName=audio-dft) 10:45:40 INFO - PID 9965 | RSS: Main: 184729600 10:45:40 INFO - PID 9965 | 10:45:43 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34897/tests/kraken/driver.html?testName=audio-fft) 10:45:44 INFO - PID 9965 | RSS: Main: 267669504 10:45:44 INFO - PID 9965 | 10:45:46 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34897/tests/kraken/driver.html?testName=audio-oscillator) 10:45:46 INFO - PID 9965 | RSS: Main: 178585600 10:45:46 INFO - PID 9965 | 10:45:49 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34897/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:45:49 INFO - PID 9965 | RSS: Main: 181604352 10:45:49 INFO - PID 9965 | 10:45:52 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34897/tests/kraken/driver.html?testName=imaging-darkroom) 10:45:53 INFO - PID 9965 | RSS: Main: 220958720 10:45:53 INFO - PID 9965 | 10:45:57 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34897/tests/kraken/driver.html?testName=imaging-desaturate) 10:45:57 INFO - PID 9965 | RSS: Main: 214716416 10:45:57 INFO - PID 9965 | 10:46:00 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34897/tests/kraken/driver.html?testName=json-parse-financial) 10:46:01 INFO - PID 9965 | RSS: Main: 217325568 10:46:01 INFO - PID 9965 | 10:46:03 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34897/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:46:03 INFO - PID 9965 | RSS: Main: 180756480 10:46:03 INFO - PID 9965 | 10:46:05 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:46:06 INFO - PID 9965 | RSS: Main: 187060224 10:46:06 INFO - PID 9965 | 10:46:08 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:46:09 INFO - PID 9965 | RSS: Main: 197619712 10:46:09 INFO - PID 9965 | 10:46:12 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:46:12 INFO - PID 9965 | RSS: Main: 205844480 10:46:12 INFO - PID 9965 | 10:46:16 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:46:16 INFO - PID 9965 | RSS: Main: 202358784 10:46:16 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | Cycle 1(1): loaded http://localhost:34897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34897/tests/kraken/driver.html?testName=ai-astar) 10:46:18 INFO - PID 9965 | RSS: Main: 272252928 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | __start_tp_report 10:46:18 INFO - PID 9965 | _x_x_mozilla_page_load 10:46:18 INFO - PID 9965 | _x_x_mozilla_page_load_details 10:46:18 INFO - PID 9965 | |i|pagename|runs| 10:46:18 INFO - PID 9965 | |0;ai-astar;102;115;112;112;112;114;114;112;112;112 10:46:18 INFO - PID 9965 | |1;audio-beat-detection;161;126;130;177;134;125;130;126;149;131 10:46:18 INFO - PID 9965 | |2;audio-dft;166;166;167;164;166;164;164;165;166;166 10:46:18 INFO - PID 9965 | |3;audio-fft;75;77;77;115;77;77;76;76;93;75 10:46:18 INFO - PID 9965 | |4;audio-oscillator;83;84;108;84;84;85;104;85;85;107 10:46:18 INFO - PID 9965 | |5;imaging-gaussian-blur;113;114;115;114;115;114;114;114;114;114 10:46:18 INFO - PID 9965 | |6;imaging-darkroom;151;153;150;151;151;151;150;151;152;152 10:46:18 INFO - PID 9965 | |7;imaging-desaturate;95;96;94;94;94;95;94;95;95;95 10:46:18 INFO - PID 9965 | |8;json-parse-financial;64;66;64;63;65;65;71;70;66;66 10:46:18 INFO - PID 9965 | |9;json-stringify-tinderbox;56;56;56;56;56;55;56;56;58;56 10:46:18 INFO - PID 9965 | |10;stanford-crypto-aes;84;85;83;85;83;83;83;82;85;82 10:46:18 INFO - PID 9965 | |11;stanford-crypto-ccm;148;137;150;146;135;149;136;135;183;137 10:46:18 INFO - PID 9965 | |12;stanford-crypto-pbkdf2;191;201;184;194;195;196;193;195;194;192 10:46:18 INFO - PID 9965 | |13;stanford-crypto-sha256-iterative;73;73;71;72;70;71;70;74;70;70 10:46:18 INFO - PID 9965 | __end_tp_report 10:46:18 INFO - PID 9965 | __start_cc_report 10:46:18 INFO - PID 9965 | _x_x_mozilla_cycle_collect,1998 10:46:18 INFO - PID 9965 | __end_cc_report 10:46:18 INFO - PID 9965 | __startTimestamp1490723178826__endTimestamp 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | ------- Summary: start ------- 10:46:18 INFO - PID 9965 | Number of tests: 14 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:3.59 (3.2%) stddev-sans-first:1.20 10:46:18 INFO - PID 9965 | Values: 102.0 115.0 112.0 112.0 112.0 114.0 114.0 112.0 112.0 112.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:132.50 stddev:17.68 (13.3%) stddev-sans-first:16.85 10:46:18 INFO - PID 9965 | Values: 161.0 126.0 130.0 177.0 134.0 125.0 130.0 126.0 149.0 131.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#2] audio-dft Cycles:10 Average:165.40 Median:166.00 stddev:1.07 (0.6%) stddev-sans-first:1.12 10:46:18 INFO - PID 9965 | Values: 166.0 166.0 167.0 164.0 166.0 164.0 164.0 165.0 166.0 166.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#3] audio-fft Cycles:10 Average:81.80 Median:77.00 stddev:12.82 (16.7%) stddev-sans-first:13.36 10:46:18 INFO - PID 9965 | Values: 75.0 77.0 77.0 115.0 77.0 77.0 76.0 76.0 93.0 75.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:84.00 stddev:10.71 (12.8%) stddev-sans-first:10.97 10:46:18 INFO - PID 9965 | Values: 83.0 84.0 108.0 84.0 84.0 85.0 104.0 85.0 85.0 107.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 10:46:18 INFO - PID 9965 | Values: 113.0 114.0 115.0 114.0 115.0 114.0 114.0 114.0 114.0 114.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#6] imaging-darkroom Cycles:10 Average:151.20 Median:151.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 10:46:18 INFO - PID 9965 | Values: 151.0 153.0 150.0 151.0 151.0 151.0 150.0 151.0 152.0 152.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 10:46:18 INFO - PID 9965 | Values: 95.0 96.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:66.00 stddev:2.58 (3.9%) stddev-sans-first:2.64 10:46:18 INFO - PID 9965 | Values: 64.0 66.0 64.0 63.0 65.0 65.0 71.0 70.0 66.0 66.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 10:46:18 INFO - PID 9965 | Values: 56.0 56.0 56.0 56.0 56.0 55.0 56.0 56.0 58.0 56.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.50 stddev:1.18 (1.4%) stddev-sans-first:1.24 10:46:18 INFO - PID 9965 | Values: 84.0 85.0 83.0 85.0 83.0 83.0 83.0 82.0 85.0 82.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:147.00 stddev:14.53 (9.9%) stddev-sans-first:15.39 10:46:18 INFO - PID 9965 | Values: 148.0 137.0 150.0 146.0 135.0 149.0 136.0 135.0 183.0 137.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.50 Median:194.50 stddev:4.30 (2.2%) stddev-sans-first:4.47 10:46:18 INFO - PID 9965 | Values: 191.0 201.0 184.0 194.0 195.0 196.0 193.0 195.0 194.0 192.0 10:46:18 INFO - PID 9965 | 10:46:18 INFO - PID 9965 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:71.50 stddev:1.51 (2.1%) stddev-sans-first:1.48 10:46:18 INFO - PID 9965 | Values: 73.0 73.0 71.0 72.0 70.0 71.0 70.0 74.0 70.0 70.0 10:46:18 INFO - PID 9965 | -------- Summary: end -------- 10:46:18 INFO - PID 9965 | 10:46:19 INFO - TEST-INFO | 9965: exit 0 10:46:19 INFO - TEST-OK | kraken | took 65390ms 10:46:19 INFO - SUITE-END | took 1189s 10:46:20 INFO - Completed test suite (00:19:50) 10:46:20 INFO - javascript benchmark 10:46:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6536.830196506994, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2660.2684261827994, 2668.6530751002365, 2701.149138021523, 2709.0382914065494, 2712.8236664616807, 4878.219484882419, 4974.303597496353, 5155.200943853709, 5172.456892026339, 5204.219367094937, 5030.924536131952, 5056.039352523018, 5069.978080358702, 5078.136716758151, 5090.898182036366, 4608.884777880555, 4650.4186976627925, 4674.0651869626045, 4679.60223381013, 4713.9057218855605, 4676.766161691919, 4687.343771871941, 4713.410823647044, 4714.976425117869, 4773.451053128882, 4754.690945088575, 4781.091592597413, 4782.306565547993, 4791.425028996526, 4810.9037819243595, 4741.454732705749, 4758.904821903542, 4795.304680821261, 4808.326834243208, 4845.466998630165, 4770.522947705236, 4791.832285869978, 4800.207965685658, 4803.159447096744, 4846.6365022623095, 5005.399352077774, 5080.870354480475, 5211.24436956643, 5215.139501982168, 5263.105272103751, 4911.533404326583, 5349.919751203735, 5420.13277875538, 5487.506124448779, 5577.2191893135, 5638.379778224392, 5640.0, 5652.06740887753, 5667.971660141714, 5685.346185188673, 5208.635395522315, 5233.241180028875, 5237.135872580981, 5237.816676416307, 5248.580113590903, 5582.7766889323975, 5639.407862174495, 5757.366689664133, 5763.625364351304, 5791.102379131242, 5474.09677403224, 5477.1510415885605, 5513.889722205534, 5532.419095994943, 5536.197251398525, 5531.640443371168, 5550.139728342114, 5550.666959982414, 5556.0, 5558.055130627722, 5374.543163831118, 5497.780088796483, 5508.614396992172, 5513.934075555362, 5564.860878478071, 5270.657407268515, 5492.697901615449, 5550.389457159709, 5601.495865372054, 5609.326880774333, 4621.468531118897, 4650.86047418578, 4659.510751371125, 4666.020135771526, 4703.270992996091, 3908.3746600544346, 3986.9003274917777, 3994.1612261425425, 4003.319435695939, 4036.394540818842, 4571.542845715402, 4685.437747470325, 4729.337892695025, 4730.597899178609, 4743.098811225925, 5520.1167813149705, 5570.777168913279, 5572.972135139379, 5576.1356989666665, 5580.49775520197, 4649.232876575327, 4651.837185698552, 4654.464736555272, 4660.1145782301255, 4687.39063921688, 4758.572428271505, 4816.638752093607, 4927.211646136672, 4988.5260900214425, 4993.375828021497, 5187.429382767817, 5187.92218116721, 5191.558717509054, 5196.844094677166, 5215.061288968022, 5599.468050535193, 5602.775888964396, 5606.747696353592, 5610.747516361692, 5656.0667489865355, 4999.0, 5078.162103252922, 5119.129747942784, 5162.716050617252, 5163.896722065613, 5492.835214943558, 5519.061759500974, 5528.170774383874, 5534.833954981357, 5556.694381809039, 5445.754941027584, 5499.7250137492165, 5601.775928962796, 5603.691796951207, 5605.635633683961, 5244.5279924806955, 5297.073012222922, 5329.413764485981, 5347.545458636059, 5348.839534813962, 5329.307190065267, 5338.97330513345, 5343.866403339947, 5347.705876176848, 5353.946460535345, 5253.737313134405, 5261.237120617412, 5267.631265811357, 5277.630565860353, 5340.279062326692, 5272.9736351318, 5335.493128152819, 5338.652987555796, 5339.492748188916, 5344.893102137858, 5142.76857541404, 5166.405863325615, 5205.063088643929, 5216.739163041909, 5217.373915130209], "value": 5039.537398372158, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.3386945315779, 733.1495465260298, 749.8050506868215, 1074.5809134437566, 1091.7488977535172, 25417.0, 30900.227494312625, 31336.373272534565, 31416.52875206868, 31469.842650786715, 32256.354872902528, 32650.83674581624, 32669.50995735066, 32775.836120819426, 32786.344273114526, 5550.862073274989, 5691.4877661010505, 7147.428205743541, 7350.522216055966, 7357.5217610855125, 6958.478114141434, 7110.928890711104, 7111.85776284474, 7120.786376408691, 7122.216556178816, 7190.065291512122, 7300.379467745248, 7303.087114110736, 7307.19620841707, 7307.8538429231385, 7220.638968051603, 7277.854442911165, 7292.270772922719, 7294.744683936036, 7311.122665280147, 7292.927070729331, 7353.227911069314, 7353.411727061823, 7357.668904899238, 7358.41132709382, 33222.16944576381, 33390.83304583486, 33603.495947560805, 33603.83198084019, 33660.663393366, 2682.5841994490884, 2710.8644567771535, 2727.5090483712925, 2729.044834307996, 2748.3541367778557, 22774.316770496913, 23017.76982230173, 23186.53626927469, 23514.64728029081, 24179.274621761375, 2940.970590294091, 2944.146197602714, 2962.318666706648, 2979.4041191761735, 3035.9544606830914, 150197.0, 150397.24801375924, 150435.24782376128, 150501.0, 150719.0, 42273.788631057265, 42339.0, 42387.57612423898, 42449.151016979486, 42481.36277955833, 43473.565264347584, 43478.0, 43495.56504434979, 43546.78226608847, 43564.346534802, 11943.940280298542, 12185.756284874431, 12251.754964900652, 12259.387030648431, 12277.20198187115, 7851.0, 7968.083670377865, 9668.9033109668, 9732.659356922473, 9733.0, 11021.504032318566, 11145.554177832959, 11181.944090279496, 11309.09527237819, 11347.0, 4819.373481447459, 4846.539578740014, 4862.270659401047, 4924.778384972685, 5088.847334579968, 891.4383938119073, 895.4582477600958, 900.5767289373983, 911.4485736129678, 916.0701887584107, 23320.416989575395, 24047.759522404904, 24054.39864003379, 24054.87972560126, 24100.276991690276, 958.5494817435933, 958.6692591056075, 960.2406578246228, 978.5107446276861, 985.3841349156777, 11324.0, 13278.66803329905, 13845.584632461043, 14301.64245893861, 14327.704627935633, 202068.0, 202953.0, 203082.0, 203251.0, 203390.0, 41408.1718365631, 42173.0, 42190.15619687589, 42209.57790422118, 42264.366034509505, 1193.91045671575, 1243.5088140184575, 1248.2572869142846, 1251.0992085698465, 1265.9620211393674, 21764.564708706053, 21899.890500548034, 22720.318390448312, 22941.655875161552, 22974.540509189388, 28547.42905141844, 28557.143285701462, 28568.57147142836, 28573.42853142884, 28584.714152858207, 195676.0, 195813.0, 196032.0, 196504.0, 196527.0, 21158.47103822417, 25098.37254068663, 25803.483930321665, 25940.740592593833, 26002.0, 38175.12172998637, 38693.22613547657, 38711.806440967615, 39274.34150340656, 39475.210495790496, 27507.862460687567, 29004.41991160207, 29449.0, 29578.852105739334, 29909.401811964075, 29036.7096329034, 29088.27279318034, 29244.0, 30222.54666179965, 30288.84855575708, 32210.516842246918, 32404.35191296208, 32649.0, 32653.673463266012, 32855.67144328526, 28946.13161605071, 29161.416771664874, 29239.561406578494, 29421.0, 29568.0, 22514.437139071655, 22795.31614051581, 23233.63112348734, 23243.0, 23255.534889302457], "value": 14720.718000035102, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.40182137721695, 418.4288446270841, 428.1330306130086, 428.6742076022222, 443.79807187729585, 11754.0, 16664.00015999041, 17307.22117504712, 17310.134493275335, 18956.146973386196, 18739.313461788435, 18932.526686832836, 19025.33411330604, 19089.427317180507, 19197.712034319462, 5583.832485025456, 5672.801451949183, 5749.942500575004, 5788.450097240777, 5792.884142317151, 5630.127330263796, 5731.340895796995, 5742.913856292159, 5743.081107022877, 5744.454276843693, 5449.509544141027, 5647.548196144319, 5662.207290979266, 5662.575306851982, 5662.858428539278, 5575.968445837492, 5623.184638227454, 5660.094384898417, 5664.745086471119, 5676.063449530822, 5045.318881950926, 5140.1518749406305, 5150.253213284091, 5154.871128221787, 5156.510131537498, 22214.0, 22429.21497747571, 22616.321510354715, 22755.88622056896, 22881.771182288132, 1510.1995942150568, 1524.4588171199287, 1530.9234538273154, 1571.418575127199, 1575.8739300855905, 13245.735085298336, 13845.100068495516, 13987.440502379994, 14435.711285774225, 14500.419983200765, 1646.4813583721088, 1688.3753011385743, 1689.8394652508116, 1704.284200635736, 1771.654527367151, 87747.0, 90189.54905225433, 90677.0, 91002.0, 91065.0, 10617.787644247226, 10738.409387483764, 10776.461176941122, 11040.723981900517, 11061.391623460788, 13241.602751917459, 15470.5358839235, 15486.148261845481, 15510.767338489932, 15513.922430387776, 6078.665673388006, 6908.0, 7093.113360829896, 7182.102237220348, 7240.782776516713, 6302.1177035215105, 6348.682565871688, 6490.837729056811, 6502.341902814846, 6508.511861610398, 6800.115984922028, 6887.827804304932, 6906.482013848881, 6914.446844252448, 7016.193137789118, 1965.9901700491405, 1966.0, 2022.8887411192231, 2056.9280075197594, 2092.1735914313763, 815.5351449673748, 842.7176895739996, 879.2526352600239, 885.1856292211181, 889.3729920406098, 16809.83190168107, 17394.47816565505, 17500.474985750447, 17598.29606815713, 17851.107444627978, 870.8911386076741, 877.5656050255164, 883.4257732473943, 884.7080463447047, 900.8828852249165, 9472.431654100637, 9503.334766566273, 9594.376365536358, 9595.568199430903, 9680.27397945143, 433909.0, 479305.0, 494327.0, 509124.0, 517055.0, 90527.54736226409, 91428.0, 92256.53871730599, 94238.0, 94399.0, 1034.4310629154086, 1073.538378497238, 1099.2195541165702, 1102.5038732570376, 1104.7293413113678, 14800.7779883304, 15497.767533486782, 17990.820091799436, 18375.540611484284, 18431.631367372847, 27594.187861948994, 41433.58566414297, 42970.35544466898, 44202.55797441985, 46076.30885536787, 45513.544864552256, 45870.77064614656, 46825.76587117043, 46952.7652361736, 46975.53024469712, 7795.688172473037, 7816.151648922285, 8009.359251260002, 8057.234562716532, 8068.273855353046, 12075.27548347102, 12092.032637389164, 12268.693282668004, 12292.815607765711, 12391.822776836187, 9516.286278529, 9541.952290238782, 9662.323637345318, 9729.610815567297, 9976.401415915068, 9426.151646351862, 9827.26295527824, 9869.506524673594, 9888.604455821687, 10139.0, 10412.479376031017, 10537.367757934242, 10556.89443105559, 10590.517327573993, 11012.449377531251, 7872.488288261245, 7873.133955265031, 7899.644515996679, 8009.797305740968, 8315.708950186714, 10760.89239107599, 10860.239783215415, 10923.672289831318, 11044.668659940215, 11202.66392008241], "value": 8777.790965127133, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1257.101172661547, 1315.7368526294738, 1329.5878277733902, 1339.5780329196295, 1344.4622151139542, 9263.258939284859, 9624.951875240631, 9632.903670963296, 9635.759106022353, 9639.036096390357, 9441.81116377672, 9466.0, 9737.269704772136, 9747.317687761843, 9759.56081976311, 2261.0955617752866, 2302.1366987379733, 2303.57383883982, 2305.8385912986096, 2307.9423014424606, 2088.812006919377, 2254.7068881045525, 2257.3905045637666, 2276.7495575486764, 2278.963071802334, 1857.3406440713554, 1917.8178073083104, 1920.42387283815, 1921.34674210769, 1922.1446456326871, 2163.902624381899, 2238.4515793630585, 2244.6857439958417, 2248.730152381708, 2248.7526372099055, 2127.6702111172795, 2158.6114499390105, 2162.7188465499544, 2165.577712346093, 2166.534195148051, 9283.953580232055, 9507.524623768784, 9713.805723885553, 9714.22286217101, 9823.312368134233, 5481.862953426196, 5661.094224923992, 5695.060315048054, 5696.487316141567, 5704.629199102086, 3352.0781785009317, 3362.041818081835, 3403.8808641697424, 3439.9656003439895, 3443.9483407748653, 986.4426598971559, 1076.617800680755, 1091.5579190427889, 1091.8034753744323, 1121.3328069798456, 14763.26183690822, 14824.184669843155, 14836.629084272872, 14861.405543778235, 14880.851191488164, 4755.809767609257, 4999.65002449825, 5030.170021946411, 5053.317802096743, 5056.367954005749, 10939.23425360233, 10992.835107473395, 11002.614908478165, 11004.614838480615, 11010.614628488125, 5615.606907516516, 5718.828435146952, 5720.998825205573, 5721.084626459747, 5730.627509211971, 5023.296738456619, 5096.9490305097215, 5134.460881607378, 5159.277701121846, 5190.792368305226, 3765.811709414518, 3880.282147802609, 3907.081835768592, 3916.3538016227003, 3934.9606503935165, 1126.1610100475034, 1139.4872307461676, 1156.196443471779, 1159.0727418065514, 1172.5192671004847, 677.5426587053719, 690.975815846443, 693.0816667914931, 700.0758998122429, 719.0005891810388, 4626.653001024936, 5848.1227815827115, 5888.970555147197, 6019.187409699722, 6134.263888333428, 803.1887793328664, 806.9596520174085, 808.3533173461208, 810.0926961802819, 812.4353574265829, 3392.1010932103013, 3469.1847415857255, 3471.479278108304, 3565.964340356615, 3593.7484376093944, 12421.254724716366, 12763.170393924365, 12978.675533111558, 13134.27761473109, 13167.078304518784, 12620.62138135839, 12943.5469758558, 12943.805842912363, 12947.741045179233, 12969.0, 9302.0, 9485.193758530464, 9488.102379524124, 9489.57296921654, 9490.050994900455, 5319.760610772602, 5378.919316210182, 5410.729463526886, 5452.236686863763, 5483.506484416422, 11318.717032073935, 11657.941710291394, 11755.647330580096, 11807.291562506278, 11818.172727908965, 11740.647780566254, 11783.351915644722, 11787.233829801036, 11887.76224475523, 11888.28670279786, 7687.192844751445, 7833.295003449717, 7837.882431763414, 7838.804029899298, 7839.294463498312, 9275.86086208694, 9289.535523223676, 9343.859842102238, 9347.205487533636, 9349.57926893305, 7547.962260188664, 7585.658645360862, 7598.12621548529, 7605.809854753454, 7842.29419352261, 5741.5406767459335, 7537.397008239437, 7584.0, 7907.446478746432, 7918.287354138155, 7286.417086633162, 7391.4086873048955, 7396.371308438843, 7401.5189012714, 7404.185539590533, 4920.5571498565305, 5114.181730923035, 5146.819861304836, 5298.072837253389, 5369.167778994262, 5099.942091495503, 5182.844514664566, 5247.2129180623215, 5249.0, 5271.393789714232], "value": 4673.220494764909, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.45927053948134, 399.63233824881104, 400.2335527464904, 404.5448870021226, 406.6746602717825, 5302.4697530247, 5358.0, 5384.542313903318, 5389.892202155955, 5410.890767392685, 4999.525045120708, 5184.1705327147565, 5205.375354957401, 5234.816781412651, 5318.574514038877, 4098.344264917614, 4103.835846566134, 4111.0, 4111.239420707178, 4120.340745480724, 4129.421880936671, 4129.752214867102, 4136.420901073852, 4149.7302675326155, 4153.771542565158, 3954.2882281189372, 4070.5929407059352, 4078.9796051019703, 4089.7750623715683, 4098.282800509899, 4066.7966601670096, 4070.7354021988476, 4084.4690190275073, 4090.386442033689, 4091.3862920562005, 3586.982065089684, 3587.65917237862, 3592.515010473579, 3593.1376469647357, 3616.0959760059986, 5053.620978426633, 5114.33513643224, 5139.203423469368, 5144.408393034774, 5153.896922061575, 4667.6732628716, 4702.988857395642, 4706.482286948433, 4714.5521175488275, 4733.148033353995, 5455.0, 5516.531094856902, 5529.391766905637, 5539.806106786284, 5544.584156188302, 3902.785169416371, 3955.406688996645, 3958.050067983692, 3962.583928687504, 3963.0, 5507.5593952483705, 5681.772729090872, 5715.8571035724435, 5718.113692377647, 5878.853028674274, 4043.3934909763184, 4055.7363771354767, 4065.6137666921595, 4070.3080476319096, 4077.7553346799286, 4115.526714427879, 4120.381942708557, 4124.52567954689, 4138.089620283533, 4140.91718165635, 4425.535318791481, 4451.59935605797, 4542.227821270392, 4686.484486706459, 4695.929561056587, 5499.780008799603, 5539.41836107211, 5554.805581804618, 5558.610897237155, 5566.972165139148, 1414.3918115210352, 1427.2221639206657, 1429.3782204740971, 1432.1836553164596, 1435.2034620785355, 934.7008957133788, 967.579103090158, 968.8256113899565, 970.8495183246608, 986.8355340698046, 4730.645201609824, 4733.863872670603, 4741.312509686076, 4775.785089670973, 4810.95189048112, 5708.885822283531, 5739.913901291484, 5742.913856292159, 5750.30996280449, 5762.7694892203845, 4667.299905014275, 4742.620590352763, 4764.0, 4772.809087636456, 4798.73606951621, 2988.745956593714, 3017.743491803177, 3035.347400308944, 3043.0, 3049.7255247027874, 5265.341832270966, 5323.414424413311, 5331.760070796823, 5334.666333416646, 5346.0, 5701.287339082614, 5853.122031695195, 5866.560007999417, 5874.295084589877, 5902.675352855548, 5323.680579165262, 5329.0, 5333.839984800462, 5367.436419176009, 5374.596905232123, 5542.528885044816, 5681.857953551194, 5740.31116266034, 5740.799072032459, 5756.453136951983, 5652.208690783212, 5660.54715622741, 5661.207430959751, 5663.4053424391495, 5665.943340566541, 5071.847844564669, 5240.764165612481, 5259.737013149251, 5260.710660913686, 5343.278657381359, 3769.9246015078998, 3941.1526521796804, 3948.427478015729, 4061.918761624692, 4067.9626695192537, 5733.71331433435, 5755.71221438918, 5760.798372056958, 5764.913526297025, 5792.189093526994, 5417.0, 5557.194206840067, 5579.330480342385, 5582.748776304995, 5603.719814009365, 5513.476219759116, 5539.476643922953, 5558.777648893999, 5570.498655121059, 5580.246666700106, 5376.381716102698, 5447.1829225616475, 5473.781048758005, 5493.23094766725, 5529.0, 5553.389127196086, 5570.610057296112, 5575.665460072247, 5580.107182850725, 5595.496405323378, 5376.838694839161, 5414.29614150158, 5473.042217611982, 5491.697956612425, 5492.313460817398], "value": 4190.778875868807, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.4157536881337, 700.1807884774813, 700.2257830294711, 700.9053777740005, 708.8936659501074, 12543.874561254384, 12917.483300667962, 12918.677033074155, 12927.482900683974, 12941.870581294184, 12411.379431028436, 12630.684232894182, 14014.439422423116, 14533.854661453383, 14562.708745825077, 848.766589187972, 851.1403482482675, 873.0134947509306, 874.8600223964168, 877.398981697536, 848.9957550212271, 870.2428886868433, 871.3116637856086, 871.4814685262259, 873.0614589316478, 901.1079031758577, 909.4452384045727, 909.7043460875242, 909.8362294786936, 910.535626830318, 892.901780804111, 897.2239013253554, 904.4437670832391, 904.4618452021069, 905.8822353094056, 670.7317073170722, 671.768239957214, 671.9865602687968, 672.7342699633617, 672.7376323233942, 14198.787018194735, 14492.927535362362, 14500.637484062878, 14503.057301275383, 14712.411503539846, 13411.932940335333, 14102.576922692335, 14107.78838317426, 14236.857631423656, 14254.291684579897, 10238.710960811602, 10394.63618773347, 10441.269111162223, 10543.367397956146, 10654.78690426195, 2748.8350698957925, 2782.2627003844, 2789.665240171172, 2790.7069757675354, 2869.426114777032, 66279.33720662828, 66341.0, 66403.33596664068, 66447.66776166088, 67353.66323168353, 9694.51527423626, 9825.361351512129, 9827.508624568743, 10060.0, 10081.49592520371, 20807.0, 20812.58374832495, 21026.2640807571, 21037.05333260007, 21185.78814211869, 6102.877942441126, 6298.433141017331, 6305.684715764193, 6387.297397286295, 6486.902696459557, 3288.309455014426, 3342.247994201285, 3353.916152096217, 3398.1164897126923, 3432.4164891968426, 3563.0, 3610.6208848070573, 3629.528161339009, 3643.544556930384, 3675.7794532328144, 656.6552559905992, 657.4477438951304, 662.0367365483306, 675.2032601530143, 676.3507033248038, 531.598643024514, 544.9209864569694, 553.3525774843442, 564.3960961770865, 575.689127870945, 8641.697540586176, 8670.656048312396, 8759.386842921062, 8894.59974301158, 9114.13415725505, 549.3956647687592, 555.4612026334449, 559.105431309901, 565.7821738630657, 569.7464628240393, 3981.6217459341315, 4021.5375231848125, 4155.3766934959995, 4156.361671862523, 4279.9786001070415, 59986.10020849691, 60645.69677151674, 61590.38409615936, 62506.687466562376, 63077.0, 53270.73364633152, 53904.0, 54110.72944635252, 54237.45762542324, 54890.451095489334, 46318.53681463142, 47965.5203447961, 49045.5095449041, 49504.50495495071, 50013.499865000886, 7934.404919631166, 8399.580020998803, 8405.888317773379, 8429.578521074045, 8483.236508714246, 26130.346741330857, 26893.596596050684, 26921.192364229104, 27075.1877443677, 27197.456050878474, 32347.838260809487, 32676.34647307088, 32766.017019489453, 32899.0, 33239.33521329512, 10196.949015255173, 10248.897511024794, 10275.948620256851, 10514.106300964504, 10599.364038157735, 10161.695149145537, 10238.692839214835, 10459.110975566859, 10654.946725266323, 10703.143748500257, 9948.79297732265, 10157.534212354061, 10179.277721463543, 10249.38503689781, 10287.794244115226, 9611.711648650551, 9655.9034409655, 9657.420554766737, 9928.503574821085, 10095.141912937483, 9473.384230025027, 9701.902980970382, 9785.412875227508, 9874.85187722199, 10256.794864102825, 7816.84366312682, 7943.44395892282, 8057.5165490070785, 8095.757127286191, 8182.263596276363, 8220.876686849582, 8242.109468637538, 8584.570771461276, 8743.169398907348, 8767.868481972648], "value": 6117.20770165461, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1564.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 112.0, 112.0, 112.0, 114.0, 114.0, 112.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 126.0, 130.0, 177.0, 134.0, 125.0, 130.0, 126.0, 149.0, 131.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 167.0, 164.0, 166.0, 164.0, 164.0, 165.0, 166.0, 166.0], "value": 165.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 115.0, 77.0, 77.0, 76.0, 76.0, 93.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 108.0, 84.0, 84.0, 85.0, 104.0, 85.0, 85.0, 107.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 150.0, 151.0, 151.0, 151.0, 150.0, 151.0, 152.0, 152.0], "value": 151.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 63.0, 65.0, 65.0, 71.0, 70.0, 66.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 56.0, 55.0, 56.0, 56.0, 58.0, 56.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 85.0, 83.0, 83.0, 83.0, 82.0, 85.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 137.0, 150.0, 146.0, 135.0, 149.0, 136.0, 135.0, 183.0, 137.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 201.0, 184.0, 194.0, 195.0, 196.0, 193.0, 195.0, 194.0, 192.0], "value": 193.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 71.0, 72.0, 70.0, 71.0, 70.0, 74.0, 70.0, 70.0], "value": 71.4, "unit": "score"}]}]} 10:46:20 INFO - Return code: 0 10:46:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:46:20 ERROR - # TBPL SUCCESS # 10:46:20 INFO - Running post-action listener: _resource_record_post_action 10:46:20 INFO - [mozharness: 2017-03-28 17:46:20.615959Z] Finished run-tests step (success) 10:46:20 INFO - Running post-run listener: _resource_record_post_run 10:46:20 INFO - Total resource usage - Wall time: 1207s; CPU: 13.0%; Read bytes: 4096; Write bytes: 374149120; Read time: 12; Write time: 320548 10:46:20 INFO - TinderboxPrint: CPU usage
13.0% 10:46:20 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:46:20 INFO - TinderboxPrint: I/O write bytes / time
374,149,120 / 320,548 10:46:20 INFO - TinderboxPrint: CPU idle
8,377.9 (86.9%) 10:46:20 INFO - TinderboxPrint: CPU user
1,206.2 (12.5%) 10:46:20 INFO - TinderboxPrint: Swap in / out
0 / 0 10:46:20 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114122752; Read time: 0; Write time: 124428 10:46:20 INFO - run-tests - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 260026368; Read time: 0; Write time: 196120 10:46:21 INFO - Running post-run listener: _upload_blobber_files 10:46:21 INFO - Blob upload gear active. 10:46:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:46:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:46:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:46:21 INFO - (blobuploader) - INFO - Open directory for files ... 10:46:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:46:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:46:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:22 INFO - (blobuploader) - INFO - Done attempting. 10:46:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:46:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:46:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:23 INFO - (blobuploader) - INFO - Done attempting. 10:46:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:46:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:46:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:24 INFO - (blobuploader) - INFO - Done attempting. 10:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:46:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:27 INFO - (blobuploader) - INFO - Done attempting. 10:46:27 INFO - (blobuploader) - INFO - Iteration through files over. 10:46:27 INFO - Return code: 0 10:46:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:46:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:46:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/699967123fa022cd323f4a4a8a35aab6ad027e1fcc4dd350365518258eb92c593361eb0f7b56f340fbcd5a891a26a6ce4f2db51d0b5bbdaed30891baffd5b718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80df935d6a0fabe6ee4879b09822e7ee905d2c8ec454f8f4e9f4566ef8bc08c64b17fb2d7bab0532dbdba18553f95eb0aa6c8581a381f7137c23acab46641940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/503a183564a812fd85428bfe11dc95c96b6a0114e5913520b9b62c3a6fae0509f1e20ac2cb2cbdefed1cf97b316d6b9028825740165d147c3a5e362b2562f6bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7dfeb6ecbe1b2a91a748f9a0159cb272d22c5f147c28aba8957caba8113f600f4cd4be38b28f1c4739284919f557c50ce79c8223bf02ad1c16bd428473b7fee"} 10:46:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:46:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:46:27 INFO - Contents: 10:46:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/699967123fa022cd323f4a4a8a35aab6ad027e1fcc4dd350365518258eb92c593361eb0f7b56f340fbcd5a891a26a6ce4f2db51d0b5bbdaed30891baffd5b718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80df935d6a0fabe6ee4879b09822e7ee905d2c8ec454f8f4e9f4566ef8bc08c64b17fb2d7bab0532dbdba18553f95eb0aa6c8581a381f7137c23acab46641940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/503a183564a812fd85428bfe11dc95c96b6a0114e5913520b9b62c3a6fae0509f1e20ac2cb2cbdefed1cf97b316d6b9028825740165d147c3a5e362b2562f6bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7dfeb6ecbe1b2a91a748f9a0159cb272d22c5f147c28aba8957caba8113f600f4cd4be38b28f1c4739284919f557c50ce79c8223bf02ad1c16bd428473b7fee"} 10:46:27 INFO - Running post-run listener: copy_logs_to_upload_dir 10:46:27 INFO - Copying logs to upload dir... 10:46:27 INFO - mkdir: /builds/slave/test/build/upload/logs 10:46:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.191006 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-03-28 10:46:27.674098) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 10:46:27.677636) ========= 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=652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/699967123fa022cd323f4a4a8a35aab6ad027e1fcc4dd350365518258eb92c593361eb0f7b56f340fbcd5a891a26a6ce4f2db51d0b5bbdaed30891baffd5b718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80df935d6a0fabe6ee4879b09822e7ee905d2c8ec454f8f4e9f4566ef8bc08c64b17fb2d7bab0532dbdba18553f95eb0aa6c8581a381f7137c23acab46641940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/503a183564a812fd85428bfe11dc95c96b6a0114e5913520b9b62c3a6fae0509f1e20ac2cb2cbdefed1cf97b316d6b9028825740165d147c3a5e362b2562f6bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7dfeb6ecbe1b2a91a748f9a0159cb272d22c5f147c28aba8957caba8113f600f4cd4be38b28f1c4739284919f557c50ce79c8223bf02ad1c16bd428473b7fee"} build_url:https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/BcCkWdkuRpqW29hYoa9Olg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/699967123fa022cd323f4a4a8a35aab6ad027e1fcc4dd350365518258eb92c593361eb0f7b56f340fbcd5a891a26a6ce4f2db51d0b5bbdaed30891baffd5b718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80df935d6a0fabe6ee4879b09822e7ee905d2c8ec454f8f4e9f4566ef8bc08c64b17fb2d7bab0532dbdba18553f95eb0aa6c8581a381f7137c23acab46641940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/503a183564a812fd85428bfe11dc95c96b6a0114e5913520b9b62c3a6fae0509f1e20ac2cb2cbdefed1cf97b316d6b9028825740165d147c3a5e362b2562f6bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7dfeb6ecbe1b2a91a748f9a0159cb272d22c5f147c28aba8957caba8113f600f4cd4be38b28f1c4739284919f557c50ce79c8223bf02ad1c16bd428473b7fee"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 10:46:27.703083) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:46:27.703423) ========= 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=652eb03db935cfc1c15ebda0000001ad-1490721919.267223-465705802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004369 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:46:27.789224) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 10:46:27.789674) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 10:46:27.790027) ========= ========= Total master_lag: 0.19 =========