builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1486334951.51 results: success (0) revision: 553ed2dadb84860cccc6541bfec86dec499a67fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.513724) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.514106) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.514369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.562236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.562491) ========= 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=8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-05 14:49:12-- 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% 46.6M=0s 2017-02-05 14:49:12 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136700 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.717408) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.717694) ========= 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=8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017340 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:11.770426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 14:49:11.770740) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 553ed2dadb84860cccc6541bfec86dec499a67fc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 553ed2dadb84860cccc6541bfec86dec499a67fc --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=8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-05 14:49:12,315 truncating revision to first 12 chars 2017-02-05 14:49:12,315 Setting DEBUG logging. 2017-02-05 14:49:12,315 attempt 1/10 2017-02-05 14:49:12,315 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/553ed2dadb84?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-05 14:49:13,822 unpacking tar archive at: mozilla-aurora-553ed2dadb84/testing/mozharness/ program finished with exit code 0 elapsedTime=1.805065 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 14:49:13.589123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:13.589436) ========= script_repo_revision: 553ed2dadb84860cccc6541bfec86dec499a67fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:13.592827) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:13.593204) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 14:49:13.603431) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-02-05 14:49:13.603801) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-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=8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:49:14 INFO - MultiFileLogger online at 20170205 14:49:14 in /builds/slave/test 14:49:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 14:49:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:49:14 INFO - {'append_to_log': False, 14:49:14 INFO - 'base_work_dir': '/builds/slave/test', 14:49:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 14:49:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:49:14 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 14:49:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:49:14 INFO - 'config_files': ('talos/linux_config.py',), 14:49:14 INFO - 'default_actions': ('clobber', 14:49:14 INFO - 'read-buildbot-config', 14:49:14 INFO - 'download-and-extract', 14:49:14 INFO - 'populate-webroot', 14:49:14 INFO - 'create-virtualenv', 14:49:14 INFO - 'install', 14:49:14 INFO - 'run-tests'), 14:49:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:49:14 INFO - 'download_minidump_stackwalk': True, 14:49:14 INFO - 'download_symbols': 'ondemand', 14:49:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:49:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:49:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:49:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:49:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:49:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:49:14 INFO - 'installer_path': 'installer.exe', 14:49:14 INFO - 'log_level': 'info', 14:49:14 INFO - 'log_name': 'talos', 14:49:14 INFO - 'log_to_console': True, 14:49:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:49:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:49:14 INFO - 'opt_config_files': (), 14:49:14 INFO - 'pip_index': False, 14:49:14 INFO - 'sps_profile': False, 14:49:14 INFO - 'sps_profile_interval': 0, 14:49:14 INFO - 'suite': 'dromaeojs', 14:49:14 INFO - 'system_bits': '32', 14:49:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:49:14 INFO - 'title': 'talos-linux64-ix-038', 14:49:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:49:14 INFO - 'use_talos_json': True, 14:49:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:49:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:49:14 INFO - 'work_dir': 'build'} 14:49:14 INFO - [mozharness: 2017-02-05 22:49:14.242078Z] Running clobber step. 14:49:14 INFO - Running pre-action listener: _resource_record_pre_action 14:49:14 INFO - Running main action method: clobber 14:49:14 INFO - rmtree: /builds/slave/test/build 14:49:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:49:15 INFO - Running post-action listener: _resource_record_post_action 14:49:15 INFO - [mozharness: 2017-02-05 22:49:15.229485Z] Finished clobber step (success) 14:49:15 INFO - [mozharness: 2017-02-05 22:49:15.229586Z] Running read-buildbot-config step. 14:49:15 INFO - Running pre-action listener: _resource_record_pre_action 14:49:15 INFO - Running main action method: read_buildbot_config 14:49:15 INFO - Using buildbot properties: 14:49:15 INFO - { 14:49:15 INFO - "project": "", 14:49:15 INFO - "product": "firefox", 14:49:15 INFO - "who": "philringnalda@gmail.com", 14:49:15 INFO - "installer_path": "public/build/target.tar.bz2", 14:49:15 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:49:15 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 14:49:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 14:49:15 INFO - "stage_platform": "linux64", 14:49:15 INFO - "basedir": "/builds/slave/test", 14:49:15 INFO - "buildnumber": 12, 14:49:15 INFO - "platform": "ubuntu64_hw", 14:49:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:49:15 INFO - "slavebuilddir": "test", 14:49:15 INFO - "taskId": "dWRNT79YRWit_FpQkvCoZQ", 14:49:15 INFO - "branch": "mozilla-aurora", 14:49:15 INFO - "script_repo_revision": "production", 14:49:15 INFO - "revision": "553ed2dadb84860cccc6541bfec86dec499a67fc", 14:49:15 INFO - "slavename": "talos-linux64-ix-038", 14:49:15 INFO - "repo_path": "releases/mozilla-aurora" 14:49:15 INFO - } 14:49:15 INFO - Finding installer, test and symbols from parent task. 14:49:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dWRNT79YRWit_FpQkvCoZQ'}, attempt #1 14:49:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dWRNT79YRWit_FpQkvCoZQ'}, attempt #1 14:49:16 INFO - Task dependencies: Qmoiejh4TiCkMiH44HFANA 14:49:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA'}, attempt #1 14:49:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2 14:49:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/test_packages.json 14:49:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.crashreporter-symbols.zip 14:49:16 INFO - Running post-action listener: _resource_record_post_action 14:49:16 INFO - [mozharness: 2017-02-05 22:49:16.610846Z] Finished read-buildbot-config step (success) 14:49:16 INFO - [mozharness: 2017-02-05 22:49:16.611066Z] Running download-and-extract step. 14:49:16 INFO - Running pre-action listener: _resource_record_pre_action 14:49:16 INFO - Running main action method: download_and_extract 14:49:16 INFO - mkdir: /builds/slave/test/build/tests 14:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:16 INFO - trying https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.test_packages.json 14:49:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:49:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:49:17 INFO - Downloaded 896 bytes. 14:49:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:49:17 INFO - Using the following test package requirements: 14:49:17 INFO - {u'common': [u'target.common.tests.zip'], 14:49:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:49:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:49:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:49:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:49:17 INFO - u'mozbase': [u'target.common.tests.zip'], 14:49:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:49:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:49:17 INFO - u'web-platform': [u'target.common.tests.zip', 14:49:17 INFO - u'target.web-platform.tests.zip'], 14:49:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:49:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:49:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.common.tests.zip 14:49:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:49:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.common.tests.zip into memory 14:49:17 INFO - Expected file size: 21853971 14:49:17 INFO - Obtained file size: 21853971 14:49:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:49:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.common.tests.zip 14:49:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:49:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.common.tests.zip into memory 14:49:22 INFO - Expected file size: 21853971 14:49:22 INFO - Obtained file size: 21853971 14:49:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.talos.tests.zip 14:49:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:49:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.talos.tests.zip into memory 14:49:28 INFO - Expected file size: 14403485 14:49:28 INFO - Obtained file size: 14403485 14:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:28 INFO - trying https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2 14:49:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:49:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:49:29 INFO - Downloaded 66106455 bytes. 14:49:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2 14:49:29 INFO - mkdir: /builds/slave/test/properties 14:49:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:49:29 INFO - Writing to file /builds/slave/test/properties/build_url 14:49:29 INFO - Contents: 14:49:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2 14:49:29 INFO - Running post-action listener: _resource_record_post_action 14:49:29 INFO - Running post-action listener: set_extra_try_arguments 14:49:29 INFO - [mozharness: 2017-02-05 22:49:29.437895Z] Finished download-and-extract step (success) 14:49:29 INFO - [mozharness: 2017-02-05 22:49:29.437992Z] Running populate-webroot step. 14:49:29 INFO - Running pre-action listener: _resource_record_pre_action 14:49:29 INFO - Running main action method: populate_webroot 14:49:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:49:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:49:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:49:29 INFO - '--tppagecycles', 14:49:29 INFO - '7'], 14:49:29 INFO - 'tests': ['tsvgm']}}, 14:49:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:49:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:49:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:49:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:49:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:49:29 INFO - 'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:49:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:49:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:49:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:49:29 INFO - 'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['damp', 'tps']}, 14:49:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:49:29 INFO - 'tests': ['damp', 'tps']}, 14:49:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['dromaeo_dom']}, 14:49:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:49:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['basic_compositor_video']}, 14:49:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:49:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['a11yr', 14:49:29 INFO - 'ts_paint', 14:49:29 INFO - 'tpaint', 14:49:29 INFO - 'sessionrestore', 14:49:29 INFO - 'sessionrestore_no_auto_restore', 14:49:29 INFO - 'tabpaint']}, 14:49:29 INFO - 'other-e10s': {'tests': ['a11yr', 14:49:29 INFO - 'ts_paint', 14:49:29 INFO - 'tpaint', 14:49:29 INFO - 'sessionrestore', 14:49:29 INFO - 'sessionrestore_no_auto_restore', 14:49:29 INFO - 'tabpaint']}, 14:49:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:49:29 INFO - 'ts_paint', 14:49:29 INFO - 'tpaint', 14:49:29 INFO - 'sessionrestore', 14:49:29 INFO - 'sessionrestore_no_auto_restore', 14:49:29 INFO - 'tabpaint']}, 14:49:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:49:29 INFO - 'ts_paint', 14:49:29 INFO - 'tpaint', 14:49:29 INFO - 'sessionrestore', 14:49:29 INFO - 'sessionrestore_no_auto_restore', 14:49:29 INFO - 'tabpaint']}, 14:49:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['a11yr', 14:49:29 INFO - 'ts_paint', 14:49:29 INFO - 'tpaint', 14:49:29 INFO - 'sessionrestore', 14:49:29 INFO - 'sessionrestore_no_auto_restore', 14:49:29 INFO - 'tabpaint']}, 14:49:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['a11yr', 14:49:29 INFO - 'ts_paint', 14:49:29 INFO - 'tpaint', 14:49:29 INFO - 'sessionrestore', 14:49:29 INFO - 'sessionrestore_no_auto_restore', 14:49:29 INFO - 'tabpaint']}, 14:49:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['tsvgx', 14:49:29 INFO - 'tsvgr_opacity', 14:49:29 INFO - 'tart', 14:49:29 INFO - 'tscrollx', 14:49:29 INFO - 'cart', 14:49:29 INFO - 'tsvg_static']}, 14:49:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:49:29 INFO - 'tsvgr_opacity', 14:49:29 INFO - 'tart', 14:49:29 INFO - 'tscrollx', 14:49:29 INFO - 'cart', 14:49:29 INFO - 'tsvg_static']}, 14:49:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:49:29 INFO - 'talos_options': ['--disable-e10s'], 14:49:29 INFO - 'tests': ['tp5o']}, 14:49:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:49:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:49:29 INFO - 'talos_options': ['--disable-e10s', 14:49:29 INFO - '--xperf_path', 14:49:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:49:29 INFO - 'tests': ['tp5n']}, 14:49:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:49:29 INFO - 'talos_options': ['--xperf_path', 14:49:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:49:29 INFO - 'tests': ['tp5n']}}, 14:49:29 INFO - 'talos.zip': {'path': '', 14:49:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:49:29 INFO - Running post-action listener: _resource_record_post_action 14:49:29 INFO - [mozharness: 2017-02-05 22:49:29.451134Z] Finished populate-webroot step (success) 14:49:29 INFO - [mozharness: 2017-02-05 22:49:29.451224Z] Running create-virtualenv step. 14:49:29 INFO - Running pre-action listener: _resource_record_pre_action 14:49:29 INFO - Running main action method: create_virtualenv 14:49:29 INFO - Creating virtualenv /builds/slave/test/build/venv 14:49:29 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 14:49:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:49:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:49:29 INFO - Using real prefix '/usr' 14:49:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:49:30 INFO - Installing distribute.............................................................................................................................................................................................done. 14:49:32 INFO - Installing pip.................done. 14:49:32 INFO - Return code: 0 14:49:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:49:32 INFO - Reading from file tmpfile_stdout 14:49:32 INFO - Output received: 14:49:32 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) 14:49:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:49:32 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')]} 14:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:32 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')]} 14:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:32 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:32 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 14:49:32 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 14:49:32 INFO - Using env: {'DISPLAY': ':0', 14:49:32 INFO - 'HOME': '/home/cltbld', 14:49:32 INFO - 'LANG': 'en_US.UTF-8', 14:49:32 INFO - 'LANGUAGE': 'en_US:en', 14:49:32 INFO - 'LOGNAME': 'cltbld', 14:49:32 INFO - 'MAIL': '/var/mail/cltbld', 14:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:32 INFO - 'MOZ_NO_REMOTE': '1', 14:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:32 INFO - 'NO_EM_RESTART': '1', 14:49:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:32 INFO - 'PWD': '/builds/slave/test', 14:49:32 INFO - 'SHELL': '/bin/bash', 14:49:32 INFO - 'SHLVL': '1', 14:49:32 INFO - 'TERM': 'linux', 14:49:32 INFO - 'TMOUT': '86400', 14:49:32 INFO - 'USER': 'cltbld', 14:49:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:32 INFO - '_': '/tools/buildbot/bin/python'} 14:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:32 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 14:49:32 INFO - Cleaning up... 14:49:32 INFO - Return code: 0 14:49:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:49:32 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')]} 14:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:32 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')]} 14:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:32 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:32 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 14:49:32 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 14:49:32 INFO - Using env: {'DISPLAY': ':0', 14:49:32 INFO - 'HOME': '/home/cltbld', 14:49:32 INFO - 'LANG': 'en_US.UTF-8', 14:49:32 INFO - 'LANGUAGE': 'en_US:en', 14:49:32 INFO - 'LOGNAME': 'cltbld', 14:49:32 INFO - 'MAIL': '/var/mail/cltbld', 14:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:32 INFO - 'MOZ_NO_REMOTE': '1', 14:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:32 INFO - 'NO_EM_RESTART': '1', 14:49:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:32 INFO - 'PWD': '/builds/slave/test', 14:49:32 INFO - 'SHELL': '/bin/bash', 14:49:32 INFO - 'SHLVL': '1', 14:49:32 INFO - 'TERM': 'linux', 14:49:32 INFO - 'TMOUT': '86400', 14:49:32 INFO - 'USER': 'cltbld', 14:49:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:32 INFO - '_': '/tools/buildbot/bin/python'} 14:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:32 INFO - Downloading/unpacking psutil>=3.1.1 14:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:32 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 14:49:32 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 14:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:49:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:49:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:49:34 INFO - Installing collected packages: psutil 14:49:34 INFO - Running setup.py install for psutil 14:49:34 INFO - building 'psutil._psutil_linux' extension 14:49:34 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 14:49:35 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 14:49:35 INFO - building 'psutil._psutil_posix' extension 14:49:35 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 14:49:35 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 14:49:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:49:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:49:35 INFO - Successfully installed psutil 14:49:35 INFO - Cleaning up... 14:49:35 INFO - Return code: 0 14:49:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:49:35 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')]} 14:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:35 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')]} 14:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:49:35 INFO - Using env: {'DISPLAY': ':0', 14:49:35 INFO - 'HOME': '/home/cltbld', 14:49:35 INFO - 'LANG': 'en_US.UTF-8', 14:49:35 INFO - 'LANGUAGE': 'en_US:en', 14:49:35 INFO - 'LOGNAME': 'cltbld', 14:49:35 INFO - 'MAIL': '/var/mail/cltbld', 14:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:35 INFO - 'MOZ_NO_REMOTE': '1', 14:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:35 INFO - 'NO_EM_RESTART': '1', 14:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:35 INFO - 'PWD': '/builds/slave/test', 14:49:35 INFO - 'SHELL': '/bin/bash', 14:49:35 INFO - 'SHLVL': '1', 14:49:35 INFO - 'TERM': 'linux', 14:49:35 INFO - 'TMOUT': '86400', 14:49:35 INFO - 'USER': 'cltbld', 14:49:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:35 INFO - '_': '/tools/buildbot/bin/python'} 14:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:49:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:49:37 INFO - Installing collected packages: mozsystemmonitor 14:49:37 INFO - Running setup.py install for mozsystemmonitor 14:49:37 INFO - Successfully installed mozsystemmonitor 14:49:37 INFO - Cleaning up... 14:49:37 INFO - Return code: 0 14:49:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:37 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:37 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 14:49:37 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 14:49:37 INFO - Using env: {'DISPLAY': ':0', 14:49:37 INFO - 'HOME': '/home/cltbld', 14:49:37 INFO - 'LANG': 'en_US.UTF-8', 14:49:37 INFO - 'LANGUAGE': 'en_US:en', 14:49:37 INFO - 'LOGNAME': 'cltbld', 14:49:37 INFO - 'MAIL': '/var/mail/cltbld', 14:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:37 INFO - 'MOZ_NO_REMOTE': '1', 14:49:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:37 INFO - 'NO_EM_RESTART': '1', 14:49:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:37 INFO - 'PWD': '/builds/slave/test', 14:49:37 INFO - 'SHELL': '/bin/bash', 14:49:37 INFO - 'SHLVL': '1', 14:49:37 INFO - 'TERM': 'linux', 14:49:37 INFO - 'TMOUT': '86400', 14:49:37 INFO - 'USER': 'cltbld', 14:49:37 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:37 INFO - '_': '/tools/buildbot/bin/python'} 14:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:38 INFO - Downloading/unpacking jsonschema==2.5.1 14:49:38 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 14:49:38 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 14:49:38 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 14:49:38 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 14:49:38 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 14:49:38 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 14:49:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:49:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:49:39 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 14:49:39 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 14:49:39 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 14:49:39 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 14:49:39 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 14:49:39 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 14:49:40 INFO - Downloading functools32-3.2.3-2.tar.gz 14:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:49:40 INFO - warning: no files found matching '*.txt' 14:49:40 INFO - no previously-included directories found matching 'build' 14:49:40 INFO - no previously-included directories found matching 'dist' 14:49:40 INFO - no previously-included directories found matching '.git*' 14:49:40 INFO - Installing collected packages: jsonschema, functools32 14:49:40 INFO - Running setup.py install for functools32 14:49:40 INFO - warning: no files found matching '*.txt' 14:49:40 INFO - no previously-included directories found matching 'build' 14:49:40 INFO - no previously-included directories found matching 'dist' 14:49:40 INFO - no previously-included directories found matching '.git*' 14:49:40 INFO - Successfully installed jsonschema functools32 14:49:40 INFO - Cleaning up... 14:49:40 INFO - Return code: 0 14:49:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:40 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:40 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 14:49:40 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 14:49:40 INFO - Using env: {'DISPLAY': ':0', 14:49:40 INFO - 'HOME': '/home/cltbld', 14:49:40 INFO - 'LANG': 'en_US.UTF-8', 14:49:40 INFO - 'LANGUAGE': 'en_US:en', 14:49:40 INFO - 'LOGNAME': 'cltbld', 14:49:40 INFO - 'MAIL': '/var/mail/cltbld', 14:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:40 INFO - 'MOZ_NO_REMOTE': '1', 14:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:40 INFO - 'NO_EM_RESTART': '1', 14:49:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:40 INFO - 'PWD': '/builds/slave/test', 14:49:40 INFO - 'SHELL': '/bin/bash', 14:49:40 INFO - 'SHLVL': '1', 14:49:40 INFO - 'TERM': 'linux', 14:49:40 INFO - 'TMOUT': '86400', 14:49:40 INFO - 'USER': 'cltbld', 14:49:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:40 INFO - '_': '/tools/buildbot/bin/python'} 14:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:49:40 INFO - Cleaning up... 14:49:40 INFO - Return code: 0 14:49:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:40 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:40 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 14:49:40 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 14:49:40 INFO - Using env: {'DISPLAY': ':0', 14:49:40 INFO - 'HOME': '/home/cltbld', 14:49:40 INFO - 'LANG': 'en_US.UTF-8', 14:49:40 INFO - 'LANGUAGE': 'en_US:en', 14:49:40 INFO - 'LOGNAME': 'cltbld', 14:49:40 INFO - 'MAIL': '/var/mail/cltbld', 14:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:40 INFO - 'MOZ_NO_REMOTE': '1', 14:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:40 INFO - 'NO_EM_RESTART': '1', 14:49:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:40 INFO - 'PWD': '/builds/slave/test', 14:49:40 INFO - 'SHELL': '/bin/bash', 14:49:40 INFO - 'SHLVL': '1', 14:49:40 INFO - 'TERM': 'linux', 14:49:40 INFO - 'TMOUT': '86400', 14:49:40 INFO - 'USER': 'cltbld', 14:49:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:40 INFO - '_': '/tools/buildbot/bin/python'} 14:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:40 INFO - Downloading/unpacking blobuploader==1.2.4 14:49:40 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 14:49:40 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 14:49:40 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 14:49:40 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 14:49:40 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 14:49:40 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 14:49:42 INFO - Downloading blobuploader-1.2.4.tar.gz 14:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:49:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:49:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 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 14:49:43 INFO - Downloading docopt-0.6.1.tar.gz 14:49:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:49:43 INFO - Installing collected packages: blobuploader, requests, docopt 14:49:43 INFO - Running setup.py install for blobuploader 14:49:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:49:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:49:43 INFO - Running setup.py install for requests 14:49:44 INFO - Running setup.py install for docopt 14:49:44 INFO - Successfully installed blobuploader requests docopt 14:49:44 INFO - Cleaning up... 14:49:44 INFO - Return code: 0 14:49:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:44 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:44 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 14:49:44 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 14:49:44 INFO - Using env: {'DISPLAY': ':0', 14:49:44 INFO - 'HOME': '/home/cltbld', 14:49:44 INFO - 'LANG': 'en_US.UTF-8', 14:49:44 INFO - 'LANGUAGE': 'en_US:en', 14:49:44 INFO - 'LOGNAME': 'cltbld', 14:49:44 INFO - 'MAIL': '/var/mail/cltbld', 14:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:44 INFO - 'MOZ_NO_REMOTE': '1', 14:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:44 INFO - 'NO_EM_RESTART': '1', 14:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:44 INFO - 'PWD': '/builds/slave/test', 14:49:44 INFO - 'SHELL': '/bin/bash', 14:49:44 INFO - 'SHLVL': '1', 14:49:44 INFO - 'TERM': 'linux', 14:49:44 INFO - 'TMOUT': '86400', 14:49:44 INFO - 'USER': 'cltbld', 14:49:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:44 INFO - '_': '/tools/buildbot/bin/python'} 14:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:49:44 INFO - Running setup.py (path:/tmp/pip-wyoawb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:49:44 INFO - Running setup.py (path:/tmp/pip-tGsG5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:49:44 INFO - Running setup.py (path:/tmp/pip-wi0rdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:49:44 INFO - Running setup.py (path:/tmp/pip-uVhOGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:49:44 INFO - Running setup.py (path:/tmp/pip-FT3LJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:49:44 INFO - Running setup.py (path:/tmp/pip-wQDLHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:49:45 INFO - Running setup.py (path:/tmp/pip-h1j23Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:49:45 INFO - Running setup.py (path:/tmp/pip-iUc4JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:49:45 INFO - Running setup.py (path:/tmp/pip-QxBvgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:49:45 INFO - Running setup.py (path:/tmp/pip-hVhn3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:49:45 INFO - Running setup.py (path:/tmp/pip-mWgRfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:49:45 INFO - Running setup.py (path:/tmp/pip-Rb7u7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:49:45 INFO - Running setup.py (path:/tmp/pip-eo5v3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:49:45 INFO - Running setup.py (path:/tmp/pip-odPNeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:45 INFO - Running setup.py (path:/tmp/pip-PoHPcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:49:45 INFO - Running setup.py (path:/tmp/pip-bjQP93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:49:45 INFO - Running setup.py (path:/tmp/pip-DRv4th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:49:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:49:45 INFO - Running setup.py install for manifestparser 14:49:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:49:45 INFO - Running setup.py install for mozcrash 14:49:46 INFO - Running setup.py install for mozdebug 14:49:46 INFO - Running setup.py install for mozdevice 14:49:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Running setup.py install for mozfile 14:49:46 INFO - Running setup.py install for mozhttpd 14:49:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Running setup.py install for mozinfo 14:49:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Running setup.py install for mozInstall 14:49:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Running setup.py install for mozleak 14:49:46 INFO - Running setup.py install for mozlog 14:49:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Running setup.py install for moznetwork 14:49:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:49:46 INFO - Running setup.py install for mozprocess 14:49:47 INFO - Running setup.py install for mozprofile 14:49:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:49:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:49:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:49:47 INFO - Running setup.py install for mozrunner 14:49:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:49:47 INFO - Running setup.py install for mozscreenshot 14:49:47 INFO - Running setup.py install for moztest 14:49:47 INFO - Running setup.py install for mozversion 14:49:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:49:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:49:47 INFO - Cleaning up... 14:49:47 INFO - Return code: 0 14:49:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:47 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:47 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 14:49:47 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 14:49:47 INFO - Using env: {'DISPLAY': ':0', 14:49:47 INFO - 'HOME': '/home/cltbld', 14:49:47 INFO - 'LANG': 'en_US.UTF-8', 14:49:47 INFO - 'LANGUAGE': 'en_US:en', 14:49:47 INFO - 'LOGNAME': 'cltbld', 14:49:47 INFO - 'MAIL': '/var/mail/cltbld', 14:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:47 INFO - 'MOZ_NO_REMOTE': '1', 14:49:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:47 INFO - 'NO_EM_RESTART': '1', 14:49:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:47 INFO - 'PWD': '/builds/slave/test', 14:49:47 INFO - 'SHELL': '/bin/bash', 14:49:47 INFO - 'SHLVL': '1', 14:49:47 INFO - 'TERM': 'linux', 14:49:47 INFO - 'TMOUT': '86400', 14:49:47 INFO - 'USER': 'cltbld', 14:49:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:47 INFO - '_': '/tools/buildbot/bin/python'} 14:49:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:49:47 INFO - Running setup.py (path:/tmp/pip-vXJR_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:49:47 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)) 14:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:49:47 INFO - Running setup.py (path:/tmp/pip-ZIy7Z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:49:47 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)) 14:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:49:47 INFO - Running setup.py (path:/tmp/pip-Y759Eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:49:48 INFO - Running setup.py (path:/tmp/pip-GGAObn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:49:48 INFO - Running setup.py (path:/tmp/pip-6dhJpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:49:48 INFO - Running setup.py (path:/tmp/pip-VVxgqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:49:48 INFO - Running setup.py (path:/tmp/pip-Jje4xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:49:48 INFO - Running setup.py (path:/tmp/pip-S10pba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:49:48 INFO - Running setup.py (path:/tmp/pip-Jerxph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:49:48 INFO - Running setup.py (path:/tmp/pip-_xVtXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:49:48 INFO - Running setup.py (path:/tmp/pip-yoxbIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:49:48 INFO - Running setup.py (path:/tmp/pip-l8In_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:49:48 INFO - Running setup.py (path:/tmp/pip-Zl2rLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:49:48 INFO - Running setup.py (path:/tmp/pip-I4XLw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:48 INFO - Running setup.py (path:/tmp/pip-T3G6Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:49:48 INFO - Running setup.py (path:/tmp/pip-jgauD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:49:48 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)) 14:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:49:48 INFO - Running setup.py (path:/tmp/pip-pqGYJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:49:49 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)) 14:49:49 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)) 14:49:49 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)) 14:49:49 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)) 14:49:49 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)) 14:49:49 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)) 14:49:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:49:49 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 14:49:49 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 14:49:49 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 14:49:49 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 14:49:49 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 14:49:49 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 14:49:51 INFO - Downloading blessings-1.6.tar.gz 14:49:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:49:51 INFO - Installing collected packages: blessings 14:49:51 INFO - Running setup.py install for blessings 14:49:51 INFO - Successfully installed blessings 14:49:51 INFO - Cleaning up... 14:49:51 INFO - Return code: 0 14:49:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:49:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:51 INFO - Reading from file tmpfile_stdout 14:49:51 INFO - Current package versions: 14:49:51 INFO - argparse == 1.2.1 14:49:51 INFO - blessings == 1.6 14:49:51 INFO - blobuploader == 1.2.4 14:49:51 INFO - docopt == 0.6.1 14:49:51 INFO - functools32 == 3.2.3-2 14:49:51 INFO - jsonschema == 2.5.1 14:49:51 INFO - manifestparser == 1.1 14:49:51 INFO - mozInstall == 1.12 14:49:51 INFO - mozcrash == 1.0 14:49:51 INFO - mozdebug == 0.1 14:49:51 INFO - mozdevice == 0.49 14:49:51 INFO - mozfile == 1.2 14:49:51 INFO - mozhttpd == 0.7 14:49:51 INFO - mozinfo == 0.9 14:49:51 INFO - mozleak == 0.1 14:49:51 INFO - mozlog == 3.4 14:49:51 INFO - moznetwork == 0.27 14:49:51 INFO - mozprocess == 0.24 14:49:51 INFO - mozprofile == 0.28 14:49:51 INFO - mozrunner == 6.13 14:49:51 INFO - mozscreenshot == 0.1 14:49:51 INFO - mozsystemmonitor == 0.3 14:49:51 INFO - moztest == 0.8 14:49:51 INFO - mozversion == 1.4 14:49:51 INFO - psutil == 3.1.1 14:49:51 INFO - requests == 1.2.3 14:49:51 INFO - wsgiref == 0.1.2 14:49:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:51 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')]} 14:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:51 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')]} 14:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:51 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:51 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 14:49:51 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 14:49:51 INFO - Using env: {'DISPLAY': ':0', 14:49:51 INFO - 'HOME': '/home/cltbld', 14:49:51 INFO - 'LANG': 'en_US.UTF-8', 14:49:51 INFO - 'LANGUAGE': 'en_US:en', 14:49:51 INFO - 'LOGNAME': 'cltbld', 14:49:51 INFO - 'MAIL': '/var/mail/cltbld', 14:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:51 INFO - 'MOZ_NO_REMOTE': '1', 14:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:51 INFO - 'NO_EM_RESTART': '1', 14:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:51 INFO - 'PWD': '/builds/slave/test', 14:49:51 INFO - 'SHELL': '/bin/bash', 14:49:51 INFO - 'SHLVL': '1', 14:49:51 INFO - 'TERM': 'linux', 14:49:51 INFO - 'TMOUT': '86400', 14:49:51 INFO - 'USER': 'cltbld', 14:49:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:51 INFO - '_': '/tools/buildbot/bin/python'} 14:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 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)) 14:49:51 INFO - Cleaning up... 14:49:51 INFO - Return code: 0 14:49:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:49:51 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')]} 14:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:51 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')]} 14:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:51 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 0x1a2b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9d980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, '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': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', '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 14:49:51 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 14:49:51 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 14:49:51 INFO - Using env: {'DISPLAY': ':0', 14:49:51 INFO - 'HOME': '/home/cltbld', 14:49:51 INFO - 'LANG': 'en_US.UTF-8', 14:49:51 INFO - 'LANGUAGE': 'en_US:en', 14:49:51 INFO - 'LOGNAME': 'cltbld', 14:49:51 INFO - 'MAIL': '/var/mail/cltbld', 14:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:51 INFO - 'MOZ_NO_REMOTE': '1', 14:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:51 INFO - 'NO_EM_RESTART': '1', 14:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:51 INFO - 'PWD': '/builds/slave/test', 14:49:51 INFO - 'SHELL': '/bin/bash', 14:49:51 INFO - 'SHLVL': '1', 14:49:51 INFO - 'TERM': 'linux', 14:49:51 INFO - 'TMOUT': '86400', 14:49:51 INFO - 'USER': 'cltbld', 14:49:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:51 INFO - '_': '/tools/buildbot/bin/python'} 14:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:49:52 INFO - Cleaning up... 14:49:52 INFO - Return code: 0 14:49:52 INFO - Running post-action listener: _resource_record_post_action 14:49:52 INFO - Running post-action listener: _start_resource_monitoring 14:49:52 INFO - Starting resource monitoring. 14:49:52 INFO - [mozharness: 2017-02-05 22:49:52.203724Z] Finished create-virtualenv step (success) 14:49:52 INFO - [mozharness: 2017-02-05 22:49:52.204175Z] Running install step. 14:49:52 INFO - Running pre-action listener: _resource_record_pre_action 14:49:52 INFO - Running main action method: install 14:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:52 INFO - Reading from file tmpfile_stdout 14:49:52 INFO - Detecting whether we're running mozinstall >=1.0... 14:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:49:52 INFO - Reading from file tmpfile_stdout 14:49:52 INFO - Output received: 14:49:52 INFO - Usage: mozinstall [options] installer 14:49:52 INFO - Options: 14:49:52 INFO - -h, --help show this help message and exit 14:49:52 INFO - -d DEST, --destination=DEST 14:49:52 INFO - Directory to install application into. [default: 14:49:52 INFO - "/builds/slave/test"] 14:49:52 INFO - --app=APP Application being installed. [default: firefox] 14:49:52 INFO - mkdir: /builds/slave/test/build/application 14:49:52 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'] 14:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:50:08 INFO - Reading from file tmpfile_stdout 14:50:08 INFO - Output received: 14:50:08 INFO - /builds/slave/test/build/application/firefox/firefox 14:50:08 INFO - Running post-action listener: _resource_record_post_action 14:50:08 INFO - [mozharness: 2017-02-05 22:50:08.948973Z] Finished install step (success) 14:50:08 INFO - [mozharness: 2017-02-05 22:50:08.949250Z] Running run-tests step. 14:50:08 INFO - Running pre-action listener: _resource_record_pre_action 14:50:08 INFO - Running main action method: run_tests 14:50:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:50:08 INFO - Python 2.7.3 14:50:08 INFO - Return code: 0 14:50:08 INFO - grabbing minidump binary from tooltool 14:50: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')]} 14:50:08 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 0x1d9ad00>, '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 0x1d9ba00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d9beb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:50:08 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 14:50:08 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 14:50:08 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 14:50:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:50:09 INFO - Return code: 0 14:50:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:50:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:50:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:50:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:50:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 14:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 14:50:09 INFO - Using env: {'DISPLAY': ':0', 14:50:09 INFO - 'HOME': '/home/cltbld', 14:50:09 INFO - 'LANG': 'en_US.UTF-8', 14:50:09 INFO - 'LANGUAGE': 'en_US:en', 14:50:09 INFO - 'LOGNAME': 'cltbld', 14:50:09 INFO - 'MAIL': '/var/mail/cltbld', 14:50:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:50:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:09 INFO - 'MOZ_NO_REMOTE': '1', 14:50:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:50:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:50:09 INFO - 'NO_EM_RESTART': '1', 14:50:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:09 INFO - 'PWD': '/builds/slave/test', 14:50:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:50:09 INFO - 'SHELL': '/bin/bash', 14:50:09 INFO - 'SHLVL': '1', 14:50:09 INFO - 'TERM': 'linux', 14:50:09 INFO - 'TMOUT': '86400', 14:50:09 INFO - 'USER': 'cltbld', 14:50:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845', 14:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:09 INFO - '_': '/tools/buildbot/bin/python'} 14:50:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 14:50:09 INFO - mozversion application_buildid: 20170205215238 14:50:09 INFO - mozversion application_changeset: 553ed2dadb84860cccc6541bfec86dec499a67fc 14:50:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:50:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:50:09 INFO - mozversion application_name: Firefox 14:50:09 INFO - mozversion application_remotingname: firefox-dev 14:50:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:50:09 INFO - mozversion application_vendor: Mozilla 14:50:09 INFO - mozversion application_version: 53.0a2 14:50:09 INFO - mozversion platform_buildid: 20170205215238 14:50:09 INFO - mozversion platform_changeset: 553ed2dadb84860cccc6541bfec86dec499a67fc 14:50:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:50:09 INFO - mozversion platform_version: 53.0a2 14:50:09 INFO - using testdate: 1486335009 14:50:09 INFO - actual date: 1486335009 14:50:09 INFO - starting webserver on 'localhost:54274' 14:50:09 INFO - SUITE-START | Running 2 tests 14:50:09 INFO - TEST-START | dromaeo_css 14:50:09 INFO - operating with platform_type : linux_ 14:50:09 INFO - Initialising browser for dromaeo_css test... 14:50:09 INFO - TEST-INFO | started process 18239 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2tQ7kn/profile http://localhost:54274/getInfo.html) 14:50:13 INFO - PROCESS | 18239 | __metrics Screen width/height:1600/1200 14:50:13 INFO - PROCESS | 18239 | colorDepth:24 14:50:13 INFO - PROCESS | 18239 | Browser inner width/height: 1024/697 14:50:13 INFO - PROCESS | 18239 | __metrics 14:50:14 INFO - PROCESS | 18239 | 1486335014424 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) 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:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884: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 14:50:14 INFO - PROCESS | 18239 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:50:14 INFO - PROCESS | 18239 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:50:15 INFO - PROCESS | 18239 | console.error: PushService: 14:50:15 INFO - PROCESS | 18239 | stateChangeProcessEnqueue: Error transitioning state 14:50:15 INFO - PROCESS | 18239 | UnknownError 14:50:15 INFO - TEST-INFO | 18239: exit 0 14:50:15 INFO - Browser initialized. 14:50:15 INFO - Running cycle 1/1 for dromaeo_css test... 14:50:15 INFO - TEST-INFO | started process 18332 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2tQ7kn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:50:27 INFO - PROCESS | 18332 | RSS: Main: 138121216 14:50:27 INFO - PROCESS | 18332 | 14:53:28 INFO - PROCESS | 18332 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54274/tests/dromaeo/cssquery-ext.html) 14:53:29 INFO - PROCESS | 18332 | RSS: Main: 236843008 14:53:29 INFO - PROCESS | 18332 | 14:55:17 INFO - PROCESS | 18332 | ************************* 14:55:17 INFO - PROCESS | 18332 | A coding exception was thrown and uncaught in a Task. 14:55:17 INFO - PROCESS | 18332 | 14:55:17 ERROR - PROCESS | 18332 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:55:17 INFO - PROCESS | 18332 | Full stack: 14:55:17 INFO - PROCESS | 18332 | ************************* 14:56:31 INFO - PROCESS | 18332 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-ext.html (next: http://localhost:54274/tests/dromaeo/cssquery-jquery.html) 14:56:32 INFO - PROCESS | 18332 | RSS: Main: 226500608 14:56:32 INFO - PROCESS | 18332 | 14:59:33 INFO - PROCESS | 18332 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54274/tests/dromaeo/cssquery-mootools.html) 14:59:34 INFO - PROCESS | 18332 | RSS: Main: 201441280 14:59:34 INFO - PROCESS | 18332 | 15:02:36 INFO - PROCESS | 18332 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54274/tests/dromaeo/cssquery-prototype.html) 15:02:37 INFO - PROCESS | 18332 | RSS: Main: 221650944 15:02:37 INFO - PROCESS | 18332 | 15:05:39 INFO - PROCESS | 18332 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54274/tests/dromaeo/cssquery-yui.html) 15:05:40 INFO - PROCESS | 18332 | RSS: Main: 173150208 15:05:40 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-yui.html (next: http://localhost:54274/tests/dromaeo/cssquery-dojo.html) 15:08:43 INFO - PROCESS | 18332 | RSS: Main: 265265152 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | __start_tp_report 15:08:43 INFO - PROCESS | 18332 | _x_x_mozilla_page_load 15:08:43 INFO - PROCESS | 18332 | _x_x_mozilla_page_load_details 15:08:43 INFO - PROCESS | 18332 | |i|pagename|runs| 15:08:43 INFO - PROCESS | 18332 | |0;dojo.html;3295.7692961492694;3325.484549894767;3329.250918543328;3363.89908302751;3384.4415671414217;5440.156775699763;5537.667739935607;5550.222968784368;5560.277163968689;5575.470330318613;5501.559875209983;5514.5864060195545;5531.9723401383035;5532.806351777688;5566.359868615111;4980.078685443195;4993.251012348159;5024.995000999815;5090.312807770941;5104.515071068243;4912.877178070559;4930.654854160211;5066.670666406671;5096.617753668471;5121.590272778169;4994.950050499485;5001.099802035632;5045.167547354682;5099.4390617032095;5119.897602047957;5132.204508301219;5135.6148288878485;5142.228665700156;5143.794248230066;5176.974115129438;4807.879803004917;5021.472745361758;5021.6735912165595;5096.490350964911;5115.104856650071;5307.416184219733;5351.491608297206;5362.839114826562;5376.865578360533;5461.344638643388;5744.454276843693;5761.942380576183;5767.36558978512;5768.480836724673;5786;5839.824805255849;5842.503387212092;5854.092615644582;5865.090910908816;5869.706514674249;5292.867678308035;5375.005623959541;5473.343198816127;5597.384287728347;5612.326520817528;6013.488853447506;6014.699265036818;6016.488598469179;6017.909731354033;6037.426444487766;5765.221695071195;5814.418558144236;5815.476607105381;5831.9708401458565;5836.854078648068;5546.168074788814;5592;5842.912356314659;5848.2689663792025;5907.616004959664;5872.0898260769645;5920.407959204132;5927.762889484458;5940.435658612425;5960.791372302036;5925.496332811673;5929.6145750526075;5934.525237981037;5936.851578710481;5939.07944268639;4356.6732495063;4372.453443319585;4515.548445155524;4584.106099310669;4588.564086411786;4024.3359845625146;4064.349704047338;4099.590040995877;4108.589141085928;4113.25961326964;5691.402402747734;5710.7430165642645;5713.342965558931;5714.885702285931;5720.342160651495;5578.107502799533;5676.489115979582;5893.528517718572;5923.911141332884;5943.286805583271;5732.197492351074;5735.684537350402;5747.8563035924435;5749.88500229993;5778.913316300259;5062.063518249136;5069.543741063322;5078.542931136216;5079.288899554065;5087.949120508748;5882.470577648032;5942.524598032147;6100.450959413674;6226.906596401058;6227.844303892349;5872.383399743137;5998.670073146019;6014.669193194416;6025.487833534199;6027.578069535266;6188.381161883775;6204.162438070803;6219.595726277777;6227.9377206227355;6241.594296370721;5568.387477377566;5832.096025116114;5897.70511474416;5898.4101589840675;5923.526117910643;5663.830085097454;5827.70861456917;5876.706164691663;5900.586958913007;5917.733701983335;5767.048437007847;5775.566832487664;5778.27771528559;5783.479486846204;5803.158542011469;5775.624584402;5775.942240577709;5786.1899334094105;5787.045137552257;5788.82633520995;5511.200875872896;5559.16612508127;5569.275994120739;5602.1036634138345;5690.231818704588;5699.430056994267;5704.0588302929555;5708.315002199596;5733.369329373849;5741.024025915521;5592.832215033555;5593.356763972195;5594.524465420485;5597.04850175463;5603 15:08:43 INFO - PROCESS | 18332 | |1;ext.html;553.9192399049881;576.1300436341124;605.3522730678175;661.6989269882204;700.1038670501756;37267.25465490689;48067.278990815124;48801.75599122008;48815.75592122034;48817.51182488174;49438.50561494384;49905;49924;49930.251046234334;50036.74981625096;8921.063288354726;9166.266698664123;9304.720858374243;9501.762455938622;9506.096920792548;11199.608013719522;11513.078953683727;11522;11943.46254418549;11955.820662690066;11480.885191148065;11541.711457213594;11569.595064172756;11569.826452603218;11572.594959176431;11535.077193824474;11558.653240402802;11577.247478913843;11600.6519804406;11611.709707257301;11335.37655428951;11382.772344553145;11389.601363952304;11424.371659558721;11431.771364572745;46516.53483465156;46710.76644616755;47008.764956175;47035.76482117602;47093.76453117713;4491.483479399845;4605.30920361945;4679.298105284201;4729.385179926622;4766.904661906742;35293;35410;35541.644583554094;35833;36633.816830915675;4745.359376484165;4791.784369703337;4845.70925744453;4883.316335713003;4975.129352363322;298961;303478;391470.0426497878;395440;396023;75570.62214688891;76474.23525764783;76494.61752691312;76504;76580.61709691415;78966;80047.59976200081;80100.19899801044;80138.59930700426;80178.59910700526;21032.422568307436;21456.678149827763;21462.785372146078;21485.140594376364;21491.140354385963;14914.850851491563;15353.692926141415;15952.76070858938;16332.75500867488;16598.75101873473;23347.53304933892;24090.87954560251;24142.7585724144;24169.79151042441;24294.51410971806;8472.949738094281;8701.95431598948;8728.607212675444;8729.694460693972;8851.291896648252;1008.6167256442504;1018.9439580822977;1030.9587616495255;1053.068034789217;1071.4963966935527;32942.50586241209;34680.65319346774;34698;34844.477332840026;34985.43439481571;1110.5502271580024;1180.9586664466703;1199.2744389644315;1205.1323047405854;1244.3031902134833;17960.9101954492;18067.819321806615;18119.18463669138;18579.907100464683;18718.71921921172;550270;556013;557042;557316;560217;69904;70595;70636.64681676558;70808.87573372807;70873.2912670877;1552.922353882324;1564.5150003498952;1582.833802450749;1615.9838401616068;1634.5014770495113;32205.355892881544;33398.49902251517;33649.495257571645;33807.66192338045;33993.66006339905;56296;57777.71111144417;57810.13284800816;57863.13205302008;57868.13197802121;423067;425468;425935;425988;426007;47405.52594474011;47598.76200618975;48860.511394885594;49080.75459622679;49241.2613810786;53007.469925300255;53170.202446962554;53561.19658205052;54115.45884541104;54465.72767136139;44022.11955760931;44996.32505512355;45578;45585;45947.31079033884;43303.566964331214;44092.77953610211;44684.32973505335;45498.54501454943;45597.54402456066;43876.780616096716;44814.3277850839;44827.32759008552;44929;44963.775181125195;35787;39094.804525978325;39336.40995385129;39430.60569394384;39512.802435987636;28742.856285718437;28859.861024127127;28974.710252897203;29011.419771604873;29334.26664333409 15:08:43 INFO - PROCESS | 18332 | |2;jquery.html;396.3985498585714;421.4963119072709;428.1575382424776;433.7766050484001;442.60829166187915;17793.555161120963;21321.68017479737;21866.89066554669;23100.537989240205;23750.643740343865;23484.17805376808;23637.527249455;23849.88075059622;23870.88064559679;24045.15841945532;5289.889123284115;6019;6207.131001659771;6256.124142620037;6281.309056003836;6001.519878409738;6099.115628233903;6183.907241391383;6193.566450348478;6195.442410183083;5894.528437724972;5961.88076238475;5976.2230909981645;5977.31260904995;5987.9101813472835;5857.092150716646;5984.982552966007;5996.610220335686;6004.7297871595665;6070.757169713206;5485.753141108659;5486.478784515464;5490.176473528963;5493.752781124859;5495.6153069285165;26696.332591685168;27221.319467013283;27324.72675273242;27485.587716184273;27620.171394858186;3050.2984313607994;3084.722374986262;3087.783855130142;3088.1198858325492;3095.814251144916;15821.920890395475;16291.755623665536;16588.751168732357;16659.16704164787;17145.142742862932;2031.989840050805;2075.8962051897497;2080.2719048333115;2115.333669893994;2124.341454149219;79124;102540.97459025389;103584.48207758988;104346;104907;12240.776931958639;12326.50693972249;12344.876551234373;12519.248845069143;12656.936715316364;17576.209070591853;17752.822471775118;17762.200700968227;18118.184681689356;18122.365717199835;9523.190528804991;9624.903750962541;9693.903060969442;9815.852762208573;9908.603655853829;9687.854682179774;9712.271579631555;9728.319017668837;9924.652637157666;9935.503224838729;7781.221877812173;7823.29590336861;8003.239692229229;8024.75306914065;8034.919650803534;2070.9378718638463;2072.4818795301176;2074.7925207479434;2084.5205602711158;2085;1178.2753606531921;1178.4814681540095;1180.1267062373781;1185.9169858110022;1199.5201919232377;21409.250676226256;21778.564428711335;21810;21844.344669659935;21890.01494932732;1185.8814118588073;1188.7681902029194;1189.7085214122596;1229.1088960503564;1237.900967922564;11571.6528504145;12462.831414714534;12509.186902851468;12590.748185036431;12675.239485630891;665726;682600;683236;703214;705273;102292;103415.48292258491;104461;105829;106355;1024.99487502562;1135.7501349703052;1138.2885696439726;1141.5776162819643;1160.773649138427;15060.698786023999;18797.812021879607;19007;20184.19263229513;20204.19183232654;44755;45298.54701452943;46547.53452465432;47108.29337559871;47984.76007620079;61203.69398152981;61670.69164654148;61793.69103154456;62676.68661656845;63234.68382658057;6784.966075169593;9054.456732596065;9183.265338773015;9217.539123043955;9492.7659404277;14169;14434.494792682206;14497.565073047825;14652.267386630412;14658.560243192722;11928.940355298168;12035.398230088285;12441.502339906303;12958.935205323913;12987.675308116995;11546.249493782878;11731.296122232694;11818.113641477095;12026;12667.80998285008;12617.93691031539;12716.364181790688;12908.16096953695;12937.741245175232;12986.026048046246;7841.09827369837;8405.117462666332;8818.338624603051;9194.678186263449;9195.678151264674;11939.880601193878;11991.76016479683;12015.339156346135;12101.394930253628;12135.393230338625 15:08:43 INFO - PROCESS | 18332 | |3;mootools.html;1511.2519302945043;1513.8940274180811;1518.3319339490624;1537.5925379774355;1553.5960650230943;8445.957770211147;8955.14926082022;8959.417637853536;8961.551922403878;8966.55167241637;7394.852102957938;7646;8385.245327920486;8696.086910874346;8766.430182038162;2388.5103553771437;2422.6123820188773;2431.756824317563;2432.3067925641194;2433.0875921529428;2389.9880500597474;2433.8174636902213;2434.2697190842764;2444.4377793107597;2446.840955337906;2402.7477114902945;2409.1688367513266;2425.1875621666763;2431.5623187826272;2440.0971640492953;2303.366574192094;2307.6884620576175;2311.237291693737;2340.2862127051244;2343.437574982009;2346.296111166643;2347.166755801683;2409.1447536124774;2415.613501839697;2418.915337963163;8853.73438796837;8977.730668079968;8980.595873185657;8982.640694372218;8988.32587555936;5353.732313384316;5522.061249587539;5531.363893152299;5535.640183388107;5537.972310138423;3529.2059286660296;3546.85812567497;3579.069441945113;3606.0804494853646;3669.8165091745573;1425.6435891027243;1454.665426951807;1465.7508223602013;1505.2589799563361;1549.8295187529363;12143.757124857453;12148.757024859453;12168.695782605506;12240.571579994657;12305.569305074368;4760.404949381327;4785.928211076836;4798.7840547174765;4853.296272040534;4917.041176970456;10605.098566621924;10608.416537090534;10621.097206737359;10630.043296103388;10631.20265980039;5610.410906854803;5614.9157762633595;5618.52242559387;5619.241402410704;5623.6906970116215;4453.153900758845;4714.787834547454;5053.7220452875445;5066.493350664904;5114.079465696136;4083.639868599621;4156.231097246959;4159.91680166395;4198.475190601175;4225.64082053029;1288.8517820450768;1290.303236252432;1292.5411478925034;1297.5588299977865;1300.5513097981182;995.3977843404781;1004.9547770350351;1014.1126514299988;1015.8019186258608;1015.8476228565775;5652.604317697722;5678.659280443187;5742.593064699176;5769.653820770767;5831.271091113611;638.9584676996074;645.515863102673;652.3215855510216;652.7519542573791;657.1916542652468;3592.928141437209;3634.345817752777;4319.179355922364;4351.8694439166875;4574.245249533789;10716.571337146426;10903.34579925207;10923.890761092447;11007.229493935507;11015.889841101487;10959.232853700323;10959.726006849733;10982.121430285559;10984.176186786022;10987.011168994828;8036.959815200887;8314.41799074059;8328.417010809186;8333.374996875138;8529.360297977553;5664.943350566442;5916.171735956884;6096.542759292982;6258.968705156445;6281.026440901667;9797.755056123653;9917.504124793875;10082.395056296646;10098.747531311776;10101.090901818872;9823.115919566988;9880.555375008285;10009.799804003733;10087.394756314645;10104.292699510963;7216.3505284524645;7482.70069197226;8064.3548516119745;8463.74608761738;8612.913870861461;8443.95778021106;8716;8744.562771861509;8748.300135989233;8750.69372571957;7689.9615501922135;7703.576303303148;7736.071671399468;7758.689652414066;7824.178461261713;7609.467337286337;7619.65711542993;7659.961700191463;7740.14858365568;7863.764087077397;6807;6829;6880.552764070319;6898.586084834926;6905;5197.896042079213;5236.790528378974;5256.080185967364;5287.629865909349;5329.920051199158;5201.687898726089;5279.128943724242;5299.894002120013;5306.6550674206055;5377.193420986883 15:08:43 INFO - PROCESS | 18332 | |4;prototype.html;395.58266029339035;409.4636026804886;413.45368322473945;421.05893328411014;428.7513242319455;5322.760475778589;5348.598855085869;5404;5482.259894914186;5491.395946445893;5097.821576244833;5220.877511335062;5256.526912577867;5329.280547126137;5406.189071639256;4360.694751367406;4399.582039706239;4408.20652282589;4412.183746006999;4417.249067658498;4394.758288294142;4403.405540252057;4407.465707218346;4417.845375411861;4435.822567097328;4115.835366585333;4153.792310384484;4362.8909277268;4369.759663218521;4390.473143222818;4362.364113457454;4379.233634114017;4379.408779814716;4385.364122202294;4400.493943196541;4000.4799376081214;4000.699947503949;4009.97995010026;4013.1572369802375;4033.657139143176;5183.585313174937;5261.76322065508;5261.921071183935;5269.578433725293;5342.893142137135;4961.231009193581;4975.751212439363;4990.263144754588;4995.701117709422;5001.374828146481;5649.350324712669;5661.660300381949;5661.754414028907;5667.801626943037;5669.518090962273;4222.725522841006;4353.151135528573;4362.520122786491;4370;4398.208322501949;5819.476247137778;5822.592418530705;5822.7088645567555;5837.241158649391;5868.295804503487;4453.465584129925;4456.77716114193;4469.843555475542;4472.284434490466;4478.910421791546;4422.181896349186;4467.307567327004;4503.234450143484;4558.49856515783;4561.977190114095;4982.701037937735;4988.80044798204;4998.375203099613;5007.4491805901325;5007.899842003212;5526.143447765603;5665.745041473144;5670.780782131861;5684.8294551163535;5689.175069614883;1428.5785693220525;1451.637090727318;1460.4596299369089;1507.1785876697227;1513.803205583267;1060.9045185933303;1082.94953894722;1087.7008822573698;1094.3269889018356;1096.3367162866512;5337.5463085637375;5370.973145134328;5372.528636827025;5372.70450125239;5389.70937351624;5575.4424557543925;5590.440955904377;5592.888142237132;5594.272744543184;5596.888062238814;5107.045083016532;5378.435264297271;5393.892122157535;5396.406395296514;5443.42844001382;2960.3339248668876;2971.5097008993275;2985.462616728966;3002.12367270456;3012.578239046535;5795.275590551181;5801.883962320814;5817.505512031525;5818.738156782955;5834.854128646818;5541.390447050821;5868.706564671749;5876.529877609781;5893.410658934073;5897.321807992136;6004.849878753066;6010.368911264254;6010.4891084258325;6019.367966363557;6041.728122234422;5540.584456165723;5713.800016999385;5720.2849643794525;5783.334916484658;5789.8263052108505;5682.602217844711;5688.520984544131;5701.372848986525;5704.3725190228215;5729.427057294404;5890.734916928663;6004.639721616717;6017.097435384728;6024.819255422344;6051.304100028553;4240.872773816791;4242.660587152958;4260.829566817292;4263.467066616673;4267.037162905099;5473.972630136824;5756.625819321731;5770.192173095686;5775.7400916957995;5781.190633311256;5406.378266499403;5518.420565840529;5624.6625202487985;5627.380988091225;5634.661920284796;5539.806106786243;5590.496855283044;5590.888182236414;5605.271314729059;5610.943890561042;5384.380796208329;5530.861728456821;5583.776648933997;5613;5616;5574.637648552831;5619.690916999604;5634.661920284796;5671.631343962629;5680.375158732619;5452.569199386662;5492.791585851106;5538.280023596906;5551.417101204477;5565.888682226252 15:08:43 INFO - PROCESS | 18332 | |5;yui.html;817.333872893592;827.7847759582506;835.94566353187;890.3856339126004;936.5857555091811;16084.758728619065;17398.391056313016;17456.21447034885;18011.81988180121;18306;15459.536213913601;15485.845141548582;15653;16112;16331.91834040831;705.7106586299618;723.5911709883925;832.7002279179476;840.6259214649483;842.4818736477084;811.8335741173075;833.7165363776314;837.0541288344162;838.6771093129158;839.1817977471977;883.779055236191;884.5577211394303;885.9911400886006;886.472548833443;891.754767438953;740.7185269597572;759.4190444310108;874.3398733955854;877.9824403511958;878.7539488943106;622.844288927768;633.3887798274641;633.7369991453542;634.2294112653102;636.859890824018;15032.17323047232;15115.093094414371;15122.773158402633;15141.924290378478;15146.318415671321;13583.660408489768;13659.248741319223;13679.863201368058;14428.783568246485;14430.20633865137;13989;14003.159810411407;14163.78754318686;14336.42654293827;14669.853301466956;4758.333833263346;4764.547368000034;4776.976115119437;4961.950380496185;4988.975055124701;84380;85011.14988850094;85112;85186.57406712988;87883;11540.538378464767;11669.474873630706;11695.532178712927;11756.177067605186;12026;21876.89061554682;22135.667964980537;22535.661965070536;22801.31596052121;22823;8922.375433719708;8969.641214351353;9233.630654773733;9286.95356523213;9487.905120948702;5024.723640199751;5025.7989680412375;5077.949220507821;5189.273501709764;5240.0567897778765;3803.1823158021157;3911.9217615647526;3914.1388894443176;3917;3919.0006548329993;649.8245473722163;653.9705713242915;662.5891946992896;665.3845193196274;674.6592970549841;817.4727300891011;827.8427098851199;847.4025811802665;851.5742128935532;865.5845194306768;11108.389038602954;11442.054850675406;11551.480183391768;11753.4710938008;12072.456739446747;830.9750707478785;833.8349942039551;842.9620667069996;858.9098144694842;867.9566021699017;5455.481729235796;5480.068388373986;5484.890302193934;5552.500274975191;5605.831825045255;68527.65736171386;75558.62220688861;84824.15175848285;87377;88439.55780221058;74762.62618686871;76398;76480;78737.60631196923;79315;65667.67166164139;66355.66822165858;66586.6682666344;67431;68489;10136.239782016526;10634.521446534769;10643.94678026605;10744.19418543628;10796.406197659204;44136;44792;45546.772266138454;45584.54415455803;46002.30996535122;49952.75023624858;53057.46942530525;53445;55133;55651;13206.009549284034;13407;13766.0332245773;13850.861491384958;14025.649358766112;15754.212091179386;15920.840791591936;16141.67716645637;16463;16558.172091395623;13153.210807351206;13606.115602485806;13916.860831391556;14104.506342277971;14382.856171438152;13588.524401645895;14027.228502432055;14418.13491190532;14706;14748.63128421798;14174.929125354307;14295.356708947915;14312.212828294543;15068.171250581323;15135.091894486366;12183.939080304895;12735.172213806072;13032.609021729364;13052.086353955132;13268.33658317061;12193.207441516273;12218.755624887275;12266.693332666755;12639.368031598568;12696 15:08:43 INFO - PROCESS | 18332 | __end_tp_report 15:08:43 INFO - PROCESS | 18332 | __start_cc_report 15:08:43 INFO - PROCESS | 18332 | _x_x_mozilla_cycle_collect,868 15:08:43 INFO - PROCESS | 18332 | __end_cc_report 15:08:43 INFO - PROCESS | 18332 | __startTimestamp1486336123272__endTimestamp 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | ------- Summary: start ------- 15:08:43 INFO - PROCESS | 18332 | Number of tests: 6 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | [#0] dojo.html Cycles:165 Average:5481.37 Median:5699.43 stddev:599.12 (10.5%) stddev-sans-first:575.90 15:08:43 INFO - PROCESS | 18332 | Values: 3295.8 3325.5 3329.3 3363.9 3384.4 5440.2 5537.7 5550.2 5560.3 5575.5 5501.6 5514.6 5532.0 5532.8 5566.4 4980.1 4993.3 5025.0 5090.3 5104.5 4912.9 4930.7 5066.7 5096.6 5121.6 4995.0 5001.1 5045.2 5099.4 5119.9 5132.2 5135.6 5142.2 5143.8 5177.0 4807.9 5021.5 5021.7 5096.5 5115.1 5307.4 5351.5 5362.8 5376.9 5461.3 5744.5 5761.9 5767.4 5768.5 5786.0 5839.8 5842.5 5854.1 5865.1 5869.7 5292.9 5375.0 5473.3 5597.4 5612.3 6013.5 6014.7 6016.5 6017.9 6037.4 5765.2 5814.4 5815.5 5832.0 5836.9 5546.2 5592.0 5842.9 5848.3 5907.6 5872.1 5920.4 5927.8 5940.4 5960.8 5925.5 5929.6 5934.5 5936.9 5939.1 4356.7 4372.5 4515.5 4584.1 4588.6 4024.3 4064.3 4099.6 4108.6 4113.3 5691.4 5710.7 5713.3 5714.9 5720.3 5578.1 5676.5 5893.5 5923.9 5943.3 5732.2 5735.7 5747.9 5749.9 5778.9 5062.1 5069.5 5078.5 5079.3 5087.9 5882.5 5942.5 6100.5 6226.9 6227.8 5872.4 5998.7 6014.7 6025.5 6027.6 6188.4 6204.2 6219.6 6227.9 6241.6 5568.4 5832.1 5897.7 5898.4 5923.5 5663.8 5827.7 5876.7 5900.6 5917.7 5767.0 5775.6 5778.3 5783.5 5803.2 5775.6 5775.9 5786.2 5787.0 5788.8 5511.2 5559.2 5569.3 5602.1 5690.2 5699.4 5704.1 5708.3 5733.4 5741.0 5592.8 5593.4 5594.5 5597.0 5603.0 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | [#1] ext.html Cycles:180 Average:64759.09 Median:234655.28 stddev:120022.60 (51.1%) stddev-sans-first:120262.49 15:08:43 INFO - PROCESS | 18332 | Values: 553.9 576.1 605.4 661.7 700.1 37267.3 48067.3 48801.8 48815.8 48817.5 49438.5 49905.0 49924.0 49930.3 50036.7 8921.1 9166.3 9304.7 9501.8 9506.1 11199.6 11513.1 11522.0 11943.5 11955.8 11480.9 11541.7 11569.6 11569.8 11572.6 11535.1 11558.7 11577.2 11600.7 11611.7 11335.4 11382.8 11389.6 11424.4 11431.8 46516.5 46710.8 47008.8 47035.8 47093.8 4491.5 4605.3 4679.3 4729.4 4766.9 35293.0 35410.0 35541.6 35833.0 36633.8 4745.4 4791.8 4845.7 4883.3 4975.1 298961.0 303478.0 391470.0 395440.0 396023.0 75570.6 76474.2 76494.6 76504.0 76580.6 78966.0 80047.6 80100.2 80138.6 80178.6 21032.4 21456.7 21462.8 21485.1 21491.1 14914.9 15353.7 15952.8 16332.8 16598.8 23347.5 24090.9 24142.8 24169.8 24294.5 8472.9 8702.0 8728.6 8729.7 8851.3 1008.6 1018.9 1031.0 1053.1 1071.5 32942.5 34680.7 34698.0 34844.5 34985.4 1110.6 1181.0 1199.3 1205.1 1244.3 17960.9 18067.8 18119.2 18579.9 18718.7 550270.0 556013.0 557042.0 557316.0 560217.0 69904.0 70595.0 70636.6 70808.9 70873.3 1552.9 1564.5 1582.8 1616.0 1634.5 32205.4 33398.5 33649.5 33807.7 33993.7 56296.0 57777.7 57810.1 57863.1 57868.1 423067.0 425468.0 425935.0 425988.0 426007.0 47405.5 47598.8 48860.5 49080.8 49241.3 53007.5 53170.2 53561.2 54115.5 54465.7 44022.1 44996.3 45578.0 45585.0 45947.3 43303.6 44092.8 44684.3 45498.5 45597.5 43876.8 44814.3 44827.3 44929.0 44963.8 35787.0 39094.8 39336.4 39430.6 39512.8 28742.9 28859.9 28974.7 29011.4 29334.3 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | [#2] jquery.html Cycles:180 Average:36705.97 Median:21878.45 stddev:112958.86 (516.3%) stddev-sans-first:113242.83 15:08:43 INFO - PROCESS | 18332 | Values: 396.4 421.5 428.2 433.8 442.6 17793.6 21321.7 21866.9 23100.5 23750.6 23484.2 23637.5 23849.9 23870.9 24045.2 5289.9 6019.0 6207.1 6256.1 6281.3 6001.5 6099.1 6183.9 6193.6 6195.4 5894.5 5961.9 5976.2 5977.3 5987.9 5857.1 5985.0 5996.6 6004.7 6070.8 5485.8 5486.5 5490.2 5493.8 5495.6 26696.3 27221.3 27324.7 27485.6 27620.2 3050.3 3084.7 3087.8 3088.1 3095.8 15821.9 16291.8 16588.8 16659.2 17145.1 2032.0 2075.9 2080.3 2115.3 2124.3 79124.0 102541.0 103584.5 104346.0 104907.0 12240.8 12326.5 12344.9 12519.2 12656.9 17576.2 17752.8 17762.2 18118.2 18122.4 9523.2 9624.9 9693.9 9815.9 9908.6 9687.9 9712.3 9728.3 9924.7 9935.5 7781.2 7823.3 8003.2 8024.8 8034.9 2070.9 2072.5 2074.8 2084.5 2085.0 1178.3 1178.5 1180.1 1185.9 1199.5 21409.3 21778.6 21810.0 21844.3 21890.0 1185.9 1188.8 1189.7 1229.1 1237.9 11571.7 12462.8 12509.2 12590.7 12675.2 665726.0 682600.0 683236.0 703214.0 705273.0 102292.0 103415.5 104461.0 105829.0 106355.0 1025.0 1135.8 1138.3 1141.6 1160.8 15060.7 18797.8 19007.0 20184.2 20204.2 44755.0 45298.5 46547.5 47108.3 47984.8 61203.7 61670.7 61793.7 62676.7 63234.7 6785.0 9054.5 9183.3 9217.5 9492.8 14169.0 14434.5 14497.6 14652.3 14658.6 11928.9 12035.4 12441.5 12958.9 12987.7 11546.2 11731.3 11818.1 12026.0 12667.8 12617.9 12716.4 12908.2 12937.7 12986.0 7841.1 8405.1 8818.3 9194.7 9195.7 11939.9 11991.8 12015.3 12101.4 12135.4 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | [#3] mootools.html Cycles:180 Average:5857.80 Median:4885.17 stddev:3273.38 (67.0%) stddev-sans-first:3266.27 15:08:43 INFO - PROCESS | 18332 | Values: 1511.3 1513.9 1518.3 1537.6 1553.6 8446.0 8955.1 8959.4 8961.6 8966.6 7394.9 7646.0 8385.2 8696.1 8766.4 2388.5 2422.6 2431.8 2432.3 2433.1 2390.0 2433.8 2434.3 2444.4 2446.8 2402.7 2409.2 2425.2 2431.6 2440.1 2303.4 2307.7 2311.2 2340.3 2343.4 2346.3 2347.2 2409.1 2415.6 2418.9 8853.7 8977.7 8980.6 8982.6 8988.3 5353.7 5522.1 5531.4 5535.6 5538.0 3529.2 3546.9 3579.1 3606.1 3669.8 1425.6 1454.7 1465.8 1505.3 1549.8 12143.8 12148.8 12168.7 12240.6 12305.6 4760.4 4785.9 4798.8 4853.3 4917.0 10605.1 10608.4 10621.1 10630.0 10631.2 5610.4 5614.9 5618.5 5619.2 5623.7 4453.2 4714.8 5053.7 5066.5 5114.1 4083.6 4156.2 4159.9 4198.5 4225.6 1288.9 1290.3 1292.5 1297.6 1300.6 995.4 1005.0 1014.1 1015.8 1015.8 5652.6 5678.7 5742.6 5769.7 5831.3 639.0 645.5 652.3 652.8 657.2 3592.9 3634.3 4319.2 4351.9 4574.2 10716.6 10903.3 10923.9 11007.2 11015.9 10959.2 10959.7 10982.1 10984.2 10987.0 8037.0 8314.4 8328.4 8333.4 8529.4 5664.9 5916.2 6096.5 6259.0 6281.0 9797.8 9917.5 10082.4 10098.7 10101.1 9823.1 9880.6 10009.8 10087.4 10104.3 7216.4 7482.7 8064.4 8463.7 8612.9 8444.0 8716.0 8744.6 8748.3 8750.7 7690.0 7703.6 7736.1 7758.7 7824.2 7609.5 7619.7 7660.0 7740.1 7863.8 6807.0 6829.0 6880.6 6898.6 6905.0 5197.9 5236.8 5256.1 5287.6 5329.9 5201.7 5279.1 5299.9 5306.7 5377.2 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | [#4] prototype.html Cycles:180 Average:4805.20 Median:5375.57 stddev:1344.21 (25.0%) stddev-sans-first:1306.60 15:08:43 INFO - PROCESS | 18332 | Values: 395.6 409.5 413.5 421.1 428.8 5322.8 5348.6 5404.0 5482.3 5491.4 5097.8 5220.9 5256.5 5329.3 5406.2 4360.7 4399.6 4408.2 4412.2 4417.2 4394.8 4403.4 4407.5 4417.8 4435.8 4115.8 4153.8 4362.9 4369.8 4390.5 4362.4 4379.2 4379.4 4385.4 4400.5 4000.5 4000.7 4010.0 4013.2 4033.7 5183.6 5261.8 5261.9 5269.6 5342.9 4961.2 4975.8 4990.3 4995.7 5001.4 5649.4 5661.7 5661.8 5667.8 5669.5 4222.7 4353.2 4362.5 4370.0 4398.2 5819.5 5822.6 5822.7 5837.2 5868.3 4453.5 4456.8 4469.8 4472.3 4478.9 4422.2 4467.3 4503.2 4558.5 4562.0 4982.7 4988.8 4998.4 5007.4 5007.9 5526.1 5665.7 5670.8 5684.8 5689.2 1428.6 1451.6 1460.5 1507.2 1513.8 1060.9 1082.9 1087.7 1094.3 1096.3 5337.5 5371.0 5372.5 5372.7 5389.7 5575.4 5590.4 5592.9 5594.3 5596.9 5107.0 5378.4 5393.9 5396.4 5443.4 2960.3 2971.5 2985.5 3002.1 3012.6 5795.3 5801.9 5817.5 5818.7 5834.9 5541.4 5868.7 5876.5 5893.4 5897.3 6004.8 6010.4 6010.5 6019.4 6041.7 5540.6 5713.8 5720.3 5783.3 5789.8 5682.6 5688.5 5701.4 5704.4 5729.4 5890.7 6004.6 6017.1 6024.8 6051.3 4240.9 4242.7 4260.8 4263.5 4267.0 5474.0 5756.6 5770.2 5775.7 5781.2 5406.4 5518.4 5624.7 5627.4 5634.7 5539.8 5590.5 5590.9 5605.3 5610.9 5384.4 5530.9 5583.8 5613.0 5616.0 5574.6 5619.7 5634.7 5671.6 5680.4 5452.6 5492.8 5538.3 5551.4 5565.9 15:08:43 INFO - PROCESS | 18332 | 15:08:43 INFO - PROCESS | 18332 | [#5] yui.html Cycles:180 Average:18766.83 Median:4761.44 stddev:23761.61 (499.0%) stddev-sans-first:23790.04 15:08:43 INFO - PROCESS | 18332 | Values: 817.3 827.8 835.9 890.4 936.6 16084.8 17398.4 17456.2 18011.8 18306.0 15459.5 15485.8 15653.0 16112.0 16331.9 705.7 723.6 832.7 840.6 842.5 811.8 833.7 837.1 838.7 839.2 883.8 884.6 886.0 886.5 891.8 740.7 759.4 874.3 878.0 878.8 622.8 633.4 633.7 634.2 636.9 15032.2 15115.1 15122.8 15141.9 15146.3 13583.7 13659.2 13679.9 14428.8 14430.2 13989.0 14003.2 14163.8 14336.4 14669.9 4758.3 4764.5 4777.0 4962.0 4989.0 84380.0 85011.1 85112.0 85186.6 87883.0 11540.5 11669.5 11695.5 11756.2 12026.0 21876.9 22135.7 22535.7 22801.3 22823.0 8922.4 8969.6 9233.6 9287.0 9487.9 5024.7 5025.8 5077.9 5189.3 5240.1 3803.2 3911.9 3914.1 3917.0 3919.0 649.8 654.0 662.6 665.4 674.7 817.5 827.8 847.4 851.6 865.6 11108.4 11442.1 11551.5 11753.5 12072.5 831.0 833.8 843.0 858.9 868.0 5455.5 5480.1 5484.9 5552.5 5605.8 68527.7 75558.6 84824.2 87377.0 88439.6 74762.6 76398.0 76480.0 78737.6 79315.0 65667.7 66355.7 66586.7 67431.0 68489.0 10136.2 10634.5 10643.9 10744.2 10796.4 44136.0 44792.0 45546.8 45584.5 46002.3 49952.8 53057.5 53445.0 55133.0 55651.0 13206.0 13407.0 13766.0 13850.9 14025.6 15754.2 15920.8 16141.7 16463.0 16558.2 13153.2 13606.1 13916.9 14104.5 14382.9 13588.5 14027.2 14418.1 14706.0 14748.6 14174.9 14295.4 14312.2 15068.2 15135.1 12183.9 12735.2 13032.6 13052.1 13268.3 12193.2 12218.8 12266.7 12639.4 12696.0 15:08:43 INFO - PROCESS | 18332 | -------- Summary: end -------- 15:08:43 INFO - PROCESS | 18332 | 15:08:44 INFO - TEST-INFO | 18332: exit 0 15:08:44 INFO - TEST-OK | dromaeo_css | took 1114915ms 15:08:44 INFO - TEST-START | kraken 15:08:44 INFO - operating with platform_type : linux_ 15:08:44 INFO - Initialising browser for kraken test... 15:08:44 INFO - TEST-INFO | started process 18645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2aEt38/profile http://localhost:54274/getInfo.html) 15:08:48 INFO - PROCESS | 18645 | __metrics Screen width/height:1600/1200 15:08:48 INFO - PROCESS | 18645 | colorDepth:24 15:08:48 INFO - PROCESS | 18645 | Browser inner width/height: 1024/697 15:08:48 INFO - PROCESS | 18645 | __metrics 15:08:49 INFO - PROCESS | 18645 | 1486336129171 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) 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:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884: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 15:08:49 INFO - PROCESS | 18645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:08:49 INFO - PROCESS | 18645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:08:49 INFO - PROCESS | 18645 | console.error: PushService: 15:08:49 INFO - PROCESS | 18645 | stateChangeProcessEnqueue: Error transitioning state 15:08:49 INFO - PROCESS | 18645 | UnknownError 15:08:50 INFO - TEST-INFO | 18645: exit 0 15:08:50 INFO - Browser initialized. 15:08:50 INFO - Running cycle 1/1 for kraken test... 15:08:50 INFO - TEST-INFO | started process 18737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2aEt38/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:09:02 INFO - PROCESS | 18737 | RSS: Main: 138399744 15:09:02 INFO - PROCESS | 18737 | 15:09:04 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-beat-detection) 15:09:05 INFO - PROCESS | 18737 | RSS: Main: 183123968 15:09:05 INFO - PROCESS | 18737 | 15:09:08 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-dft) 15:09:08 INFO - PROCESS | 18737 | RSS: Main: 172830720 15:09:08 INFO - PROCESS | 18737 | 15:09:11 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-fft) 15:09:12 INFO - PROCESS | 18737 | RSS: Main: 263045120 15:09:12 INFO - PROCESS | 18737 | 15:09:14 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-oscillator) 15:09:14 INFO - PROCESS | 18737 | RSS: Main: 169525248 15:09:14 INFO - PROCESS | 18737 | 15:09:16 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:09:17 INFO - PROCESS | 18737 | RSS: Main: 172531712 15:09:17 INFO - PROCESS | 18737 | 15:09:20 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54274/tests/kraken/driver.html?testName=imaging-darkroom) 15:09:21 INFO - PROCESS | 18737 | RSS: Main: 211468288 15:09:21 INFO - PROCESS | 18737 | 15:09:24 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54274/tests/kraken/driver.html?testName=imaging-desaturate) 15:09:25 INFO - PROCESS | 18737 | RSS: Main: 210034688 15:09:25 INFO - PROCESS | 18737 | 15:09:27 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54274/tests/kraken/driver.html?testName=json-parse-financial) 15:09:28 INFO - PROCESS | 18737 | RSS: Main: 208080896 15:09:28 INFO - PROCESS | 18737 | 15:09:29 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:09:30 INFO - PROCESS | 18737 | RSS: Main: 170315776 15:09:30 INFO - PROCESS | 18737 | 15:09:32 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:09:32 INFO - PROCESS | 18737 | RSS: Main: 177594368 15:09:32 INFO - PROCESS | 18737 | 15:09:34 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:09:35 INFO - PROCESS | 18737 | RSS: Main: 186662912 15:09:35 INFO - PROCESS | 18737 | 15:09:37 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:09:38 INFO - PROCESS | 18737 | RSS: Main: 195928064 15:09:38 INFO - PROCESS | 18737 | 15:09:41 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:09:41 INFO - PROCESS | 18737 | RSS: Main: 192073728 15:09:41 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54274/tests/kraken/driver.html?testName=ai-astar) 15:09:43 INFO - PROCESS | 18737 | RSS: Main: 244965376 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | __start_tp_report 15:09:43 INFO - PROCESS | 18737 | _x_x_mozilla_page_load 15:09:43 INFO - PROCESS | 18737 | _x_x_mozilla_page_load_details 15:09:43 INFO - PROCESS | 18737 | |i|pagename|runs| 15:09:43 INFO - PROCESS | 18737 | |0;ai-astar;110;112;111;111;112;114;112;112;110;113 15:09:43 INFO - PROCESS | 18737 | |1;audio-beat-detection;150;116;118;151;121;121;123;118;142;117 15:09:43 INFO - PROCESS | 18737 | |2;audio-dft;161;161;161;160;160;160;161;163;159;160 15:09:43 INFO - PROCESS | 18737 | |3;audio-fft;74;75;75;120;76;77;75;75;101;76 15:09:43 INFO - PROCESS | 18737 | |4;audio-oscillator;82;83;102;84;83;83;96;83;83;110 15:09:43 INFO - PROCESS | 18737 | |5;imaging-gaussian-blur;117;116;118;116;116;117;117;117;117;117 15:09:43 INFO - PROCESS | 18737 | |6;imaging-darkroom;147;147;147;147;147;146;147;148;147;148 15:09:43 INFO - PROCESS | 18737 | |7;imaging-desaturate;96;95;96;96;96;96;95;95;95;95 15:09:43 INFO - PROCESS | 18737 | |8;json-parse-financial;48;50;49;49;51;48;50;50;49;49 15:09:43 INFO - PROCESS | 18737 | |9;json-stringify-tinderbox;63;63;65;63;63;62;68;63;63;63 15:09:43 INFO - PROCESS | 18737 | |10;stanford-crypto-aes;76;76;80;76;75;76;76;81;75;75 15:09:43 INFO - PROCESS | 18737 | |11;stanford-crypto-ccm;131;123;121;122;121;120;119;119;162;123 15:09:43 INFO - PROCESS | 18737 | |12;stanford-crypto-pbkdf2;165;157;158;158;156;160;156;154;154;157 15:09:43 INFO - PROCESS | 18737 | |13;stanford-crypto-sha256-iterative;59;61;61;59;64;61;59;59;59;61 15:09:43 INFO - PROCESS | 18737 | __end_tp_report 15:09:43 INFO - PROCESS | 18737 | __start_cc_report 15:09:43 INFO - PROCESS | 18737 | _x_x_mozilla_cycle_collect,1514 15:09:43 INFO - PROCESS | 18737 | __end_cc_report 15:09:43 INFO - PROCESS | 18737 | __startTimestamp1486336183691__endTimestamp 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | ------- Summary: start ------- 15:09:43 INFO - PROCESS | 18737 | Number of tests: 14 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.25 (1.1%) stddev-sans-first:1.17 15:09:43 INFO - PROCESS | 18737 | Values: 110.0 112.0 111.0 111.0 112.0 114.0 112.0 112.0 110.0 113.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:122.00 stddev:14.13 (11.6%) stddev-sans-first:12.47 15:09:43 INFO - PROCESS | 18737 | Values: 150.0 116.0 118.0 151.0 121.0 121.0 123.0 118.0 142.0 117.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 15:09:43 INFO - PROCESS | 18737 | Values: 161.0 161.0 161.0 160.0 160.0 160.0 161.0 163.0 159.0 160.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:15.49 (20.7%) stddev-sans-first:16.13 15:09:43 INFO - PROCESS | 18737 | Values: 74.0 75.0 75.0 120.0 76.0 77.0 75.0 75.0 101.0 76.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:83.00 stddev:10.07 (12.1%) stddev-sans-first:10.37 15:09:43 INFO - PROCESS | 18737 | Values: 82.0 83.0 102.0 84.0 83.0 83.0 96.0 83.0 83.0 110.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 15:09:43 INFO - PROCESS | 18737 | Values: 117.0 116.0 118.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 15:09:43 INFO - PROCESS | 18737 | Values: 147.0 147.0 147.0 147.0 147.0 146.0 147.0 148.0 147.0 148.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 15:09:43 INFO - PROCESS | 18737 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.50 stddev:0.95 (1.9%) stddev-sans-first:0.88 15:09:43 INFO - PROCESS | 18737 | Values: 48.0 50.0 49.0 49.0 51.0 48.0 50.0 50.0 49.0 49.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:63.00 stddev:1.71 (2.7%) stddev-sans-first:1.80 15:09:43 INFO - PROCESS | 18737 | Values: 63.0 63.0 65.0 63.0 63.0 62.0 68.0 63.0 63.0 63.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#10] stanford-crypto-aes Cycles:10 Average:76.60 Median:76.00 stddev:2.12 (2.8%) stddev-sans-first:2.24 15:09:43 INFO - PROCESS | 18737 | Values: 76.0 76.0 80.0 76.0 75.0 76.0 76.0 81.0 75.0 75.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:122.50 stddev:13.08 (10.7%) stddev-sans-first:13.75 15:09:43 INFO - PROCESS | 18737 | Values: 131.0 123.0 121.0 122.0 121.0 120.0 119.0 119.0 162.0 123.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:157.50 stddev:3.21 (2.0%) stddev-sans-first:1.94 15:09:43 INFO - PROCESS | 18737 | Values: 165.0 157.0 158.0 158.0 156.0 160.0 156.0 154.0 154.0 157.0 15:09:43 INFO - PROCESS | 18737 | 15:09:43 INFO - PROCESS | 18737 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.30 Median:61.00 stddev:1.64 (2.7%) stddev-sans-first:1.67 15:09:43 INFO - PROCESS | 18737 | Values: 59.0 61.0 61.0 59.0 64.0 61.0 59.0 59.0 59.0 61.0 15:09:43 INFO - PROCESS | 18737 | -------- Summary: end -------- 15:09:43 INFO - PROCESS | 18737 | 15:09:44 INFO - TEST-INFO | 18737: exit 0 15:09:44 INFO - TEST-OK | kraken | took 60633ms 15:09:44 INFO - SUITE-END | took 1175s 15:09:44 INFO - Completed test suite (00:19:35) 15:09:44 INFO - javascript benchmark 15:09:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.7692961492694, 3325.484549894767, 3329.250918543328, 3363.89908302751, 3384.4415671414217, 5440.156775699763, 5537.667739935607, 5550.222968784368, 5560.277163968689, 5575.470330318613, 5501.559875209983, 5514.5864060195545, 5531.9723401383035, 5532.806351777688, 5566.359868615111, 4980.078685443195, 4993.251012348159, 5024.995000999815, 5090.312807770941, 5104.515071068243, 4912.877178070559, 4930.654854160211, 5066.670666406671, 5096.617753668471, 5121.590272778169, 4994.950050499485, 5001.099802035632, 5045.167547354682, 5099.4390617032095, 5119.897602047957, 5132.204508301219, 5135.6148288878485, 5142.228665700156, 5143.794248230066, 5176.974115129438, 4807.879803004917, 5021.472745361758, 5021.6735912165595, 5096.490350964911, 5115.104856650071, 5307.416184219733, 5351.491608297206, 5362.839114826562, 5376.865578360533, 5461.344638643388, 5744.454276843693, 5761.942380576183, 5767.36558978512, 5768.480836724673, 5786.0, 5839.824805255849, 5842.503387212092, 5854.092615644582, 5865.090910908816, 5869.706514674249, 5292.867678308035, 5375.005623959541, 5473.343198816127, 5597.384287728347, 5612.326520817528, 6013.488853447506, 6014.699265036818, 6016.488598469179, 6017.909731354033, 6037.426444487766, 5765.221695071195, 5814.418558144236, 5815.476607105381, 5831.9708401458565, 5836.854078648068, 5546.168074788814, 5592.0, 5842.912356314659, 5848.2689663792025, 5907.616004959664, 5872.0898260769645, 5920.407959204132, 5927.762889484458, 5940.435658612425, 5960.791372302036, 5925.496332811673, 5929.6145750526075, 5934.525237981037, 5936.851578710481, 5939.07944268639, 4356.6732495063, 4372.453443319585, 4515.548445155524, 4584.106099310669, 4588.564086411786, 4024.3359845625146, 4064.349704047338, 4099.590040995877, 4108.589141085928, 4113.25961326964, 5691.402402747734, 5710.7430165642645, 5713.342965558931, 5714.885702285931, 5720.342160651495, 5578.107502799533, 5676.489115979582, 5893.528517718572, 5923.911141332884, 5943.286805583271, 5732.197492351074, 5735.684537350402, 5747.8563035924435, 5749.88500229993, 5778.913316300259, 5062.063518249136, 5069.543741063322, 5078.542931136216, 5079.288899554065, 5087.949120508748, 5882.470577648032, 5942.524598032147, 6100.450959413674, 6226.906596401058, 6227.844303892349, 5872.383399743137, 5998.670073146019, 6014.669193194416, 6025.487833534199, 6027.578069535266, 6188.381161883775, 6204.162438070803, 6219.595726277777, 6227.9377206227355, 6241.594296370721, 5568.387477377566, 5832.096025116114, 5897.70511474416, 5898.4101589840675, 5923.526117910643, 5663.830085097454, 5827.70861456917, 5876.706164691663, 5900.586958913007, 5917.733701983335, 5767.048437007847, 5775.566832487664, 5778.27771528559, 5783.479486846204, 5803.158542011469, 5775.624584402, 5775.942240577709, 5786.1899334094105, 5787.045137552257, 5788.82633520995, 5511.200875872896, 5559.16612508127, 5569.275994120739, 5602.1036634138345, 5690.231818704588, 5699.430056994267, 5704.0588302929555, 5708.315002199596, 5733.369329373849, 5741.024025915521, 5592.832215033555, 5593.356763972195, 5594.524465420485, 5597.04850175463, 5603.0], "value": 5443.432663945226, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.9192399049881, 576.1300436341124, 605.3522730678175, 661.6989269882204, 700.1038670501756, 37267.25465490689, 48067.278990815124, 48801.75599122008, 48815.75592122034, 48817.51182488174, 49438.50561494384, 49905.0, 49924.0, 49930.251046234334, 50036.74981625096, 8921.063288354726, 9166.266698664123, 9304.720858374243, 9501.762455938622, 9506.096920792548, 11199.608013719522, 11513.078953683727, 11522.0, 11943.46254418549, 11955.820662690066, 11480.885191148065, 11541.711457213594, 11569.595064172756, 11569.826452603218, 11572.594959176431, 11535.077193824474, 11558.653240402802, 11577.247478913843, 11600.6519804406, 11611.709707257301, 11335.37655428951, 11382.772344553145, 11389.601363952304, 11424.371659558721, 11431.771364572745, 46516.53483465156, 46710.76644616755, 47008.764956175, 47035.76482117602, 47093.76453117713, 4491.483479399845, 4605.30920361945, 4679.298105284201, 4729.385179926622, 4766.904661906742, 35293.0, 35410.0, 35541.644583554094, 35833.0, 36633.816830915675, 4745.359376484165, 4791.784369703337, 4845.70925744453, 4883.316335713003, 4975.129352363322, 298961.0, 303478.0, 391470.0426497878, 395440.0, 396023.0, 75570.62214688891, 76474.23525764783, 76494.61752691312, 76504.0, 76580.61709691415, 78966.0, 80047.59976200081, 80100.19899801044, 80138.59930700426, 80178.59910700526, 21032.422568307436, 21456.678149827763, 21462.785372146078, 21485.140594376364, 21491.140354385963, 14914.850851491563, 15353.692926141415, 15952.76070858938, 16332.75500867488, 16598.75101873473, 23347.53304933892, 24090.87954560251, 24142.7585724144, 24169.79151042441, 24294.51410971806, 8472.949738094281, 8701.95431598948, 8728.607212675444, 8729.694460693972, 8851.291896648252, 1008.6167256442504, 1018.9439580822977, 1030.9587616495255, 1053.068034789217, 1071.4963966935527, 32942.50586241209, 34680.65319346774, 34698.0, 34844.477332840026, 34985.43439481571, 1110.5502271580024, 1180.9586664466703, 1199.2744389644315, 1205.1323047405854, 1244.3031902134833, 17960.9101954492, 18067.819321806615, 18119.18463669138, 18579.907100464683, 18718.71921921172, 550270.0, 556013.0, 557042.0, 557316.0, 560217.0, 69904.0, 70595.0, 70636.64681676558, 70808.87573372807, 70873.2912670877, 1552.922353882324, 1564.5150003498952, 1582.833802450749, 1615.9838401616068, 1634.5014770495113, 32205.355892881544, 33398.49902251517, 33649.495257571645, 33807.66192338045, 33993.66006339905, 56296.0, 57777.71111144417, 57810.13284800816, 57863.13205302008, 57868.13197802121, 423067.0, 425468.0, 425935.0, 425988.0, 426007.0, 47405.52594474011, 47598.76200618975, 48860.511394885594, 49080.75459622679, 49241.2613810786, 53007.469925300255, 53170.202446962554, 53561.19658205052, 54115.45884541104, 54465.72767136139, 44022.11955760931, 44996.32505512355, 45578.0, 45585.0, 45947.31079033884, 43303.566964331214, 44092.77953610211, 44684.32973505335, 45498.54501454943, 45597.54402456066, 43876.780616096716, 44814.3277850839, 44827.32759008552, 44929.0, 44963.775181125195, 35787.0, 39094.804525978325, 39336.40995385129, 39430.60569394384, 39512.802435987636, 28742.856285718437, 28859.861024127127, 28974.710252897203, 29011.419771604873, 29334.26664333409], "value": 23195.320340435122, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.3985498585714, 421.4963119072709, 428.1575382424776, 433.7766050484001, 442.60829166187915, 17793.555161120963, 21321.68017479737, 21866.89066554669, 23100.537989240205, 23750.643740343865, 23484.17805376808, 23637.527249455, 23849.88075059622, 23870.88064559679, 24045.15841945532, 5289.889123284115, 6019.0, 6207.131001659771, 6256.124142620037, 6281.309056003836, 6001.519878409738, 6099.115628233903, 6183.907241391383, 6193.566450348478, 6195.442410183083, 5894.528437724972, 5961.88076238475, 5976.2230909981645, 5977.31260904995, 5987.9101813472835, 5857.092150716646, 5984.982552966007, 5996.610220335686, 6004.7297871595665, 6070.757169713206, 5485.753141108659, 5486.478784515464, 5490.176473528963, 5493.752781124859, 5495.6153069285165, 26696.332591685168, 27221.319467013283, 27324.72675273242, 27485.587716184273, 27620.171394858186, 3050.2984313607994, 3084.722374986262, 3087.783855130142, 3088.1198858325492, 3095.814251144916, 15821.920890395475, 16291.755623665536, 16588.751168732357, 16659.16704164787, 17145.142742862932, 2031.989840050805, 2075.8962051897497, 2080.2719048333115, 2115.333669893994, 2124.341454149219, 79124.0, 102540.97459025389, 103584.48207758988, 104346.0, 104907.0, 12240.776931958639, 12326.50693972249, 12344.876551234373, 12519.248845069143, 12656.936715316364, 17576.209070591853, 17752.822471775118, 17762.200700968227, 18118.184681689356, 18122.365717199835, 9523.190528804991, 9624.903750962541, 9693.903060969442, 9815.852762208573, 9908.603655853829, 9687.854682179774, 9712.271579631555, 9728.319017668837, 9924.652637157666, 9935.503224838729, 7781.221877812173, 7823.29590336861, 8003.239692229229, 8024.75306914065, 8034.919650803534, 2070.9378718638463, 2072.4818795301176, 2074.7925207479434, 2084.5205602711158, 2085.0, 1178.2753606531921, 1178.4814681540095, 1180.1267062373781, 1185.9169858110022, 1199.5201919232377, 21409.250676226256, 21778.564428711335, 21810.0, 21844.344669659935, 21890.01494932732, 1185.8814118588073, 1188.7681902029194, 1189.7085214122596, 1229.1088960503564, 1237.900967922564, 11571.6528504145, 12462.831414714534, 12509.186902851468, 12590.748185036431, 12675.239485630891, 665726.0, 682600.0, 683236.0, 703214.0, 705273.0, 102292.0, 103415.48292258491, 104461.0, 105829.0, 106355.0, 1024.99487502562, 1135.7501349703052, 1138.2885696439726, 1141.5776162819643, 1160.773649138427, 15060.698786023999, 18797.812021879607, 19007.0, 20184.19263229513, 20204.19183232654, 44755.0, 45298.54701452943, 46547.53452465432, 47108.29337559871, 47984.76007620079, 61203.69398152981, 61670.69164654148, 61793.69103154456, 62676.68661656845, 63234.68382658057, 6784.966075169593, 9054.456732596065, 9183.265338773015, 9217.539123043955, 9492.7659404277, 14169.0, 14434.494792682206, 14497.565073047825, 14652.267386630412, 14658.560243192722, 11928.940355298168, 12035.398230088285, 12441.502339906303, 12958.935205323913, 12987.675308116995, 11546.249493782878, 11731.296122232694, 11818.113641477095, 12026.0, 12667.80998285008, 12617.93691031539, 12716.364181790688, 12908.16096953695, 12937.741245175232, 12986.026048046246, 7841.09827369837, 8405.117462666332, 8818.338624603051, 9194.678186263449, 9195.678151264674, 11939.880601193878, 11991.76016479683, 12015.339156346135, 12101.394930253628, 12135.393230338625], "value": 10538.993147225387, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1511.2519302945043, 1513.8940274180811, 1518.3319339490624, 1537.5925379774355, 1553.5960650230943, 8445.957770211147, 8955.14926082022, 8959.417637853536, 8961.551922403878, 8966.55167241637, 7394.852102957938, 7646.0, 8385.245327920486, 8696.086910874346, 8766.430182038162, 2388.5103553771437, 2422.6123820188773, 2431.756824317563, 2432.3067925641194, 2433.0875921529428, 2389.9880500597474, 2433.8174636902213, 2434.2697190842764, 2444.4377793107597, 2446.840955337906, 2402.7477114902945, 2409.1688367513266, 2425.1875621666763, 2431.5623187826272, 2440.0971640492953, 2303.366574192094, 2307.6884620576175, 2311.237291693737, 2340.2862127051244, 2343.437574982009, 2346.296111166643, 2347.166755801683, 2409.1447536124774, 2415.613501839697, 2418.915337963163, 8853.73438796837, 8977.730668079968, 8980.595873185657, 8982.640694372218, 8988.32587555936, 5353.732313384316, 5522.061249587539, 5531.363893152299, 5535.640183388107, 5537.972310138423, 3529.2059286660296, 3546.85812567497, 3579.069441945113, 3606.0804494853646, 3669.8165091745573, 1425.6435891027243, 1454.665426951807, 1465.7508223602013, 1505.2589799563361, 1549.8295187529363, 12143.757124857453, 12148.757024859453, 12168.695782605506, 12240.571579994657, 12305.569305074368, 4760.404949381327, 4785.928211076836, 4798.7840547174765, 4853.296272040534, 4917.041176970456, 10605.098566621924, 10608.416537090534, 10621.097206737359, 10630.043296103388, 10631.20265980039, 5610.410906854803, 5614.9157762633595, 5618.52242559387, 5619.241402410704, 5623.6906970116215, 4453.153900758845, 4714.787834547454, 5053.7220452875445, 5066.493350664904, 5114.079465696136, 4083.639868599621, 4156.231097246959, 4159.91680166395, 4198.475190601175, 4225.64082053029, 1288.8517820450768, 1290.303236252432, 1292.5411478925034, 1297.5588299977865, 1300.5513097981182, 995.3977843404781, 1004.9547770350351, 1014.1126514299988, 1015.8019186258608, 1015.8476228565775, 5652.604317697722, 5678.659280443187, 5742.593064699176, 5769.653820770767, 5831.271091113611, 638.9584676996074, 645.515863102673, 652.3215855510216, 652.7519542573791, 657.1916542652468, 3592.928141437209, 3634.345817752777, 4319.179355922364, 4351.8694439166875, 4574.245249533789, 10716.571337146426, 10903.34579925207, 10923.890761092447, 11007.229493935507, 11015.889841101487, 10959.232853700323, 10959.726006849733, 10982.121430285559, 10984.176186786022, 10987.011168994828, 8036.959815200887, 8314.41799074059, 8328.417010809186, 8333.374996875138, 8529.360297977553, 5664.943350566442, 5916.171735956884, 6096.542759292982, 6258.968705156445, 6281.026440901667, 9797.755056123653, 9917.504124793875, 10082.395056296646, 10098.747531311776, 10101.090901818872, 9823.115919566988, 9880.555375008285, 10009.799804003733, 10087.394756314645, 10104.292699510963, 7216.3505284524645, 7482.70069197226, 8064.3548516119745, 8463.74608761738, 8612.913870861461, 8443.95778021106, 8716.0, 8744.562771861509, 8748.300135989233, 8750.69372571957, 7689.9615501922135, 7703.576303303148, 7736.071671399468, 7758.689652414066, 7824.178461261713, 7609.467337286337, 7619.65711542993, 7659.961700191463, 7740.14858365568, 7863.764087077397, 6807.0, 6829.0, 6880.552764070319, 6898.586084834926, 6905.0, 5197.896042079213, 5236.790528378974, 5256.080185967364, 5287.629865909349, 5329.920051199158, 5201.687898726089, 5279.128943724242, 5299.894002120013, 5306.6550674206055, 5377.193420986883], "value": 4697.480335726324, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.58266029339035, 409.4636026804886, 413.45368322473945, 421.05893328411014, 428.7513242319455, 5322.760475778589, 5348.598855085869, 5404.0, 5482.259894914186, 5491.395946445893, 5097.821576244833, 5220.877511335062, 5256.526912577867, 5329.280547126137, 5406.189071639256, 4360.694751367406, 4399.582039706239, 4408.20652282589, 4412.183746006999, 4417.249067658498, 4394.758288294142, 4403.405540252057, 4407.465707218346, 4417.845375411861, 4435.822567097328, 4115.835366585333, 4153.792310384484, 4362.8909277268, 4369.759663218521, 4390.473143222818, 4362.364113457454, 4379.233634114017, 4379.408779814716, 4385.364122202294, 4400.493943196541, 4000.4799376081214, 4000.699947503949, 4009.97995010026, 4013.1572369802375, 4033.657139143176, 5183.585313174937, 5261.76322065508, 5261.921071183935, 5269.578433725293, 5342.893142137135, 4961.231009193581, 4975.751212439363, 4990.263144754588, 4995.701117709422, 5001.374828146481, 5649.350324712669, 5661.660300381949, 5661.754414028907, 5667.801626943037, 5669.518090962273, 4222.725522841006, 4353.151135528573, 4362.520122786491, 4370.0, 4398.208322501949, 5819.476247137778, 5822.592418530705, 5822.7088645567555, 5837.241158649391, 5868.295804503487, 4453.465584129925, 4456.77716114193, 4469.843555475542, 4472.284434490466, 4478.910421791546, 4422.181896349186, 4467.307567327004, 4503.234450143484, 4558.49856515783, 4561.977190114095, 4982.701037937735, 4988.80044798204, 4998.375203099613, 5007.4491805901325, 5007.899842003212, 5526.143447765603, 5665.745041473144, 5670.780782131861, 5684.8294551163535, 5689.175069614883, 1428.5785693220525, 1451.637090727318, 1460.4596299369089, 1507.1785876697227, 1513.803205583267, 1060.9045185933303, 1082.94953894722, 1087.7008822573698, 1094.3269889018356, 1096.3367162866512, 5337.5463085637375, 5370.973145134328, 5372.528636827025, 5372.70450125239, 5389.70937351624, 5575.4424557543925, 5590.440955904377, 5592.888142237132, 5594.272744543184, 5596.888062238814, 5107.045083016532, 5378.435264297271, 5393.892122157535, 5396.406395296514, 5443.42844001382, 2960.3339248668876, 2971.5097008993275, 2985.462616728966, 3002.12367270456, 3012.578239046535, 5795.275590551181, 5801.883962320814, 5817.505512031525, 5818.738156782955, 5834.854128646818, 5541.390447050821, 5868.706564671749, 5876.529877609781, 5893.410658934073, 5897.321807992136, 6004.849878753066, 6010.368911264254, 6010.4891084258325, 6019.367966363557, 6041.728122234422, 5540.584456165723, 5713.800016999385, 5720.2849643794525, 5783.334916484658, 5789.8263052108505, 5682.602217844711, 5688.520984544131, 5701.372848986525, 5704.3725190228215, 5729.427057294404, 5890.734916928663, 6004.639721616717, 6017.097435384728, 6024.819255422344, 6051.304100028553, 4240.872773816791, 4242.660587152958, 4260.829566817292, 4263.467066616673, 4267.037162905099, 5473.972630136824, 5756.625819321731, 5770.192173095686, 5775.7400916957995, 5781.190633311256, 5406.378266499403, 5518.420565840529, 5624.6625202487985, 5627.380988091225, 5634.661920284796, 5539.806106786243, 5590.496855283044, 5590.888182236414, 5605.271314729059, 5610.943890561042, 5384.380796208329, 5530.861728456821, 5583.776648933997, 5613.0, 5616.0, 5574.637648552831, 5619.690916999604, 5634.661920284796, 5671.631343962629, 5680.375158732619, 5452.569199386662, 5492.791585851106, 5538.280023596906, 5551.417101204477, 5565.888682226252], "value": 4399.715130527877, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.333872893592, 827.7847759582506, 835.94566353187, 890.3856339126004, 936.5857555091811, 16084.758728619065, 17398.391056313016, 17456.21447034885, 18011.81988180121, 18306.0, 15459.536213913601, 15485.845141548582, 15653.0, 16112.0, 16331.91834040831, 705.7106586299618, 723.5911709883925, 832.7002279179476, 840.6259214649483, 842.4818736477084, 811.8335741173075, 833.7165363776314, 837.0541288344162, 838.6771093129158, 839.1817977471977, 883.779055236191, 884.5577211394303, 885.9911400886006, 886.472548833443, 891.754767438953, 740.7185269597572, 759.4190444310108, 874.3398733955854, 877.9824403511958, 878.7539488943106, 622.844288927768, 633.3887798274641, 633.7369991453542, 634.2294112653102, 636.859890824018, 15032.17323047232, 15115.093094414371, 15122.773158402633, 15141.924290378478, 15146.318415671321, 13583.660408489768, 13659.248741319223, 13679.863201368058, 14428.783568246485, 14430.20633865137, 13989.0, 14003.159810411407, 14163.78754318686, 14336.42654293827, 14669.853301466956, 4758.333833263346, 4764.547368000034, 4776.976115119437, 4961.950380496185, 4988.975055124701, 84380.0, 85011.14988850094, 85112.0, 85186.57406712988, 87883.0, 11540.538378464767, 11669.474873630706, 11695.532178712927, 11756.177067605186, 12026.0, 21876.89061554682, 22135.667964980537, 22535.661965070536, 22801.31596052121, 22823.0, 8922.375433719708, 8969.641214351353, 9233.630654773733, 9286.95356523213, 9487.905120948702, 5024.723640199751, 5025.7989680412375, 5077.949220507821, 5189.273501709764, 5240.0567897778765, 3803.1823158021157, 3911.9217615647526, 3914.1388894443176, 3917.0, 3919.0006548329993, 649.8245473722163, 653.9705713242915, 662.5891946992896, 665.3845193196274, 674.6592970549841, 817.4727300891011, 827.8427098851199, 847.4025811802665, 851.5742128935532, 865.5845194306768, 11108.389038602954, 11442.054850675406, 11551.480183391768, 11753.4710938008, 12072.456739446747, 830.9750707478785, 833.8349942039551, 842.9620667069996, 858.9098144694842, 867.9566021699017, 5455.481729235796, 5480.068388373986, 5484.890302193934, 5552.500274975191, 5605.831825045255, 68527.65736171386, 75558.62220688861, 84824.15175848285, 87377.0, 88439.55780221058, 74762.62618686871, 76398.0, 76480.0, 78737.60631196923, 79315.0, 65667.67166164139, 66355.66822165858, 66586.6682666344, 67431.0, 68489.0, 10136.239782016526, 10634.521446534769, 10643.94678026605, 10744.19418543628, 10796.406197659204, 44136.0, 44792.0, 45546.772266138454, 45584.54415455803, 46002.30996535122, 49952.75023624858, 53057.46942530525, 53445.0, 55133.0, 55651.0, 13206.009549284034, 13407.0, 13766.0332245773, 13850.861491384958, 14025.649358766112, 15754.212091179386, 15920.840791591936, 16141.67716645637, 16463.0, 16558.172091395623, 13153.210807351206, 13606.115602485806, 13916.860831391556, 14104.506342277971, 14382.856171438152, 13588.524401645895, 14027.228502432055, 14418.13491190532, 14706.0, 14748.63128421798, 14174.929125354307, 14295.356708947915, 14312.212828294543, 15068.171250581323, 15135.091894486366, 12183.939080304895, 12735.172213806072, 13032.609021729364, 13052.086353955132, 13268.33658317061, 12193.207441516273, 12218.755624887275, 12266.693332666755, 12639.368031598568, 12696.0], "value": 7819.848500003124, "unit": "score"}], "name": "dromaeo_css", "value": 7740.48779408945, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 111.0, 111.0, 112.0, 114.0, 112.0, 112.0, 110.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 118.0, 151.0, 121.0, 121.0, 123.0, 118.0, 142.0, 117.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 163.0, 159.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 120.0, 76.0, 77.0, 75.0, 75.0, 101.0, 76.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 102.0, 84.0, 83.0, 83.0, 96.0, 83.0, 83.0, 110.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 118.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 147.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 50.0, 49.0, 49.0, 51.0, 48.0, 50.0, 50.0, 49.0, 49.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 65.0, 63.0, 63.0, 62.0, 68.0, 63.0, 63.0, 63.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 80.0, 76.0, 75.0, 76.0, 76.0, 81.0, 75.0, 75.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 123.0, 121.0, 122.0, 121.0, 120.0, 119.0, 119.0, 162.0, 123.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 157.0, 158.0, 158.0, 156.0, 160.0, 156.0, 154.0, 154.0, 157.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 59.0, 64.0, 61.0, 59.0, 59.0, 59.0, 61.0], "value": 60.3, "unit": "score"}], "name": "kraken", "value": 1464.0999999999997, "alertThreshold": 2.0}]} 15:09:44 INFO - Return code: 0 15:09:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:09:44 ERROR - # TBPL SUCCESS # 15:09:44 INFO - Running post-action listener: _resource_record_post_action 15:09:44 INFO - [mozharness: 2017-02-05 23:09:44.975033Z] Finished run-tests step (success) 15:09:44 INFO - Running post-run listener: _resource_record_post_run 15:09:45 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 262144; Write bytes: 360079360; Read time: 32; Write time: 689084 15:09:45 INFO - TinderboxPrint: CPU usage
13.0% 15:09:45 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 32 15:09:45 INFO - TinderboxPrint: I/O write bytes / time
360,079,360 / 689,084 15:09:45 INFO - TinderboxPrint: CPU idle
8,271.7 (86.9%) 15:09:45 INFO - TinderboxPrint: CPU user
1,190.2 (12.5%) 15:09:45 INFO - TinderboxPrint: Swap in / out
0 / 0 15:09:45 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 110247936; Read time: 0; Write time: 339244 15:09:45 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249831424; Read time: 32; Write time: 349840 15:09:45 INFO - Running post-run listener: _upload_blobber_files 15:09:45 INFO - Blob upload gear active. 15:09:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:09:45 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 15:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:09:46 INFO - (blobuploader) - INFO - Open directory for files ... 15:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:46 INFO - (blobuploader) - INFO - Done attempting. 15:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:09:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:49 INFO - (blobuploader) - INFO - Done attempting. 15:09:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:09:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:09:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:50 INFO - (blobuploader) - INFO - Done attempting. 15:09:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:09:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:09:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:51 INFO - (blobuploader) - INFO - Done attempting. 15:09:51 INFO - (blobuploader) - INFO - Iteration through files over. 15:09:51 INFO - Return code: 0 15:09:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:09:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:09:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd526b01187cf829661884270519fa4092cd175faa7ae6a10b6cc32c2225a64584c6a27205509edd251f538ea724971dc5f99803bb856a8ef6772e8ed50668d8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c2ce977bd927b3da258eec24534f47b282bf7d2a64ed8d729629ab0e81afc447081ba8eccf6202e59e053540e3781ea8185f0bbc43a50c01d805f3b20b63cfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2e51ed5b3bfa143183aa09181163d956db17480d47d4279b4cef998281a507c2e18b0b444184341364e53361bbcccfcb49da23016e26810bcec6f95e8e03e427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/85d2f5392a604b12fc9e4e294e7c89c1cac76f0575c076a41065e397d0add0c2a5a8ddc7ad967baa8a422e96c7270c3c82ec46619677a793e23a2f4b8fe10dca"} 15:09:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:09:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:09:51 INFO - Contents: 15:09:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd526b01187cf829661884270519fa4092cd175faa7ae6a10b6cc32c2225a64584c6a27205509edd251f538ea724971dc5f99803bb856a8ef6772e8ed50668d8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c2ce977bd927b3da258eec24534f47b282bf7d2a64ed8d729629ab0e81afc447081ba8eccf6202e59e053540e3781ea8185f0bbc43a50c01d805f3b20b63cfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2e51ed5b3bfa143183aa09181163d956db17480d47d4279b4cef998281a507c2e18b0b444184341364e53361bbcccfcb49da23016e26810bcec6f95e8e03e427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/85d2f5392a604b12fc9e4e294e7c89c1cac76f0575c076a41065e397d0add0c2a5a8ddc7ad967baa8a422e96c7270c3c82ec46619677a793e23a2f4b8fe10dca"} 15:09:51 INFO - Running post-run listener: copy_logs_to_upload_dir 15:09:51 INFO - Copying logs to upload dir... 15:09:51 INFO - mkdir: /builds/slave/test/build/upload/logs 15:09:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.577218 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-02-05 15:09:51.197522) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 15:09:51.201101) ========= 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=8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd526b01187cf829661884270519fa4092cd175faa7ae6a10b6cc32c2225a64584c6a27205509edd251f538ea724971dc5f99803bb856a8ef6772e8ed50668d8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c2ce977bd927b3da258eec24534f47b282bf7d2a64ed8d729629ab0e81afc447081ba8eccf6202e59e053540e3781ea8185f0bbc43a50c01d805f3b20b63cfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2e51ed5b3bfa143183aa09181163d956db17480d47d4279b4cef998281a507c2e18b0b444184341364e53361bbcccfcb49da23016e26810bcec6f95e8e03e427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/85d2f5392a604b12fc9e4e294e7c89c1cac76f0575c076a41065e397d0add0c2a5a8ddc7ad967baa8a422e96c7270c3c82ec46619677a793e23a2f4b8fe10dca"} build_url:https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011438 build_url: 'https://queue.taskcluster.net/v1/task/Qmoiejh4TiCkMiH44HFANA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bd526b01187cf829661884270519fa4092cd175faa7ae6a10b6cc32c2225a64584c6a27205509edd251f538ea724971dc5f99803bb856a8ef6772e8ed50668d8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8c2ce977bd927b3da258eec24534f47b282bf7d2a64ed8d729629ab0e81afc447081ba8eccf6202e59e053540e3781ea8185f0bbc43a50c01d805f3b20b63cfa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2e51ed5b3bfa143183aa09181163d956db17480d47d4279b4cef998281a507c2e18b0b444184341364e53361bbcccfcb49da23016e26810bcec6f95e8e03e427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/85d2f5392a604b12fc9e4e294e7c89c1cac76f0575c076a41065e397d0add0c2a5a8ddc7ad967baa8a422e96c7270c3c82ec46619677a793e23a2f4b8fe10dca"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 15:09:51.226023) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 15:09:51.226333) ========= 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=8783db0840cc980e80bb0bd400000236-1486334839.593314-97407845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 15:09:51.311520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 15:09:51.311851) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 15:09:51.312179) ========= ========= Total master_lag: 0.18 =========