builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1492186316.62 results: success (0) revision: 567d5c33be379ef8d206e3b64afec4a6a92920d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.624112) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.624674) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.624997) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.649603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.649998) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-14 09:11:56-- 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% 42.9M=0s 2017-04-14 09:11:56 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178548 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.841694) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.842018) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.038724 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:56.892002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-14 09:11:56.892351) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 567d5c33be379ef8d206e3b64afec4a6a92920d6 --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 567d5c33be379ef8d206e3b64afec4a6a92920d6 --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=9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-14 09:11:56,949 truncating revision to first 12 chars 2017-04-14 09:11:56,949 Setting DEBUG logging. 2017-04-14 09:11:56,949 attempt 1/10 2017-04-14 09:11:56,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/567d5c33be37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-14 09:11:58,236 unpacking tar archive at: mozilla-aurora-567d5c33be37/testing/mozharness/ program finished with exit code 0 elapsedTime=1.638981 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-14 09:11:58.544920) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:58.545296) ========= script_repo_revision: 567d5c33be379ef8d206e3b64afec4a6a92920d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:58.545691) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:58.545972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 09:11:58.557215) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-04-14 09:11:58.557509) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:11:58 INFO - MultiFileLogger online at 20170414 09:11:58 in /builds/slave/test 09:11:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:11:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:58 INFO - {'append_to_log': False, 09:11:58 INFO - 'base_work_dir': '/builds/slave/test', 09:11:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:11:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:58 INFO - 'branch': 'Mozilla-Aurora', 09:11:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:58 INFO - 'config_files': ('talos/linux_config.py',), 09:11:58 INFO - 'default_actions': ('clobber', 09:11:58 INFO - 'read-buildbot-config', 09:11:58 INFO - 'download-and-extract', 09:11:58 INFO - 'populate-webroot', 09:11:58 INFO - 'create-virtualenv', 09:11:58 INFO - 'install', 09:11:58 INFO - 'run-tests'), 09:11:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:58 INFO - 'download_minidump_stackwalk': True, 09:11:58 INFO - 'download_symbols': 'ondemand', 09:11:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:58 INFO - 'gecko_profile': False, 09:11:58 INFO - 'gecko_profile_interval': 0, 09:11:58 INFO - 'installer_path': 'installer.exe', 09:11:58 INFO - 'log_level': 'info', 09:11:58 INFO - 'log_name': 'talos', 09:11:58 INFO - 'log_to_console': True, 09:11:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:11:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:11:58 INFO - 'opt_config_files': (), 09:11:58 INFO - 'pip_index': False, 09:11:58 INFO - 'suite': 'dromaeojs', 09:11:58 INFO - 'system_bits': '32', 09:11:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:58 INFO - 'title': 'talos-linux64-ix-013', 09:11:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:58 INFO - 'use_talos_json': True, 09:11:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:58 INFO - 'work_dir': 'build'} 09:11:58 INFO - [mozharness: 2017-04-14 16:11:58.846395Z] Running clobber step. 09:11:58 INFO - Running pre-action listener: _resource_record_pre_action 09:11:58 INFO - Running main action method: clobber 09:11:58 INFO - rmtree: /builds/slave/test/build 09:11:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:12:04 INFO - Running post-action listener: _resource_record_post_action 09:12:04 INFO - [mozharness: 2017-04-14 16:12:04.550971Z] Finished clobber step (success) 09:12:04 INFO - [mozharness: 2017-04-14 16:12:04.551069Z] Running read-buildbot-config step. 09:12:04 INFO - Running pre-action listener: _resource_record_pre_action 09:12:04 INFO - Running main action method: read_buildbot_config 09:12:04 INFO - Using buildbot properties: 09:12:04 INFO - { 09:12:04 INFO - "project": "", 09:12:04 INFO - "product": "firefox", 09:12:04 INFO - "who": "ffxbld@noreply.mozilla.org", 09:12:04 INFO - "installer_path": "public/build/target.tar.bz2", 09:12:04 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:12:04 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 09:12:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:12:04 INFO - "stage_platform": "linux64-pgo", 09:12:04 INFO - "basedir": "/builds/slave/test", 09:12:04 INFO - "buildnumber": 836, 09:12:04 INFO - "platform": "ubuntu64_hw", 09:12:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:12:04 INFO - "slavebuilddir": "test", 09:12:04 INFO - "taskId": "chziLOpBTcGoC-wrNHHqPA", 09:12:04 INFO - "branch": "mozilla-aurora", 09:12:04 INFO - "script_repo_revision": "production", 09:12:04 INFO - "revision": "567d5c33be379ef8d206e3b64afec4a6a92920d6", 09:12:04 INFO - "slavename": "talos-linux64-ix-013", 09:12:04 INFO - "repo_path": "releases/mozilla-aurora" 09:12:04 INFO - } 09:12:04 INFO - Finding installer, test and symbols from parent task. 09:12:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chziLOpBTcGoC-wrNHHqPA'}, attempt #1 09:12:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chziLOpBTcGoC-wrNHHqPA'}, attempt #1 09:12:05 INFO - Task dependencies: VHWJsPokQVuGOv2cPQrzyg 09:12:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg'}, attempt #1 09:12:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2 09:12:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/test_packages.json 09:12:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.crashreporter-symbols.zip 09:12:05 INFO - Running post-action listener: _resource_record_post_action 09:12:05 INFO - [mozharness: 2017-04-14 16:12:05.938672Z] Finished read-buildbot-config step (success) 09:12:05 INFO - [mozharness: 2017-04-14 16:12:05.938892Z] Running download-and-extract step. 09:12:05 INFO - Running pre-action listener: _resource_record_pre_action 09:12:05 INFO - Running main action method: download_and_extract 09:12:05 INFO - mkdir: /builds/slave/test/build/tests 09:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:05 INFO - trying https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.test_packages.json 09:12:05 INFO - Downloading https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:12:06 INFO - Downloaded 896 bytes. 09:12:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:12:06 INFO - Using the following test package requirements: 09:12:06 INFO - {u'common': [u'target.common.tests.zip'], 09:12:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:12:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:12:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:12:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:12:06 INFO - u'mozbase': [u'target.common.tests.zip'], 09:12:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:12:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:12:06 INFO - u'web-platform': [u'target.common.tests.zip', 09:12:06 INFO - u'target.web-platform.tests.zip'], 09:12:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:12:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:12:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.common.tests.zip 09:12:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:12:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.common.tests.zip into memory 09:12:07 INFO - Expected file size: 59544311 09:12:07 INFO - Obtained file size: 59544311 09:12:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:12:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.common.tests.zip 09:12:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:12:17 INFO - Fetch https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.common.tests.zip into memory 09:12:18 INFO - Expected file size: 59544311 09:12:18 INFO - Obtained file size: 59544311 09:12:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.talos.tests.zip 09:12:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:12:29 INFO - Fetch https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.talos.tests.zip into memory 09:12:30 INFO - Expected file size: 14404424 09:12:30 INFO - Obtained file size: 14404424 09:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:30 INFO - trying https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2 09:12:30 INFO - Downloading https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:12:32 INFO - Downloaded 66769174 bytes. 09:12:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2 09:12:32 INFO - mkdir: /builds/slave/test/properties 09:12:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:32 INFO - Contents: 09:12:32 INFO - build_url:https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2 09:12:32 INFO - Running post-action listener: _resource_record_post_action 09:12:32 INFO - Running post-action listener: set_extra_try_arguments 09:12:32 INFO - [mozharness: 2017-04-14 16:12:32.306228Z] Finished download-and-extract step (success) 09:12:32 INFO - [mozharness: 2017-04-14 16:12:32.306325Z] Running populate-webroot step. 09:12:32 INFO - Running pre-action listener: _resource_record_pre_action 09:12:32 INFO - Running main action method: populate_webroot 09:12:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:32 INFO - '--tppagecycles', 09:12:32 INFO - '7'], 09:12:32 INFO - 'tests': ['tsvgm']}}, 09:12:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:12:32 INFO - 'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:12:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:12:32 INFO - 'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['damp', 'tps']}, 09:12:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:12:32 INFO - 'tests': ['damp', 'tps']}, 09:12:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['dromaeo_dom']}, 09:12:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['basic_compositor_video']}, 09:12:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:12:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['a11yr', 09:12:32 INFO - 'ts_paint', 09:12:32 INFO - 'tpaint', 09:12:32 INFO - 'sessionrestore', 09:12:32 INFO - 'sessionrestore_no_auto_restore', 09:12:32 INFO - 'tabpaint']}, 09:12:32 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:32 INFO - 'ts_paint', 09:12:32 INFO - 'tpaint', 09:12:32 INFO - 'sessionrestore', 09:12:32 INFO - 'sessionrestore_no_auto_restore', 09:12:32 INFO - 'tabpaint']}, 09:12:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:32 INFO - 'ts_paint', 09:12:32 INFO - 'tpaint', 09:12:32 INFO - 'sessionrestore', 09:12:32 INFO - 'sessionrestore_no_auto_restore', 09:12:32 INFO - 'tabpaint']}, 09:12:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:32 INFO - 'ts_paint', 09:12:32 INFO - 'tpaint', 09:12:32 INFO - 'sessionrestore', 09:12:32 INFO - 'sessionrestore_no_auto_restore', 09:12:32 INFO - 'tabpaint']}, 09:12:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['a11yr', 09:12:32 INFO - 'ts_paint', 09:12:32 INFO - 'tpaint', 09:12:32 INFO - 'sessionrestore', 09:12:32 INFO - 'sessionrestore_no_auto_restore', 09:12:32 INFO - 'tabpaint']}, 09:12:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['a11yr', 09:12:32 INFO - 'ts_paint', 09:12:32 INFO - 'tpaint', 09:12:32 INFO - 'sessionrestore', 09:12:32 INFO - 'sessionrestore_no_auto_restore', 09:12:32 INFO - 'tabpaint']}, 09:12:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['tsvgx', 09:12:32 INFO - 'tsvgr_opacity', 09:12:32 INFO - 'tart', 09:12:32 INFO - 'tscrollx', 09:12:32 INFO - 'cart', 09:12:32 INFO - 'tsvg_static']}, 09:12:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:32 INFO - 'tsvgr_opacity', 09:12:32 INFO - 'tart', 09:12:32 INFO - 'tscrollx', 09:12:32 INFO - 'cart', 09:12:32 INFO - 'tsvg_static']}, 09:12:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:12:32 INFO - 'talos_options': ['--disable-e10s'], 09:12:32 INFO - 'tests': ['tp5o']}, 09:12:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:12:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:12:32 INFO - 'talos_options': ['--disable-e10s', 09:12:32 INFO - '--xperf_path', 09:12:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:32 INFO - 'tests': ['tp5n']}, 09:12:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:12:32 INFO - 'talos_options': ['--xperf_path', 09:12:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:32 INFO - 'tests': ['tp5n']}}, 09:12:32 INFO - 'talos.zip': {'path': '', 09:12:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:32 INFO - Running post-action listener: _resource_record_post_action 09:12:32 INFO - [mozharness: 2017-04-14 16:12:32.320963Z] Finished populate-webroot step (success) 09:12:32 INFO - [mozharness: 2017-04-14 16:12:32.321080Z] Running create-virtualenv step. 09:12:32 INFO - Running pre-action listener: _resource_record_pre_action 09:12:32 INFO - Running main action method: create_virtualenv 09:12:32 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:32 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 09:12:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:32 INFO - Using real prefix '/usr' 09:12:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:35 INFO - Installing pip.................done. 09:12:35 INFO - Return code: 0 09:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:12:35 INFO - Reading from file tmpfile_stdout 09:12:35 INFO - Output received: 09:12:35 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) 09:12:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12: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', 'pip>=1.5'] in /builds/slave/test/build 09:12: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 pip>=1.5 09:12:35 INFO - Using env: {'DISPLAY': ':0', 09:12:35 INFO - 'HOME': '/home/cltbld', 09:12:35 INFO - 'LANG': 'en_US.UTF-8', 09:12:35 INFO - 'LANGUAGE': 'en_US:en', 09:12:35 INFO - 'LOGNAME': 'cltbld', 09:12:35 INFO - 'MAIL': '/var/mail/cltbld', 09:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:35 INFO - 'MOZ_NO_REMOTE': '1', 09:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:35 INFO - 'NO_EM_RESTART': '1', 09:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:35 INFO - 'PWD': '/builds/slave/test', 09:12:35 INFO - 'SHELL': '/bin/bash', 09:12:35 INFO - 'SHLVL': '1', 09:12:35 INFO - 'TERM': 'linux', 09:12:35 INFO - 'TMOUT': '86400', 09:12:35 INFO - 'USER': 'cltbld', 09:12:35 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:35 INFO - '_': '/tools/buildbot/bin/python'} 09:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:35 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 09:12:35 INFO - Cleaning up... 09:12:35 INFO - Return code: 0 09:12:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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', '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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:12: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 psutil>=3.1.1 09:12:35 INFO - Using env: {'DISPLAY': ':0', 09:12:35 INFO - 'HOME': '/home/cltbld', 09:12:35 INFO - 'LANG': 'en_US.UTF-8', 09:12:35 INFO - 'LANGUAGE': 'en_US:en', 09:12:35 INFO - 'LOGNAME': 'cltbld', 09:12:35 INFO - 'MAIL': '/var/mail/cltbld', 09:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:35 INFO - 'MOZ_NO_REMOTE': '1', 09:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:35 INFO - 'NO_EM_RESTART': '1', 09:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:35 INFO - 'PWD': '/builds/slave/test', 09:12:35 INFO - 'SHELL': '/bin/bash', 09:12:35 INFO - 'SHLVL': '1', 09:12:35 INFO - 'TERM': 'linux', 09:12:35 INFO - 'TMOUT': '86400', 09:12:35 INFO - 'USER': 'cltbld', 09:12:35 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:35 INFO - '_': '/tools/buildbot/bin/python'} 09:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:36 INFO - Downloading/unpacking psutil>=3.1.1 09:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:38 INFO - Installing collected packages: psutil 09:12:38 INFO - Running setup.py install for psutil 09:12:38 INFO - building 'psutil._psutil_linux' extension 09:12:38 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 09:12:39 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 09:12:39 INFO - building 'psutil._psutil_posix' extension 09:12:39 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 09:12:39 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 09:12:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:39 INFO - Successfully installed psutil 09:12:39 INFO - Cleaning up... 09:12:39 INFO - Return code: 0 09:12:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:12:39 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')]} 09:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:39 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')]} 09:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:39 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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:39 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 09:12:39 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 09:12:39 INFO - Using env: {'DISPLAY': ':0', 09:12:39 INFO - 'HOME': '/home/cltbld', 09:12:39 INFO - 'LANG': 'en_US.UTF-8', 09:12:39 INFO - 'LANGUAGE': 'en_US:en', 09:12:39 INFO - 'LOGNAME': 'cltbld', 09:12:39 INFO - 'MAIL': '/var/mail/cltbld', 09:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:39 INFO - 'MOZ_NO_REMOTE': '1', 09:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:39 INFO - 'NO_EM_RESTART': '1', 09:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:39 INFO - 'PWD': '/builds/slave/test', 09:12:39 INFO - 'SHELL': '/bin/bash', 09:12:39 INFO - 'SHLVL': '1', 09:12:39 INFO - 'TERM': 'linux', 09:12:39 INFO - 'TMOUT': '86400', 09:12:39 INFO - 'USER': 'cltbld', 09:12:39 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:39 INFO - '_': '/tools/buildbot/bin/python'} 09:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:12:41 INFO - Installing collected packages: mozsystemmonitor 09:12:41 INFO - Running setup.py install for mozsystemmonitor 09:12:41 INFO - Successfully installed mozsystemmonitor 09:12:41 INFO - Cleaning up... 09:12:41 INFO - Return code: 0 09:12:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:12:41 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')]} 09:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:41 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')]} 09:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:41 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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:41 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 09:12:41 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 09:12:41 INFO - Using env: {'DISPLAY': ':0', 09:12:41 INFO - 'HOME': '/home/cltbld', 09:12:41 INFO - 'LANG': 'en_US.UTF-8', 09:12:41 INFO - 'LANGUAGE': 'en_US:en', 09:12:41 INFO - 'LOGNAME': 'cltbld', 09:12:41 INFO - 'MAIL': '/var/mail/cltbld', 09:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:41 INFO - 'MOZ_NO_REMOTE': '1', 09:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:41 INFO - 'NO_EM_RESTART': '1', 09:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:41 INFO - 'PWD': '/builds/slave/test', 09:12:41 INFO - 'SHELL': '/bin/bash', 09:12:41 INFO - 'SHLVL': '1', 09:12:41 INFO - 'TERM': 'linux', 09:12:41 INFO - 'TMOUT': '86400', 09:12:41 INFO - 'USER': 'cltbld', 09:12:41 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:41 INFO - '_': '/tools/buildbot/bin/python'} 09:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:42 INFO - Downloading/unpacking jsonschema==2.5.1 09:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:12:44 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 09:12:44 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 09:12:44 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 09:12:44 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 09:12:44 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 09:12:44 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 09:12:44 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:44 INFO - warning: no files found matching '*.txt' 09:12:44 INFO - no previously-included directories found matching 'build' 09:12:44 INFO - no previously-included directories found matching 'dist' 09:12:44 INFO - no previously-included directories found matching '.git*' 09:12:44 INFO - Installing collected packages: jsonschema, functools32 09:12:44 INFO - Running setup.py install for functools32 09:12:44 INFO - warning: no files found matching '*.txt' 09:12:44 INFO - no previously-included directories found matching 'build' 09:12:44 INFO - no previously-included directories found matching 'dist' 09:12:44 INFO - no previously-included directories found matching '.git*' 09:12:44 INFO - Successfully installed jsonschema functools32 09:12:44 INFO - Cleaning up... 09:12:44 INFO - Return code: 0 09:12:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:12:44 INFO - Using env: {'DISPLAY': ':0', 09:12:44 INFO - 'HOME': '/home/cltbld', 09:12:44 INFO - 'LANG': 'en_US.UTF-8', 09:12:44 INFO - 'LANGUAGE': 'en_US:en', 09:12:44 INFO - 'LOGNAME': 'cltbld', 09:12:44 INFO - 'MAIL': '/var/mail/cltbld', 09:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:44 INFO - 'MOZ_NO_REMOTE': '1', 09:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:44 INFO - 'NO_EM_RESTART': '1', 09:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:44 INFO - 'PWD': '/builds/slave/test', 09:12:44 INFO - 'SHELL': '/bin/bash', 09:12:44 INFO - 'SHLVL': '1', 09:12:44 INFO - 'TERM': 'linux', 09:12:44 INFO - 'TMOUT': '86400', 09:12:44 INFO - 'USER': 'cltbld', 09:12:44 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:44 INFO - '_': '/tools/buildbot/bin/python'} 09:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:12:44 INFO - Cleaning up... 09:12:44 INFO - Return code: 0 09:12:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:12:44 INFO - Using env: {'DISPLAY': ':0', 09:12:44 INFO - 'HOME': '/home/cltbld', 09:12:44 INFO - 'LANG': 'en_US.UTF-8', 09:12:44 INFO - 'LANGUAGE': 'en_US:en', 09:12:44 INFO - 'LOGNAME': 'cltbld', 09:12:44 INFO - 'MAIL': '/var/mail/cltbld', 09:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:44 INFO - 'MOZ_NO_REMOTE': '1', 09:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:44 INFO - 'NO_EM_RESTART': '1', 09:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:44 INFO - 'PWD': '/builds/slave/test', 09:12:44 INFO - 'SHELL': '/bin/bash', 09:12:44 INFO - 'SHLVL': '1', 09:12:44 INFO - 'TERM': 'linux', 09:12:44 INFO - 'TMOUT': '86400', 09:12:44 INFO - 'USER': 'cltbld', 09:12:44 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:44 INFO - '_': '/tools/buildbot/bin/python'} 09:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:45 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 09:12:45 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 09:12:45 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 09:12:45 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 09:12:45 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 09:12:45 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 09:12:47 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:12:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 INFO - Downloading docopt-0.6.1.tar.gz 09:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:47 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:47 INFO - Running setup.py install for blobuploader 09:12:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:12:48 INFO - Running setup.py install for requests 09:12:48 INFO - Running setup.py install for docopt 09:12:48 INFO - Successfully installed blobuploader requests docopt 09:12:48 INFO - Cleaning up... 09:12:48 INFO - Return code: 0 09:12:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:48 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')]} 09:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:48 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')]} 09:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:48 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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:48 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 09:12:48 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 09:12:48 INFO - Using env: {'DISPLAY': ':0', 09:12:48 INFO - 'HOME': '/home/cltbld', 09:12:48 INFO - 'LANG': 'en_US.UTF-8', 09:12:48 INFO - 'LANGUAGE': 'en_US:en', 09:12:48 INFO - 'LOGNAME': 'cltbld', 09:12:48 INFO - 'MAIL': '/var/mail/cltbld', 09:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:48 INFO - 'MOZ_NO_REMOTE': '1', 09:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:48 INFO - 'NO_EM_RESTART': '1', 09:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:48 INFO - 'PWD': '/builds/slave/test', 09:12:48 INFO - 'SHELL': '/bin/bash', 09:12:48 INFO - 'SHLVL': '1', 09:12:48 INFO - 'TERM': 'linux', 09:12:48 INFO - 'TMOUT': '86400', 09:12:48 INFO - 'USER': 'cltbld', 09:12:48 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:48 INFO - '_': '/tools/buildbot/bin/python'} 09:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:48 INFO - Running setup.py (path:/tmp/pip-kKuSxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:48 INFO - Running setup.py (path:/tmp/pip-nFoJFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:48 INFO - Running setup.py (path:/tmp/pip-pdtV1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:49 INFO - Running setup.py (path:/tmp/pip-f7UCTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:49 INFO - Running setup.py (path:/tmp/pip-DkyCZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:49 INFO - Running setup.py (path:/tmp/pip-mrERCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:49 INFO - Running setup.py (path:/tmp/pip-fYruJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:49 INFO - Running setup.py (path:/tmp/pip-6xov2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:49 INFO - Running setup.py (path:/tmp/pip-V_SEZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:49 INFO - Running setup.py (path:/tmp/pip-Eek1Wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:49 INFO - Running setup.py (path:/tmp/pip-nowudw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:49 INFO - Running setup.py (path:/tmp/pip-LGs8xR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:49 INFO - Running setup.py (path:/tmp/pip-WZbvtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:49 INFO - Running setup.py (path:/tmp/pip-Rr1vYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:49 INFO - Running setup.py (path:/tmp/pip-kM7dNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:49 INFO - Running setup.py (path:/tmp/pip-nuMjYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:49 INFO - Running setup.py (path:/tmp/pip-FD3HOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:50 INFO - Running setup.py install for manifestparser 09:12:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Running setup.py install for mozcrash 09:12:50 INFO - Running setup.py install for mozdebug 09:12:50 INFO - Running setup.py install for mozdevice 09:12:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Running setup.py install for mozfile 09:12:50 INFO - Running setup.py install for mozhttpd 09:12:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Running setup.py install for mozinfo 09:12:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Running setup.py install for mozInstall 09:12:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:12:50 INFO - Running setup.py install for mozleak 09:12:50 INFO - Running setup.py install for mozlog 09:12:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:12:51 INFO - Running setup.py install for moznetwork 09:12:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:12:51 INFO - Running setup.py install for mozprocess 09:12:51 INFO - Running setup.py install for mozprofile 09:12:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:12:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:12:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:12:51 INFO - Running setup.py install for mozrunner 09:12:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:12:51 INFO - Running setup.py install for mozscreenshot 09:12:51 INFO - Running setup.py install for moztest 09:12:51 INFO - Running setup.py install for mozversion 09:12:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:12:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:51 INFO - Cleaning up... 09:12:51 INFO - Return code: 0 09:12:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:51 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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:51 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 09:12:51 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 09:12:51 INFO - Using env: {'DISPLAY': ':0', 09:12:51 INFO - 'HOME': '/home/cltbld', 09:12:51 INFO - 'LANG': 'en_US.UTF-8', 09:12:51 INFO - 'LANGUAGE': 'en_US:en', 09:12:51 INFO - 'LOGNAME': 'cltbld', 09:12:51 INFO - 'MAIL': '/var/mail/cltbld', 09:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:51 INFO - 'MOZ_NO_REMOTE': '1', 09:12:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:51 INFO - 'NO_EM_RESTART': '1', 09:12:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:51 INFO - 'PWD': '/builds/slave/test', 09:12:51 INFO - 'SHELL': '/bin/bash', 09:12:51 INFO - 'SHLVL': '1', 09:12:51 INFO - 'TERM': 'linux', 09:12:51 INFO - 'TMOUT': '86400', 09:12:51 INFO - 'USER': 'cltbld', 09:12:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:51 INFO - '_': '/tools/buildbot/bin/python'} 09:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:52 INFO - Running setup.py (path:/tmp/pip-jSaSVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:52 INFO - Running setup.py (path:/tmp/pip-0rGtMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:52 INFO - Running setup.py (path:/tmp/pip-dOMmiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:52 INFO - Running setup.py (path:/tmp/pip-tjCoOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:52 INFO - Running setup.py (path:/tmp/pip-AjNE6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:52 INFO - Running setup.py (path:/tmp/pip-E_VIxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:52 INFO - Running setup.py (path:/tmp/pip-O5nDoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:52 INFO - Running setup.py (path:/tmp/pip-K3ZiE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:52 INFO - Running setup.py (path:/tmp/pip-gOBrER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:52 INFO - Running setup.py (path:/tmp/pip-MCqGml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:52 INFO - Running setup.py (path:/tmp/pip-TsPObp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:52 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)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:52 INFO - Running setup.py (path:/tmp/pip-0kQ1uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:52 INFO - Running setup.py (path:/tmp/pip-5wXW8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:53 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)) 09:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:53 INFO - Running setup.py (path:/tmp/pip-37zI6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:53 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)) 09:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:53 INFO - Running setup.py (path:/tmp/pip-QaLUN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:53 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)) 09:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:53 INFO - Running setup.py (path:/tmp/pip-kvm1Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:53 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)) 09:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:53 INFO - Running setup.py (path:/tmp/pip-BRPCAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:55 INFO - Downloading blessings-1.6.tar.gz 09:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:55 INFO - Installing collected packages: blessings 09:12:55 INFO - Running setup.py install for blessings 09:12:55 INFO - Successfully installed blessings 09:12:55 INFO - Cleaning up... 09:12:55 INFO - Return code: 0 09:12:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:12:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:55 INFO - Reading from file tmpfile_stdout 09:12:55 INFO - Current package versions: 09:12:55 INFO - argparse == 1.2.1 09:12:55 INFO - blessings == 1.6 09:12:55 INFO - blobuploader == 1.2.4 09:12:55 INFO - docopt == 0.6.1 09:12:55 INFO - functools32 == 3.2.3-2 09:12:55 INFO - jsonschema == 2.5.1 09:12:55 INFO - manifestparser == 1.1 09:12:55 INFO - mozInstall == 1.12 09:12:55 INFO - mozcrash == 1.0 09:12:55 INFO - mozdebug == 0.1 09:12:55 INFO - mozdevice == 0.49 09:12:55 INFO - mozfile == 1.2 09:12:55 INFO - mozhttpd == 0.7 09:12:55 INFO - mozinfo == 0.9 09:12:55 INFO - mozleak == 0.1 09:12:55 INFO - mozlog == 3.4 09:12:55 INFO - moznetwork == 0.27 09:12:55 INFO - mozprocess == 0.25 09:12:55 INFO - mozprofile == 0.28 09:12:55 INFO - mozrunner == 6.13 09:12:55 INFO - mozscreenshot == 0.1 09:12:55 INFO - mozsystemmonitor == 0.3 09:12:55 INFO - moztest == 0.8 09:12:55 INFO - mozversion == 1.4 09:12:55 INFO - psutil == 3.1.1 09:12:55 INFO - requests == 1.2.3 09:12:55 INFO - wsgiref == 0.1.2 09:12:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:55 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')]} 09:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:55 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')]} 09:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:55 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 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:55 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 09:12:55 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 09:12:55 INFO - Using env: {'DISPLAY': ':0', 09:12:55 INFO - 'HOME': '/home/cltbld', 09:12:55 INFO - 'LANG': 'en_US.UTF-8', 09:12:55 INFO - 'LANGUAGE': 'en_US:en', 09:12:55 INFO - 'LOGNAME': 'cltbld', 09:12:55 INFO - 'MAIL': '/var/mail/cltbld', 09:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:55 INFO - 'MOZ_NO_REMOTE': '1', 09:12:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:55 INFO - 'NO_EM_RESTART': '1', 09:12:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:55 INFO - 'PWD': '/builds/slave/test', 09:12:55 INFO - 'SHELL': '/bin/bash', 09:12:55 INFO - 'SHLVL': '1', 09:12:55 INFO - 'TERM': 'linux', 09:12:55 INFO - 'TMOUT': '86400', 09:12:55 INFO - 'USER': 'cltbld', 09:12:55 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:55 INFO - '_': '/tools/buildbot/bin/python'} 09:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 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)) 09:12:56 INFO - Cleaning up... 09:12:56 INFO - Return code: 0 09:12:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, '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': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', '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 09:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:12:56 INFO - Using env: {'DISPLAY': ':0', 09:12:56 INFO - 'HOME': '/home/cltbld', 09:12:56 INFO - 'LANG': 'en_US.UTF-8', 09:12:56 INFO - 'LANGUAGE': 'en_US:en', 09:12:56 INFO - 'LOGNAME': 'cltbld', 09:12:56 INFO - 'MAIL': '/var/mail/cltbld', 09:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:56 INFO - 'MOZ_NO_REMOTE': '1', 09:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:56 INFO - 'NO_EM_RESTART': '1', 09:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:56 INFO - 'PWD': '/builds/slave/test', 09:12:56 INFO - 'SHELL': '/bin/bash', 09:12:56 INFO - 'SHLVL': '1', 09:12:56 INFO - 'TERM': 'linux', 09:12:56 INFO - 'TMOUT': '86400', 09:12:56 INFO - 'USER': 'cltbld', 09:12:56 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:56 INFO - '_': '/tools/buildbot/bin/python'} 09:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:12:56 INFO - Cleaning up... 09:12:56 INFO - Return code: 0 09:12:56 INFO - Running post-action listener: _resource_record_post_action 09:12:56 INFO - Running post-action listener: _start_resource_monitoring 09:12:56 INFO - Starting resource monitoring. 09:12:56 INFO - [mozharness: 2017-04-14 16:12:56.327202Z] Finished create-virtualenv step (success) 09:12:56 INFO - [mozharness: 2017-04-14 16:12:56.327664Z] Running install step. 09:12:56 INFO - Running pre-action listener: _resource_record_pre_action 09:12:56 INFO - Running main action method: install 09:12:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:56 INFO - Reading from file tmpfile_stdout 09:12:56 INFO - Detecting whether we're running mozinstall >=1.0... 09:12:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:12:56 INFO - Reading from file tmpfile_stdout 09:12:56 INFO - Output received: 09:12:56 INFO - Usage: mozinstall [options] installer 09:12:56 INFO - Options: 09:12:56 INFO - -h, --help show this help message and exit 09:12:56 INFO - -d DEST, --destination=DEST 09:12:56 INFO - Directory to install application into. [default: 09:12:56 INFO - "/builds/slave/test"] 09:12:56 INFO - --app=APP Application being installed. [default: firefox] 09:12:56 INFO - mkdir: /builds/slave/test/build/application 09:12:56 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'] 09:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:13:13 INFO - Reading from file tmpfile_stdout 09:13:13 INFO - Output received: 09:13:13 INFO - /builds/slave/test/build/application/firefox/firefox 09:13:13 INFO - Running post-action listener: _resource_record_post_action 09:13:13 INFO - [mozharness: 2017-04-14 16:13:13.231342Z] Finished install step (success) 09:13:13 INFO - [mozharness: 2017-04-14 16:13:13.231613Z] Running run-tests step. 09:13:13 INFO - Running pre-action listener: _resource_record_pre_action 09:13:13 INFO - Running main action method: run_tests 09:13:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:13:13 INFO - Python 2.7.3 09:13:13 INFO - Return code: 0 09:13:13 INFO - grabbing minidump binary from tooltool 09:13:13 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')]} 09:13:13 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 0x137c290>, '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 0x137cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137d040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:13:13 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 09:13:13 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 09:13:13 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 09:13:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:13:13 INFO - Return code: 0 09:13:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:13:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:13:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:13:13 INFO - ENV: RUST_BACKTRACE is now 1 09:13:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:13:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:13:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 09:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 09:13:13 INFO - Using env: {'DISPLAY': ':0', 09:13:13 INFO - 'HOME': '/home/cltbld', 09:13:13 INFO - 'LANG': 'en_US.UTF-8', 09:13:13 INFO - 'LANGUAGE': 'en_US:en', 09:13:13 INFO - 'LOGNAME': 'cltbld', 09:13:13 INFO - 'MAIL': '/var/mail/cltbld', 09:13:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:13 INFO - 'MOZ_NO_REMOTE': '1', 09:13:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:13 INFO - 'NO_EM_RESTART': '1', 09:13:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:13 INFO - 'PWD': '/builds/slave/test', 09:13:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:13 INFO - 'RUST_BACKTRACE': '1', 09:13:13 INFO - 'SHELL': '/bin/bash', 09:13:13 INFO - 'SHLVL': '1', 09:13:13 INFO - 'TERM': 'linux', 09:13:13 INFO - 'TMOUT': '86400', 09:13:13 INFO - 'USER': 'cltbld', 09:13:13 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274', 09:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:13 INFO - '_': '/tools/buildbot/bin/python'} 09:13:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 09:13:13 INFO - mozversion application_buildid: 20170414151056 09:13:13 INFO - mozversion application_changeset: 567d5c33be379ef8d206e3b64afec4a6a92920d6 09:13:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:13:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:13:13 INFO - mozversion application_name: Firefox 09:13:13 INFO - mozversion application_remotingname: firefox-dev 09:13:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:13:13 INFO - mozversion application_vendor: Mozilla 09:13:13 INFO - mozversion application_version: 54.0a2 09:13:13 INFO - mozversion platform_buildid: 20170414151056 09:13:13 INFO - mozversion platform_changeset: 567d5c33be379ef8d206e3b64afec4a6a92920d6 09:13:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:13:13 INFO - mozversion platform_version: 54.0a2 09:13:13 INFO - using testdate: 1492186393 09:13:13 INFO - actual date: 1492186393 09:13:13 INFO - starting webserver on 'localhost:51849' 09:13:13 INFO - SUITE-START | Running 2 tests 09:13:13 INFO - TEST-START | dromaeo_css 09:13:13 INFO - operating with platform_type : linux_ 09:13:13 INFO - Initialising browser for dromaeo_css test... 09:13:13 INFO - TEST-INFO | started process 3297 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeKJxq_/profile http://localhost:51849/getInfo.html) 09:13:18 INFO - PROCESS | 3297 | __metrics Screen width/height:1600/1200 09:13:18 INFO - PROCESS | 3297 | colorDepth:24 09:13:18 INFO - PROCESS | 3297 | Browser inner width/height: 1024/697 09:13:18 INFO - PROCESS | 3297 | __metrics 09:13:20 INFO - PROCESS | 3297 | 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" 09:13:20 INFO - PROCESS | 3297 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:13:21 INFO - TEST-INFO | 3297: exit 0 09:13:21 INFO - Browser initialized. 09:13:21 INFO - Running cycle 1/1 for dromaeo_css test... 09:13:21 INFO - TEST-INFO | started process 3391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeKJxq_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:33 INFO - PROCESS | 3391 | RSS: Main: 139956224 09:13:33 INFO - PROCESS | 3391 | 09:16:34 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:51849/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51849/tests/dromaeo/cssquery-ext.html) 09:16:36 INFO - PROCESS | 3391 | RSS: Main: 298061824 09:16:36 INFO - PROCESS | 3391 | 09:18:22 INFO - PROCESS | 3391 | ************************* 09:18:22 INFO - PROCESS | 3391 | A coding exception was thrown and uncaught in a Task. 09:18:22 INFO - PROCESS | 3391 | 09:18:22 ERROR - PROCESS | 3391 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:18:22 INFO - PROCESS | 3391 | Full stack: 09:18:22 INFO - PROCESS | 3391 | ************************* 09:18:22 INFO - PROCESS | 3391 | ************************* 09:18:22 INFO - PROCESS | 3391 | A coding exception was thrown and uncaught in a Task. 09:18:22 INFO - PROCESS | 3391 | 09:18:22 ERROR - PROCESS | 3391 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:18:22 INFO - PROCESS | 3391 | Full stack: 09:18:22 INFO - PROCESS | 3391 | ************************* 09:18:22 INFO - PROCESS | 3391 | ************************* 09:18:22 INFO - PROCESS | 3391 | A coding exception was thrown and uncaught in a Task. 09:18:22 INFO - PROCESS | 3391 | 09:18:22 ERROR - PROCESS | 3391 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:18:22 INFO - PROCESS | 3391 | Full stack: 09:18:22 INFO - PROCESS | 3391 | ************************* 09:18:22 INFO - PROCESS | 3391 | ************************* 09:18:22 INFO - PROCESS | 3391 | A coding exception was thrown and uncaught in a Task. 09:18:22 INFO - PROCESS | 3391 | 09:18:22 ERROR - PROCESS | 3391 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:18:22 INFO - PROCESS | 3391 | Full stack: 09:18:22 INFO - PROCESS | 3391 | ************************* 09:19:38 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:51849/tests/dromaeo/cssquery-ext.html (next: http://localhost:51849/tests/dromaeo/cssquery-jquery.html) 09:19:39 INFO - PROCESS | 3391 | RSS: Main: 224677888 09:19:39 INFO - PROCESS | 3391 | 09:22:40 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:51849/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51849/tests/dromaeo/cssquery-mootools.html) 09:22:41 INFO - PROCESS | 3391 | RSS: Main: 217767936 09:22:41 INFO - PROCESS | 3391 | 09:25:43 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:51849/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51849/tests/dromaeo/cssquery-prototype.html) 09:25:44 INFO - PROCESS | 3391 | RSS: Main: 222760960 09:25:44 INFO - PROCESS | 3391 | 09:28:46 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:51849/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51849/tests/dromaeo/cssquery-yui.html) 09:28:47 INFO - PROCESS | 3391 | RSS: Main: 175673344 09:28:47 INFO - PROCESS | 3391 | 09:31:49 INFO - PROCESS | 3391 | Cycle 1(1): loaded http://localhost:51849/tests/dromaeo/cssquery-yui.html (next: http://localhost:51849/tests/dromaeo/cssquery-dojo.html) 09:31:50 INFO - PROCESS | 3391 | RSS: Main: 268804096 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | __start_tp_report 09:31:50 INFO - PROCESS | 3391 | _x_x_mozilla_page_load 09:31:50 INFO - PROCESS | 3391 | _x_x_mozilla_page_load_details 09:31:50 INFO - PROCESS | 3391 | |i|pagename|runs| 09:31:50 INFO - PROCESS | 3391 | |0;dojo.html;3397.5753030871138;3440.5871295444535;3466.7746596471243;3466.9653303466966;3488.197714525659;5646.491815736588;5722.856928576788;5733.885322293562;5737.282839645044;5759.16492108644;5718.428157184279;5722.198892155102;5751.4248575142465;5766.106253530699;5771.2497375341145;5094.592432605383;5157.277981082632;5165.9225111623355;5177.042247184283;5217.3217481727515;4919.483454237307;5199.870003249911;5208.244804503344;5212.713300768475;5238.05714971305;4962.503749625027;5068.771905264272;5115.923261151086;5206.45332240115;5230.398504172012;5181.404138524062;5217.452167522431;5222.373315202162;5223.582113430935;5233.685978841244;5084.542391184811;5121.385433747937;5188.299579556749;5196.480351964811;5226.268322434862;5461.563074953994;5489.670619762827;5608.355039170507;5668.574856885709;5673.6879471629045;6131.662758548278;6166.260048794128;6166.599171053867;6168.321484636686;6168.9821179505325;6239.78160764371;6243.56295059346;6246.437820596168;6259.436650701413;6262.060690896356;5520.337559492847;5543.6119471637;5735.168400581934;5767.077267637158;5856.8535786604825;6326.304106548276;6336.58812177207;6344.206974128234;6347.777827776005;6348.650824204621;5953.166556682068;6053.51571874249;6059.515238780887;6062.514998800174;6099.329073801879;5821.388754180834;5865.149553314745;6049.3950604939155;6181.96909015452;6184.072389141665;6117.340260877713;6117.908231376533;6120.538559592075;6134.72433673595;6138.677162522609;5831.941680583224;5891.322497912709;5891.617044892068;6121.859371575363;6160.199174107427;4591.471980722193;4595.816167353335;4619.330197121466;4633.351330813689;4638.930416043762;4155.937660935088;4162.729422587522;4183.309753890574;4196.139791342767;4247.8088486018205;6029.788957386558;6055.666938318347;6055.788047418319;6058;6069.939300607026;5837.532997360201;5873.148393482921;6129.20597562684;6132.6627035513475;6244.625322480666;6052.818415447544;6055.6063855849225;6055.788047418319;6061.787837425669;6096.481799047042;5285.497877701612;5298.09932311508;5298.15229563268;5299.867503312448;5301.284326615858;6045.969770151121;6111.014442317695;6197.96901015492;6501.382368674968;6524.282328943812;5982.670953097448;6013.9097913532205;6096.298925623432;6186.845328866814;6193.876122477615;6471.579347342407;6479.611223326616;6484.546081774419;6490.837729056811;6494.6752662367635;5980.491658208927;6087.665178415053;6092.055731361646;6094.360092190256;6099.359567245376;5868.530517558671;5897.67562784051;6082.908756368746;6120.204373431426;6195.19462469876;5944.4352786485215;5951.851203719769;5952.880942381042;5958.165856779968;5993.160957465871;5877.882442351214;5902.4097590240635;5903.085021821625;5943.940560594512;5955.315138759098;5696.088625819849;5740.1963725077685;5823.155642431739;5895.027320492242;5895.64626122434;5952.255968003999;5966.76132954677;5987.88024239504;6003;6004.609700369462;5609.298837645294;5648.802291919763;5731.054376028075;5819.127130930395;5840.795572154954 09:31:50 INFO - PROCESS | 3391 | |1;ext.html;685.8148299959012;752.044902973224;768.8462307538493;868.2489646455817;884.8849649545558;30374.69625303749;43277.35083973743;44056.559434405644;44091.338629920574;44092;44887.77556112223;45028.774856125674;45042.32436513455;45107.32339014918;45130;7631.580263085528;7778.64996075178;8995.145461181177;9144.725658230263;9153.359264851462;10400.167986561057;10590.2057345699;10618.150547956184;10623.734406639818;10625.521851516662;10735.463226838667;10804.18968577356;10804.945975270113;10808.351498910051;10811.78376432471;10604.78790424195;10681.198910081775;10734.516946737338;10740.08709259714;10740.24818262722;10634.202434817342;10698.037176654061;10704.571817127306;10719.19606029551;10724.088452481548;41378;41473.377899331535;41570.37644435336;41571.376429353586;41643.37534936978;3789.6399842014966;3832.1760821423527;3837.961620383788;3840.9423858642144;3900.921981560353;29867.701322986708;29879.253018674488;30121.698783012107;30409;30577.541336879964;4163.39630753539;4299.935500967488;4304.827806887721;4307.246231909435;4371.23503386906;244605;246929;247067;247428;247470;52905.73547132317;52982.205266921024;53027;53032.46967530275;53064.204036939474;54902.17646735302;54992.45007549873;55120.44879551233;55136.44863551393;55153;15525.922370388076;16335.18324083791;16339.754903676454;16363.590910227102;16384.344626214817;12837.743245135232;13044.739105217843;14114.153150810778;14601.269936503133;14603.19682417456;12834.229946203257;12852.48590056406;12932.224066556037;12935.482580696855;13040.282784446947;5492.148716948879;5514.283143191359;5612.298462692163;5618.775248990077;5638.238837756932;993.7614972406715;1016.6797458800602;1020.7142000239945;1035.9171266298679;1056;26164.607530887053;27455.86272068627;27619.585706214424;27896.721032789817;28179.295517612223;1076.2742211435245;1093.0981939899614;1098.0940723902813;1119.5801574409597;1128.5260190719753;14059.367328470244;15010.642656566934;15063;15117.470888518848;15358.385664573292;260305;261474;261553;261583;261725;51181;51209.23186152211;51314.23028654573;51343;51397.22904156441;1488.7543555313468;1509.8263699674458;1536.7925330080295;1543.4983630320191;1561.4769052367358;25033.249002529952;26563.33591660224;26580.601290980263;26583.867080664473;26723.599146013214;39541.80229098836;46069.30896036629;46737.29894051524;50411.49588504068;50543;301624;302158;302403;302570;302963;24806.50386992286;25140;27831.44337113206;29295.707042929298;29488.11535653934;38485.615143848205;38765.41851872168;38947.415788763756;39011.41482877816;39156.60843391644;29817.25456863596;29824;30176.396472069995;30288.394232115676;30451.2387190322;29381.85309073441;29540.556891647073;29611.25971850635;29909.850450747606;30074.849625752606;34065.48901766525;34513.30973380568;35149.64850351464;35207.64792352044;35256.29487410289;28495.572566411935;28555.857220713762;28929.132126036253;28995.565066523595;29342.559861601665;23169.768302316763;23946.64080038766;24076.277711668678;24128.396790080387;24287.392815179763 09:31:50 INFO - PROCESS | 3391 | |2;jquery.html;461.2366533387241;468.59934755783814;475.5957436179248;485.3399376847487;493.393126454461;19610.31363902262;21420.57158856822;21438.356849294527;21728.3481495555;21757.99999999998;23012.424689382733;23048.769512304872;23150.3054908353;23323;23448.413789655267;6393.584417012891;6474.769793739198;6613.206415230179;6625.17185351831;6704.698288577026;6574.539782215247;6676.365745254193;6686.933130668704;6997.370236678698;7267.709291628328;7431.851362972738;7432.108147022365;7435.591042492661;7437.739679111232;7441.516301440389;7320.707171713152;7477.962610186914;7519.360854327416;7519.548827070366;7521.887171692429;7163.424046709715;7329.377002954755;7474.813129671747;7531.585762783045;7541.24587541247;24941.750582494307;24995.62506562403;25202.495950080895;25364.112256071134;25598.104066357588;3143.5441860930036;3233.87064517419;3290.3090351026312;3327.184839714261;3336.9989003299156;15937.840621593752;16007.519774406786;16330;16406.753898691528;16432.096234707085;2211.901997558879;2251.223327951854;2254.2673631069806;2288.4622113803243;2297.4715815362392;116089;119942.40028799887;120547.397263014;120576;120788.39605801915;18021.369252076114;18153.27386904509;18155.818441815678;18197.818021819876;18248.908755456137;31901.680983190334;31909.840450797597;31920.201994950312;32014.83992580022;32039.359212815612;9898.307118501636;10029.949850250701;10085.29402941787;10101.797964040825;10206.642767503101;8360.623771930277;8361.874571881426;8457.238848503664;8594.527300998505;8641.481511109354;9044.638214471348;9313.487758173365;9484.95257523708;9503.144716975505;9652.75868103303;3638.8362523686496;3658.7804731716183;3831.1762970961377;3831.214601006787;3861.4400911867624;982.513655740413;982.7493989032894;985.8028394321021;988.17981075707;1000.6147633161286;21065.473363166042;21320.78679213219;21538.676919846213;21599.075529809612;21605.243816466347;997.9351342162736;998.9350692204983;1003.9849402258972;1017.811704834608;1020.8877023527406;11030.558777648805;11624.128190385754;11713;11788.823167652492;11901.750316216672;677964;680096.8474652442;700344;703269;708648.654053832;106118;108226;109407;109634;110375;1201.8798120188087;1223.6390264871884;1230.3233221728015;1236.2706003458004;1255.5982085732478;17440.651186975934;18063.367782128087;18188.181531831364;18458.538536536693;18757.155927983;48254.75872620755;48359.5164048355;48990.26514602355;49133;49336;62931.37068629438;63017.68491157515;63935.68032159809;65064;66098;11832.881671183522;12319;12371.876281237073;12477.438515266653;12726.136619260593;13333.933330333286;13708.794368084686;14044.227567483478;14115.364808583843;14284.928575357057;6340.936590634219;11866.169368144147;12284.877151228373;12321.938390308349;12428.56500022531;11290.209685321948;11479.770404592029;11480.368569745828;11671.941640291745;11673.649790506299;11373.488193031499;11478.770424591294;11685.591004314809;12656.17734847232;12889;11352.772944541228;11453.854489705041;11580.826287605862;11623.941880290544;11694.88305116938;11152.275102118336;11296.717582060513;11327.15046371542;11580.6525804226;11653.708657283636 09:31:50 INFO - PROCESS | 3391 | |3;mootools.html;1446.7829825526164;1584.9683006339874;1602.4471557312731;1607.7749115123884;1608.0592853180888;9219.262459003281;10904.727381815448;11249.718757031069;11659.475323610437;11681.182317237797;11346.886531134685;11400.714982125452;11427.828582571268;11447.656570302905;11453.083753299716;1982.851286153537;2051.989740051305;2390.091765129253;2428.344347026302;2429.769171928664;2333.684952531412;2416.9637455438183;2423.854568725882;2424.539337525873;2439.7926176275037;2375.1449478187837;2392.605220138675;2398;2400.3399065257017;2404.903803847853;2353.846999944998;2373.501564671421;2378.9881050594636;2379.0840526397287;2381.2856143156982;2186.1364760919505;2225.977740222593;2228.955420891573;2229.7547269800307;2230.0856648773924;11450.65648030552;11530.192886497949;11538.826917596243;11559.711007224803;11569.074474042056;5362.195670649395;5511.917321240185;5522.144067669517;5529.861753456155;5533.22534845122;3178.7457003439667;3270.1661076425357;3418.5555877735983;3431.0564594736397;3439.07145070832;1304.4456106154846;1309.8624644412296;1313.3958379145604;1321.055445356565;1378.2488543743684;17175.742363864552;17199.398021069326;17251;17282.30870765168;17286.13569321529;4472.530384309665;4648.86053418398;4745.738984355825;4801.351817504662;4815.398075240595;13906.58280251594;13920.164790112625;13930.164190148624;13935.790963135561;13944.372503237175;5758.309002919593;5764.308283005982;5766.711664416763;5770;5782.045962416154;5125.820596279187;5153.072446959583;5199.038177937094;5205.427402985669;5269.235960785664;3975.3043217436834;4064.9390259146135;4081.89795255121;4128.318827393507;4153.54311025787;1033.1992705653029;1041.6614600254948;1057.5293994172519;1058.0371861605902;1058.523664351045;1000.1348833259324;1005.4470041477158;1015.8323876560432;1017.7608262058559;1022.9335093218917;6289.811305660837;6425.228972523327;6523.282438931714;6727.798166055026;6807.115075040312;1042.61944390297;1046.0899017854515;1066.2696053203426;1072.5120070367964;1110.3781882146025;4266.4240327556;4324.308110702273;4330.242207613718;4337.349397590387;4346.500152482505;14424.134551926918;15014.24928753558;15079.623009424851;15117.54647360581;15133.772993405108;14620;14700.77948830747;14740.852591474162;14849.851501484847;15009.324580393908;10463.84304235437;10476.952304769615;10599.099076578423;10627.681169564925;10656.62701805433;6593.208814942238;6629.7679581214425;6639;6640.435562977201;6701.765438209639;12475.376231188586;12504.499820007099;12654.87345126537;12761.106722529705;12836.358182090671;11122.88877111251;13000.479980800663;13034;13038.934805325913;13154.34228288601;7843.333316667919;8617.870731938901;8747.60635771379;8765.298776098025;8781.780455488664;10726.302790318605;10922.399268040333;10984.835227471754;11008.119350452105;11030.448477575928;8974.596143173703;9042.502662353634;9059.547022648972;9168.404053736485;9402.247820174232;8986.32602554798;9041.231495322709;9065.184133428023;9128.49793261377;9320.627174912928;7895.131535531202;8007.639656215601;8035.196480351918;8039.799005024921;8042.195780422145;6273.278572964167;6274.403931626488;6324.272708638566;6326.683665816783;6369.745210191726;6003.7898673546215;6008.459238668541;6049.3950604939155;6117.296510901126;6171.259448866164 09:31:50 INFO - PROCESS | 3391 | |4;prototype.html;413.5616246778417;416.58549743005716;433.23101494846645;449.96175325097374;449.9752513611751;5169.948300516994;5258.44786297439;5262.500312351641;5264.8157314494;5339.759710813013;5080.288759573663;5121.257417674435;5133.281340612317;5176.602317374307;5261.973690131554;4337.978310108445;4346.36977638243;4347.4131941841315;4359.367891655708;4371.737695738266;4361.629261512775;4396.978015109936;4428.291473364262;4437.290033594625;4451.332300154971;4247.343536020939;4313.590208930146;4320.28715261983;4373.300271956487;4441.422615060038;4352.759463552904;4357.2592659248;4358.65130789536;4373.747520027188;4379.75911324877;4094.9181016379507;4120.196561670476;4144.772037537934;4171.0615111599955;4173.770442625654;4984.850454486371;5037.697738135724;5082.466341034212;5207.583393328525;5264.368275806889;5053.84838454847;5072.797088116507;5096.541311281965;5116.488351164891;5126.000859664734;5494.862628434281;5505.889882202374;5533.972330138324;5535.086710692731;5550.500454959033;4490.7530085845265;4577.29051996941;4598.977005114953;4601.585857272828;4606.078784243131;5437.265969094161;5529.474699903543;5534.616345913521;5700.745835916092;5708.1723150143;4655.928111990132;4659.95340046602;4666.953330466652;4716.9056618867435;4724;4578.374676989621;4660.464046634613;4787.42550893888;4790.664653474223;4800.399950006249;4963.851084467472;5010.69935803853;5062.519060689228;5066.290719299301;5106.770195341219;5467.261919640877;5467.4532546745;5499.560035197174;5516.200150978086;5521.779128834802;1571.8192407873012;1634.9671359627957;1656.4119737493018;1662.8420300071473;1665.7923005820683;1266.5060626355728;1291.179436993834;1292.7414517096431;1293.275765571283;1310.7247478029722;5418.593605479605;5421.864453388618;5447;5452.182172674051;5470.343558772973;5650.943490565124;5656.208130861683;5658.830235092953;5686.402927692616;5692.345380281238;5269.894602107936;5381.811636592701;5382.973085134549;5444.972775136099;5471.23402723619;2941.8232706916974;2954.9214536693953;2960.319126600894;2965.383865793148;2969.7624190064744;5502.669839809624;5822.767089316465;5836.474717275466;5850.765969361263;5884.588078834526;5761.769529218868;5801.7389217485315;5807.738651760681;5826.950269562698;5836.358000619928;5831.571265040007;5851.912221316598;5890.823275301748;5909.527237820964;5959.553033522503;5319.823938208488;5572.3034620672415;5613.214150018919;5641;5658.9151162733415;5316.548093411949;5410.918836217381;5446.21029950647;5552.69460179694;5588.6646801192055;5818;5838.883222335445;5845.3570107289015;5882.49998750111;5897.498712609477;4596.701214421052;4719;4735.976320118378;4747.335373047707;4792.808287668454;5578.665280083208;5616.522595579421;5648.661080335193;5661.0752344203165;5690.2887139107615;5452.482014208644;5494.313210848644;5522.978249023783;5546.278983732089;5563.193336966198;5276.894462110813;5348.625596208383;5392.299001129828;5449.64577302474;5465.480779325958;5387.407385187548;5391.514763671288;5423.484768946943;5483.629092726818;5517.641353312022;5396.190571414162;5456.399796022513;5491.258680078298;5538.529225015757;5543.334799824047;5152.613553983541;5347.812826551052;5350.357957045179;5386.892262154814;5464.617476776588 09:31:50 INFO - PROCESS | 3391 | |5;yui.html;847.8134810341727;857.2798848966867;910.1126401758285;920.6685593186454;929.7350255177275;17373.7393939091;18132.72800907986;18458.815411845877;18711;18834.246630134778;16472.34110635576;16781.41265055723;16829.66340673186;16926.74609880853;16937.745933811002;875.7066382761783;880.0671288434264;903.6114470777563;906.075802681268;908.3323757038571;916.1479823763897;916.2761418479394;919.2691810011057;919.3564504846601;919.6413398774483;959.4435227567993;960.5149399553251;960.9087136722035;961.2838435365662;961.4904100826573;947.0245646983584;947.9004704506066;948.2461443152711;948.2603569215988;948.4593781544556;704.9118860142482;704.9612271325075;705.5590256089944;705.7459314646723;705.8941158826166;17056.82943170565;17113.144342782813;17206.311747530082;17532.561685957953;17720.468385948443;15980;16036.75944860828;16293.59266018347;16577.91711041449;16693.582660433465;13560.186388816703;13849.307534623329;13991.30043497821;14294.213818239992;14786.482473113389;4083.3670781028986;4083.7958102094776;4092.9590704092875;4149.7095203335775;4232.746035237865;86668.56665716694;87555.3629166857;88001.55999219963;88357;90233.09766902313;12301.876981230072;12310.630681079581;12346.81479777804;12382.690432739073;12484.375781210903;24082.87958560231;24192.637110443357;24205.152819651226;24212.515749684906;24242.393940151636;8304.792380190424;8379.413441059189;8493.830123397498;8575.742727718178;8590.355723320776;4810.7113573185725;4858.368412106474;4863.392075990501;4873.269009648581;4951.084049450863;3673.540807399075;3716.1267102230954;3745.3632882410056;3771.132639492932;3786.3752480840353;574.4858351775138;584.0713265907228;590.4538302070654;591.2432086928738;592.3365830270125;798.5767543201996;815.5962798414822;816.420341557489;833.4540875726409;855.6149732620346;11011.28426652281;11282.830757538644;11305.773884522263;11360.715982100513;11803.468843902045;860.802223987294;863.9611217495228;871.8648609465542;877.2105105405186;878.7978764883985;5490.505854473116;5506.284183056177;5529.944700553024;5594.216809646571;5606.243157173811;65554;68615;75393.62303188449;75734;80410;63247;64247;65923.34076659172;66643.33356666469;67612.32387676158;62330.37669623246;62650.686746565974;63528.999999999076;63531;63565.68217158884;11443.542258309908;11625.302481851117;11726.94136529312;11977.820332695192;11980.820287695866;41907.580924191585;41941.58058419377;41947.58052419559;42576;43294.13411731685;46181;46708.53291467042;46841;47255.527444725114;48819.75590122027;13021.283829389475;13568.728625427633;13612.982180345552;13790.931045344709;14000.509982150576;16180.352785888432;16409.91795041017;16618.41835535799;16664.916675416545;16864.915675421544;15390.230488475756;15445.84554154444;15501.069935803887;15620.765688514453;15809;15349;15501.224938752792;15715.292811823723;15866.286017129027;16230.756538652166;15096.866395830364;15349.769753453484;15670.608234794221;15689.29398177062;15953.601159971093;8786.042937131399;8891.866622000545;8924.152205540464;9076.500792456478;9157.809484766938;11203.495842686936;11487.425628718698;11664.125190610568;13340.933295333462;13360.398782054637 09:31:50 INFO - PROCESS | 3391 | __end_tp_report 09:31:50 INFO - PROCESS | 3391 | __start_cc_report 09:31:50 INFO - PROCESS | 3391 | _x_x_mozilla_cycle_collect,1600 09:31:50 INFO - PROCESS | 3391 | __end_cc_report 09:31:50 INFO - PROCESS | 3391 | __startTimestamp1492187510075__endTimestamp 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | ------- Summary: start ------- 09:31:50 INFO - PROCESS | 3391 | Number of tests: 6 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | [#0] dojo.html Cycles:165 Average:5683.06 Median:5895.03 stddev:645.83 (11.0%) stddev-sans-first:622.43 09:31:50 INFO - PROCESS | 3391 | Values: 3397.6 3440.6 3466.8 3467.0 3488.2 5646.5 5722.9 5733.9 5737.3 5759.2 5718.4 5722.2 5751.4 5766.1 5771.2 5094.6 5157.3 5165.9 5177.0 5217.3 4919.5 5199.9 5208.2 5212.7 5238.1 4962.5 5068.8 5115.9 5206.5 5230.4 5181.4 5217.5 5222.4 5223.6 5233.7 5084.5 5121.4 5188.3 5196.5 5226.3 5461.6 5489.7 5608.4 5668.6 5673.7 6131.7 6166.3 6166.6 6168.3 6169.0 6239.8 6243.6 6246.4 6259.4 6262.1 5520.3 5543.6 5735.2 5767.1 5856.9 6326.3 6336.6 6344.2 6347.8 6348.7 5953.2 6053.5 6059.5 6062.5 6099.3 5821.4 5865.1 6049.4 6182.0 6184.1 6117.3 6117.9 6120.5 6134.7 6138.7 5831.9 5891.3 5891.6 6121.9 6160.2 4591.5 4595.8 4619.3 4633.4 4638.9 4155.9 4162.7 4183.3 4196.1 4247.8 6029.8 6055.7 6055.8 6058.0 6069.9 5837.5 5873.1 6129.2 6132.7 6244.6 6052.8 6055.6 6055.8 6061.8 6096.5 5285.5 5298.1 5298.2 5299.9 5301.3 6046.0 6111.0 6198.0 6501.4 6524.3 5982.7 6013.9 6096.3 6186.8 6193.9 6471.6 6479.6 6484.5 6490.8 6494.7 5980.5 6087.7 6092.1 6094.4 6099.4 5868.5 5897.7 6082.9 6120.2 6195.2 5944.4 5951.9 5952.9 5958.2 5993.2 5877.9 5902.4 5903.1 5943.9 5955.3 5696.1 5740.2 5823.2 5895.0 5895.6 5952.3 5966.8 5987.9 6003.0 6004.6 5609.3 5648.8 5731.1 5819.1 5840.8 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | [#1] ext.html Cycles:180 Average:43583.60 Median:29575.91 stddev:70670.58 (238.9%) stddev-sans-first:70795.43 09:31:50 INFO - PROCESS | 3391 | Values: 685.8 752.0 768.8 868.2 884.9 30374.7 43277.4 44056.6 44091.3 44092.0 44887.8 45028.8 45042.3 45107.3 45130.0 7631.6 7778.6 8995.1 9144.7 9153.4 10400.2 10590.2 10618.2 10623.7 10625.5 10735.5 10804.2 10804.9 10808.4 10811.8 10604.8 10681.2 10734.5 10740.1 10740.2 10634.2 10698.0 10704.6 10719.2 10724.1 41378.0 41473.4 41570.4 41571.4 41643.4 3789.6 3832.2 3838.0 3840.9 3900.9 29867.7 29879.3 30121.7 30409.0 30577.5 4163.4 4299.9 4304.8 4307.2 4371.2 244605.0 246929.0 247067.0 247428.0 247470.0 52905.7 52982.2 53027.0 53032.5 53064.2 54902.2 54992.5 55120.4 55136.4 55153.0 15525.9 16335.2 16339.8 16363.6 16384.3 12837.7 13044.7 14114.2 14601.3 14603.2 12834.2 12852.5 12932.2 12935.5 13040.3 5492.1 5514.3 5612.3 5618.8 5638.2 993.8 1016.7 1020.7 1035.9 1056.0 26164.6 27455.9 27619.6 27896.7 28179.3 1076.3 1093.1 1098.1 1119.6 1128.5 14059.4 15010.6 15063.0 15117.5 15358.4 260305.0 261474.0 261553.0 261583.0 261725.0 51181.0 51209.2 51314.2 51343.0 51397.2 1488.8 1509.8 1536.8 1543.5 1561.5 25033.2 26563.3 26580.6 26583.9 26723.6 39541.8 46069.3 46737.3 50411.5 50543.0 301624.0 302158.0 302403.0 302570.0 302963.0 24806.5 25140.0 27831.4 29295.7 29488.1 38485.6 38765.4 38947.4 39011.4 39156.6 29817.3 29824.0 30176.4 30288.4 30451.2 29381.9 29540.6 29611.3 29909.9 30074.8 34065.5 34513.3 35149.6 35207.6 35256.3 28495.6 28555.9 28929.1 28995.6 29342.6 23169.8 23946.6 24076.3 24128.4 24287.4 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | [#2] jquery.html Cycles:180 Average:38417.28 Median:2292.97 stddev:114228.20 (4981.7%) stddev-sans-first:114513.09 09:31:50 INFO - PROCESS | 3391 | Values: 461.2 468.6 475.6 485.3 493.4 19610.3 21420.6 21438.4 21728.3 21758.0 23012.4 23048.8 23150.3 23323.0 23448.4 6393.6 6474.8 6613.2 6625.2 6704.7 6574.5 6676.4 6686.9 6997.4 7267.7 7431.9 7432.1 7435.6 7437.7 7441.5 7320.7 7478.0 7519.4 7519.5 7521.9 7163.4 7329.4 7474.8 7531.6 7541.2 24941.8 24995.6 25202.5 25364.1 25598.1 3143.5 3233.9 3290.3 3327.2 3337.0 15937.8 16007.5 16330.0 16406.8 16432.1 2211.9 2251.2 2254.3 2288.5 2297.5 116089.0 119942.4 120547.4 120576.0 120788.4 18021.4 18153.3 18155.8 18197.8 18248.9 31901.7 31909.8 31920.2 32014.8 32039.4 9898.3 10029.9 10085.3 10101.8 10206.6 8360.6 8361.9 8457.2 8594.5 8641.5 9044.6 9313.5 9485.0 9503.1 9652.8 3638.8 3658.8 3831.2 3831.2 3861.4 982.5 982.7 985.8 988.2 1000.6 21065.5 21320.8 21538.7 21599.1 21605.2 997.9 998.9 1004.0 1017.8 1020.9 11030.6 11624.1 11713.0 11788.8 11901.8 677964.0 680096.8 700344.0 703269.0 708648.7 106118.0 108226.0 109407.0 109634.0 110375.0 1201.9 1223.6 1230.3 1236.3 1255.6 17440.7 18063.4 18188.2 18458.5 18757.2 48254.8 48359.5 48990.3 49133.0 49336.0 62931.4 63017.7 63935.7 65064.0 66098.0 11832.9 12319.0 12371.9 12477.4 12726.1 13333.9 13708.8 14044.2 14115.4 14284.9 6340.9 11866.2 12284.9 12321.9 12428.6 11290.2 11479.8 11480.4 11671.9 11673.6 11373.5 11478.8 11685.6 12656.2 12889.0 11352.8 11453.9 11580.8 11623.9 11694.9 11152.3 11296.7 11327.2 11580.7 11653.7 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | [#3] mootools.html Cycles:180 Average:7015.39 Median:2379.04 stddev:4604.36 (193.5%) stddev-sans-first:4598.27 09:31:50 INFO - PROCESS | 3391 | Values: 1446.8 1585.0 1602.4 1607.8 1608.1 9219.3 10904.7 11249.7 11659.5 11681.2 11346.9 11400.7 11427.8 11447.7 11453.1 1982.9 2052.0 2390.1 2428.3 2429.8 2333.7 2417.0 2423.9 2424.5 2439.8 2375.1 2392.6 2398.0 2400.3 2404.9 2353.8 2373.5 2379.0 2379.1 2381.3 2186.1 2226.0 2229.0 2229.8 2230.1 11450.7 11530.2 11538.8 11559.7 11569.1 5362.2 5511.9 5522.1 5529.9 5533.2 3178.7 3270.2 3418.6 3431.1 3439.1 1304.4 1309.9 1313.4 1321.1 1378.2 17175.7 17199.4 17251.0 17282.3 17286.1 4472.5 4648.9 4745.7 4801.4 4815.4 13906.6 13920.2 13930.2 13935.8 13944.4 5758.3 5764.3 5766.7 5770.0 5782.0 5125.8 5153.1 5199.0 5205.4 5269.2 3975.3 4064.9 4081.9 4128.3 4153.5 1033.2 1041.7 1057.5 1058.0 1058.5 1000.1 1005.4 1015.8 1017.8 1022.9 6289.8 6425.2 6523.3 6727.8 6807.1 1042.6 1046.1 1066.3 1072.5 1110.4 4266.4 4324.3 4330.2 4337.3 4346.5 14424.1 15014.2 15079.6 15117.5 15133.8 14620.0 14700.8 14740.9 14849.9 15009.3 10463.8 10477.0 10599.1 10627.7 10656.6 6593.2 6629.8 6639.0 6640.4 6701.8 12475.4 12504.5 12654.9 12761.1 12836.4 11122.9 13000.5 13034.0 13038.9 13154.3 7843.3 8617.9 8747.6 8765.3 8781.8 10726.3 10922.4 10984.8 11008.1 11030.4 8974.6 9042.5 9059.5 9168.4 9402.2 8986.3 9041.2 9065.2 9128.5 9320.6 7895.1 8007.6 8035.2 8039.8 8042.2 6273.3 6274.4 6324.3 6326.7 6369.7 6003.8 6008.5 6049.4 6117.3 6171.3 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | [#4] prototype.html Cycles:180 Average:4793.43 Median:5273.39 stddev:1271.83 (24.1%) stddev-sans-first:1232.18 09:31:50 INFO - PROCESS | 3391 | Values: 413.6 416.6 433.2 450.0 450.0 5169.9 5258.4 5262.5 5264.8 5339.8 5080.3 5121.3 5133.3 5176.6 5262.0 4338.0 4346.4 4347.4 4359.4 4371.7 4361.6 4397.0 4428.3 4437.3 4451.3 4247.3 4313.6 4320.3 4373.3 4441.4 4352.8 4357.3 4358.7 4373.7 4379.8 4094.9 4120.2 4144.8 4171.1 4173.8 4984.9 5037.7 5082.5 5207.6 5264.4 5053.8 5072.8 5096.5 5116.5 5126.0 5494.9 5505.9 5534.0 5535.1 5550.5 4490.8 4577.3 4599.0 4601.6 4606.1 5437.3 5529.5 5534.6 5700.7 5708.2 4655.9 4660.0 4667.0 4716.9 4724.0 4578.4 4660.5 4787.4 4790.7 4800.4 4963.9 5010.7 5062.5 5066.3 5106.8 5467.3 5467.5 5499.6 5516.2 5521.8 1571.8 1635.0 1656.4 1662.8 1665.8 1266.5 1291.2 1292.7 1293.3 1310.7 5418.6 5421.9 5447.0 5452.2 5470.3 5650.9 5656.2 5658.8 5686.4 5692.3 5269.9 5381.8 5383.0 5445.0 5471.2 2941.8 2954.9 2960.3 2965.4 2969.8 5502.7 5822.8 5836.5 5850.8 5884.6 5761.8 5801.7 5807.7 5827.0 5836.4 5831.6 5851.9 5890.8 5909.5 5959.6 5319.8 5572.3 5613.2 5641.0 5658.9 5316.5 5410.9 5446.2 5552.7 5588.7 5818.0 5838.9 5845.4 5882.5 5897.5 4596.7 4719.0 4736.0 4747.3 4792.8 5578.7 5616.5 5648.7 5661.1 5690.3 5452.5 5494.3 5523.0 5546.3 5563.2 5276.9 5348.6 5392.3 5449.6 5465.5 5387.4 5391.5 5423.5 5483.6 5517.6 5396.2 5456.4 5491.3 5538.5 5543.3 5152.6 5347.8 5350.4 5386.9 5464.6 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - PROCESS | 3391 | [#5] yui.html Cycles:180 Average:18179.39 Median:46444.77 stddev:22125.95 (47.6%) stddev-sans-first:22149.74 09:31:50 INFO - PROCESS | 3391 | Values: 847.8 857.3 910.1 920.7 929.7 17373.7 18132.7 18458.8 18711.0 18834.2 16472.3 16781.4 16829.7 16926.7 16937.7 875.7 880.1 903.6 906.1 908.3 916.1 916.3 919.3 919.4 919.6 959.4 960.5 960.9 961.3 961.5 947.0 947.9 948.2 948.3 948.5 704.9 705.0 705.6 705.7 705.9 17056.8 17113.1 17206.3 17532.6 17720.5 15980.0 16036.8 16293.6 16577.9 16693.6 13560.2 13849.3 13991.3 14294.2 14786.5 4083.4 4083.8 4093.0 4149.7 4232.7 86668.6 87555.4 88001.6 88357.0 90233.1 12301.9 12310.6 12346.8 12382.7 12484.4 24082.9 24192.6 24205.2 24212.5 24242.4 8304.8 8379.4 8493.8 8575.7 8590.4 4810.7 4858.4 4863.4 4873.3 4951.1 3673.5 3716.1 3745.4 3771.1 3786.4 574.5 584.1 590.5 591.2 592.3 798.6 815.6 816.4 833.5 855.6 11011.3 11282.8 11305.8 11360.7 11803.5 860.8 864.0 871.9 877.2 878.8 5490.5 5506.3 5529.9 5594.2 5606.2 65554.0 68615.0 75393.6 75734.0 80410.0 63247.0 64247.0 65923.3 66643.3 67612.3 62330.4 62650.7 63529.0 63531.0 63565.7 11443.5 11625.3 11726.9 11977.8 11980.8 41907.6 41941.6 41947.6 42576.0 43294.1 46181.0 46708.5 46841.0 47255.5 48819.8 13021.3 13568.7 13613.0 13790.9 14000.5 16180.4 16409.9 16618.4 16664.9 16864.9 15390.2 15445.8 15501.1 15620.8 15809.0 15349.0 15501.2 15715.3 15866.3 16230.8 15096.9 15349.8 15670.6 15689.3 15953.6 8786.0 8891.9 8924.2 9076.5 9157.8 11203.5 11487.4 11664.1 13340.9 13360.4 09:31:50 INFO - PROCESS | 3391 | -------- Summary: end -------- 09:31:50 INFO - PROCESS | 3391 | 09:31:50 INFO - TEST-INFO | 3391: exit 0 09:31:50 INFO - TEST-OK | dromaeo_css | took 1117253ms 09:31:50 INFO - TEST-START | kraken 09:31:50 INFO - operating with platform_type : linux_ 09:31:50 INFO - Initialising browser for kraken test... 09:31:50 INFO - TEST-INFO | started process 3715 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNJogMF/profile http://localhost:51849/getInfo.html) 09:31:54 INFO - PROCESS | 3715 | __metrics Screen width/height:1600/1200 09:31:54 INFO - PROCESS | 3715 | colorDepth:24 09:31:54 INFO - PROCESS | 3715 | Browser inner width/height: 1024/697 09:31:54 INFO - PROCESS | 3715 | __metrics 09:31:55 INFO - PROCESS | 3715 | 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" 09:31:55 INFO - PROCESS | 3715 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:31:56 INFO - TEST-INFO | 3715: exit 0 09:31:56 INFO - Browser initialized. 09:31:56 INFO - Running cycle 1/1 for kraken test... 09:31:56 INFO - TEST-INFO | started process 3807 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNJogMF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:08 INFO - PROCESS | 3807 | RSS: Main: 140009472 09:32:08 INFO - PROCESS | 3807 | 09:32:11 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51849/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:11 INFO - PROCESS | 3807 | RSS: Main: 183603200 09:32:11 INFO - PROCESS | 3807 | 09:32:14 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51849/tests/kraken/driver.html?testName=audio-dft) 09:32:15 INFO - PROCESS | 3807 | RSS: Main: 174297088 09:32:15 INFO - PROCESS | 3807 | 09:32:17 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51849/tests/kraken/driver.html?testName=audio-fft) 09:32:18 INFO - PROCESS | 3807 | RSS: Main: 265883648 09:32:18 INFO - PROCESS | 3807 | 09:32:20 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51849/tests/kraken/driver.html?testName=audio-oscillator) 09:32:21 INFO - PROCESS | 3807 | RSS: Main: 172302336 09:32:21 INFO - PROCESS | 3807 | 09:32:23 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51849/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:32:23 INFO - PROCESS | 3807 | RSS: Main: 174354432 09:32:23 INFO - PROCESS | 3807 | 09:32:26 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51849/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:27 INFO - PROCESS | 3807 | RSS: Main: 214155264 09:32:27 INFO - PROCESS | 3807 | 09:32:30 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51849/tests/kraken/driver.html?testName=imaging-desaturate) 09:32:31 INFO - PROCESS | 3807 | RSS: Main: 212828160 09:32:31 INFO - PROCESS | 3807 | 09:32:34 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51849/tests/kraken/driver.html?testName=json-parse-financial) 09:32:34 INFO - PROCESS | 3807 | RSS: Main: 211410944 09:32:34 INFO - PROCESS | 3807 | 09:32:36 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51849/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:32:36 INFO - PROCESS | 3807 | RSS: Main: 173703168 09:32:36 INFO - PROCESS | 3807 | 09:32:38 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:39 INFO - PROCESS | 3807 | RSS: Main: 180232192 09:32:39 INFO - PROCESS | 3807 | 09:32:41 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:32:41 INFO - PROCESS | 3807 | RSS: Main: 189513728 09:32:41 INFO - PROCESS | 3807 | 09:32:44 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:44 INFO - PROCESS | 3807 | RSS: Main: 199667712 09:32:44 INFO - PROCESS | 3807 | 09:32:47 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:48 INFO - PROCESS | 3807 | RSS: Main: 195764224 09:32:48 INFO - PROCESS | 3807 | 09:32:49 INFO - PROCESS | 3807 | Cycle 1(1): loaded http://localhost:51849/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51849/tests/kraken/driver.html?testName=ai-astar) 09:32:50 INFO - PROCESS | 3807 | RSS: Main: 246259712 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | __start_tp_report 09:32:50 INFO - PROCESS | 3807 | _x_x_mozilla_page_load 09:32:50 INFO - PROCESS | 3807 | _x_x_mozilla_page_load_details 09:32:50 INFO - PROCESS | 3807 | |i|pagename|runs| 09:32:50 INFO - PROCESS | 3807 | |0;ai-astar;102;111;110;111;111;111;110;112;111;111 09:32:50 INFO - PROCESS | 3807 | |1;audio-beat-detection;144;113;117;155;117;117;115;114;135;116 09:32:50 INFO - PROCESS | 3807 | |2;audio-dft;161;162;161;162;162;160;159;160;161;159 09:32:50 INFO - PROCESS | 3807 | |3;audio-fft;74;75;75;112;76;75;76;75;90;75 09:32:50 INFO - PROCESS | 3807 | |4;audio-oscillator;82;81;117;82;81;82;98;82;82;102 09:32:50 INFO - PROCESS | 3807 | |5;imaging-gaussian-blur;113;112;114;113;113;113;113;114;113;113 09:32:50 INFO - PROCESS | 3807 | |6;imaging-darkroom;148;146;146;147;146;146;146;146;146;146 09:32:50 INFO - PROCESS | 3807 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;94;94 09:32:50 INFO - PROCESS | 3807 | |8;json-parse-financial;52;55;53;53;53;55;53;55;53;55 09:32:50 INFO - PROCESS | 3807 | |9;json-stringify-tinderbox;54;53;55;54;54;53;53;53;54;55 09:32:50 INFO - PROCESS | 3807 | |10;stanford-crypto-aes;80;75;75;74;80;78;75;79;79;80 09:32:50 INFO - PROCESS | 3807 | |11;stanford-crypto-ccm;120;121;125;128;123;119;133;119;160;140 09:32:50 INFO - PROCESS | 3807 | |12;stanford-crypto-pbkdf2;161;163;161;158;162;162;156;164;161;161 09:32:50 INFO - PROCESS | 3807 | |13;stanford-crypto-sha256-iterative;65;61;59;60;60;64;59;61;58;62 09:32:50 INFO - PROCESS | 3807 | __end_tp_report 09:32:50 INFO - PROCESS | 3807 | __start_cc_report 09:32:50 INFO - PROCESS | 3807 | _x_x_mozilla_cycle_collect,1694 09:32:50 INFO - PROCESS | 3807 | __end_cc_report 09:32:50 INFO - PROCESS | 3807 | __startTimestamp1492187570194__endTimestamp 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | ------- Summary: start ------- 09:32:50 INFO - PROCESS | 3807 | Number of tests: 14 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.87 (2.6%) stddev-sans-first:0.60 09:32:50 INFO - PROCESS | 3807 | Values: 102.0 111.0 110.0 111.0 111.0 111.0 110.0 112.0 111.0 111.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:117.00 stddev:14.89 (12.7%) stddev-sans-first:13.98 09:32:50 INFO - PROCESS | 3807 | Values: 144.0 113.0 117.0 155.0 117.0 117.0 115.0 114.0 135.0 116.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 09:32:50 INFO - PROCESS | 3807 | Values: 161.0 162.0 161.0 162.0 162.0 160.0 159.0 160.0 161.0 159.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#3] audio-fft Cycles:10 Average:80.30 Median:75.00 stddev:12.09 (16.1%) stddev-sans-first:12.61 09:32:50 INFO - PROCESS | 3807 | Values: 74.0 75.0 75.0 112.0 76.0 75.0 76.0 75.0 90.0 75.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:82.00 stddev:12.50 (15.2%) stddev-sans-first:13.01 09:32:50 INFO - PROCESS | 3807 | Values: 82.0 81.0 117.0 82.0 81.0 82.0 98.0 82.0 82.0 102.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 09:32:50 INFO - PROCESS | 3807 | Values: 113.0 112.0 114.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.33 09:32:50 INFO - PROCESS | 3807 | Values: 148.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:32:50 INFO - PROCESS | 3807 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.16 (2.1%) stddev-sans-first:1.05 09:32:50 INFO - PROCESS | 3807 | Values: 52.0 55.0 53.0 53.0 53.0 55.0 53.0 55.0 53.0 55.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:54.00 stddev:0.79 (1.5%) stddev-sans-first:0.83 09:32:50 INFO - PROCESS | 3807 | Values: 54.0 53.0 55.0 54.0 54.0 53.0 53.0 53.0 54.0 55.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#10] stanford-crypto-aes Cycles:10 Average:77.50 Median:79.00 stddev:2.46 (3.1%) stddev-sans-first:2.44 09:32:50 INFO - PROCESS | 3807 | Values: 80.0 75.0 75.0 74.0 80.0 78.0 75.0 79.0 79.0 80.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:126.50 stddev:12.89 (10.2%) stddev-sans-first:13.27 09:32:50 INFO - PROCESS | 3807 | Values: 120.0 121.0 125.0 128.0 123.0 119.0 133.0 119.0 160.0 140.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.50 stddev:2.33 (1.4%) stddev-sans-first:2.47 09:32:50 INFO - PROCESS | 3807 | Values: 161.0 163.0 161.0 158.0 162.0 162.0 156.0 164.0 161.0 161.0 09:32:50 INFO - PROCESS | 3807 | 09:32:50 INFO - PROCESS | 3807 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:2.23 (3.7%) stddev-sans-first:1.81 09:32:50 INFO - PROCESS | 3807 | Values: 65.0 61.0 59.0 60.0 60.0 64.0 59.0 61.0 58.0 62.0 09:32:50 INFO - PROCESS | 3807 | -------- Summary: end -------- 09:32:50 INFO - PROCESS | 3807 | 09:32:51 INFO - TEST-INFO | 3807: exit 0 09:32:51 INFO - TEST-OK | kraken | took 60392ms 09:32:51 INFO - SUITE-END | took 1177s 09:32:51 INFO - Completed test suite (00:19:37) 09:32:51 INFO - javascript benchmark 09:32:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7754.6365695113445, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3397.5753030871138, 3440.5871295444535, 3466.7746596471243, 3466.9653303466966, 3488.197714525659, 5646.491815736588, 5722.856928576788, 5733.885322293562, 5737.282839645044, 5759.16492108644, 5718.428157184279, 5722.198892155102, 5751.4248575142465, 5766.106253530699, 5771.2497375341145, 5094.592432605383, 5157.277981082632, 5165.9225111623355, 5177.042247184283, 5217.3217481727515, 4919.483454237307, 5199.870003249911, 5208.244804503344, 5212.713300768475, 5238.05714971305, 4962.503749625027, 5068.771905264272, 5115.923261151086, 5206.45332240115, 5230.398504172012, 5181.404138524062, 5217.452167522431, 5222.373315202162, 5223.582113430935, 5233.685978841244, 5084.542391184811, 5121.385433747937, 5188.299579556749, 5196.480351964811, 5226.268322434862, 5461.563074953994, 5489.670619762827, 5608.355039170507, 5668.574856885709, 5673.6879471629045, 6131.662758548278, 6166.260048794128, 6166.599171053867, 6168.321484636686, 6168.9821179505325, 6239.78160764371, 6243.56295059346, 6246.437820596168, 6259.436650701413, 6262.060690896356, 5520.337559492847, 5543.6119471637, 5735.168400581934, 5767.077267637158, 5856.8535786604825, 6326.304106548276, 6336.58812177207, 6344.206974128234, 6347.777827776005, 6348.650824204621, 5953.166556682068, 6053.51571874249, 6059.515238780887, 6062.514998800174, 6099.329073801879, 5821.388754180834, 5865.149553314745, 6049.3950604939155, 6181.96909015452, 6184.072389141665, 6117.340260877713, 6117.908231376533, 6120.538559592075, 6134.72433673595, 6138.677162522609, 5831.941680583224, 5891.322497912709, 5891.617044892068, 6121.859371575363, 6160.199174107427, 4591.471980722193, 4595.816167353335, 4619.330197121466, 4633.351330813689, 4638.930416043762, 4155.937660935088, 4162.729422587522, 4183.309753890574, 4196.139791342767, 4247.8088486018205, 6029.788957386558, 6055.666938318347, 6055.788047418319, 6058.0, 6069.939300607026, 5837.532997360201, 5873.148393482921, 6129.20597562684, 6132.6627035513475, 6244.625322480666, 6052.818415447544, 6055.6063855849225, 6055.788047418319, 6061.787837425669, 6096.481799047042, 5285.497877701612, 5298.09932311508, 5298.15229563268, 5299.867503312448, 5301.284326615858, 6045.969770151121, 6111.014442317695, 6197.96901015492, 6501.382368674968, 6524.282328943812, 5982.670953097448, 6013.9097913532205, 6096.298925623432, 6186.845328866814, 6193.876122477615, 6471.579347342407, 6479.611223326616, 6484.546081774419, 6490.837729056811, 6494.6752662367635, 5980.491658208927, 6087.665178415053, 6092.055731361646, 6094.360092190256, 6099.359567245376, 5868.530517558671, 5897.67562784051, 6082.908756368746, 6120.204373431426, 6195.19462469876, 5944.4352786485215, 5951.851203719769, 5952.880942381042, 5958.165856779968, 5993.160957465871, 5877.882442351214, 5902.4097590240635, 5903.085021821625, 5943.940560594512, 5955.315138759098, 5696.088625819849, 5740.1963725077685, 5823.155642431739, 5895.027320492242, 5895.64626122434, 5952.255968003999, 5966.76132954677, 5987.88024239504, 6003.0, 6004.609700369462, 5609.298837645294, 5648.802291919763, 5731.054376028075, 5819.127130930395, 5840.795572154954], "value": 5640.873561217951, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.8148299959012, 752.044902973224, 768.8462307538493, 868.2489646455817, 884.8849649545558, 30374.69625303749, 43277.35083973743, 44056.559434405644, 44091.338629920574, 44092.0, 44887.77556112223, 45028.774856125674, 45042.32436513455, 45107.32339014918, 45130.0, 7631.580263085528, 7778.64996075178, 8995.145461181177, 9144.725658230263, 9153.359264851462, 10400.167986561057, 10590.2057345699, 10618.150547956184, 10623.734406639818, 10625.521851516662, 10735.463226838667, 10804.18968577356, 10804.945975270113, 10808.351498910051, 10811.78376432471, 10604.78790424195, 10681.198910081775, 10734.516946737338, 10740.08709259714, 10740.24818262722, 10634.202434817342, 10698.037176654061, 10704.571817127306, 10719.19606029551, 10724.088452481548, 41378.0, 41473.377899331535, 41570.37644435336, 41571.376429353586, 41643.37534936978, 3789.6399842014966, 3832.1760821423527, 3837.961620383788, 3840.9423858642144, 3900.921981560353, 29867.701322986708, 29879.253018674488, 30121.698783012107, 30409.0, 30577.541336879964, 4163.39630753539, 4299.935500967488, 4304.827806887721, 4307.246231909435, 4371.23503386906, 244605.0, 246929.0, 247067.0, 247428.0, 247470.0, 52905.73547132317, 52982.205266921024, 53027.0, 53032.46967530275, 53064.204036939474, 54902.17646735302, 54992.45007549873, 55120.44879551233, 55136.44863551393, 55153.0, 15525.922370388076, 16335.18324083791, 16339.754903676454, 16363.590910227102, 16384.344626214817, 12837.743245135232, 13044.739105217843, 14114.153150810778, 14601.269936503133, 14603.19682417456, 12834.229946203257, 12852.48590056406, 12932.224066556037, 12935.482580696855, 13040.282784446947, 5492.148716948879, 5514.283143191359, 5612.298462692163, 5618.775248990077, 5638.238837756932, 993.7614972406715, 1016.6797458800602, 1020.7142000239945, 1035.9171266298679, 1056.0, 26164.607530887053, 27455.86272068627, 27619.585706214424, 27896.721032789817, 28179.295517612223, 1076.2742211435245, 1093.0981939899614, 1098.0940723902813, 1119.5801574409597, 1128.5260190719753, 14059.367328470244, 15010.642656566934, 15063.0, 15117.470888518848, 15358.385664573292, 260305.0, 261474.0, 261553.0, 261583.0, 261725.0, 51181.0, 51209.23186152211, 51314.23028654573, 51343.0, 51397.22904156441, 1488.7543555313468, 1509.8263699674458, 1536.7925330080295, 1543.4983630320191, 1561.4769052367358, 25033.249002529952, 26563.33591660224, 26580.601290980263, 26583.867080664473, 26723.599146013214, 39541.80229098836, 46069.30896036629, 46737.29894051524, 50411.49588504068, 50543.0, 301624.0, 302158.0, 302403.0, 302570.0, 302963.0, 24806.50386992286, 25140.0, 27831.44337113206, 29295.707042929298, 29488.11535653934, 38485.615143848205, 38765.41851872168, 38947.415788763756, 39011.41482877816, 39156.60843391644, 29817.25456863596, 29824.0, 30176.396472069995, 30288.394232115676, 30451.2387190322, 29381.85309073441, 29540.556891647073, 29611.25971850635, 29909.850450747606, 30074.849625752606, 34065.48901766525, 34513.30973380568, 35149.64850351464, 35207.64792352044, 35256.29487410289, 28495.572566411935, 28555.857220713762, 28929.132126036253, 28995.565066523595, 29342.559861601665, 23169.768302316763, 23946.64080038766, 24076.277711668678, 24128.396790080387, 24287.392815179763], "value": 18361.939992822994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.2366533387241, 468.59934755783814, 475.5957436179248, 485.3399376847487, 493.393126454461, 19610.31363902262, 21420.57158856822, 21438.356849294527, 21728.3481495555, 21757.99999999998, 23012.424689382733, 23048.769512304872, 23150.3054908353, 23323.0, 23448.413789655267, 6393.584417012891, 6474.769793739198, 6613.206415230179, 6625.17185351831, 6704.698288577026, 6574.539782215247, 6676.365745254193, 6686.933130668704, 6997.370236678698, 7267.709291628328, 7431.851362972738, 7432.108147022365, 7435.591042492661, 7437.739679111232, 7441.516301440389, 7320.707171713152, 7477.962610186914, 7519.360854327416, 7519.548827070366, 7521.887171692429, 7163.424046709715, 7329.377002954755, 7474.813129671747, 7531.585762783045, 7541.24587541247, 24941.750582494307, 24995.62506562403, 25202.495950080895, 25364.112256071134, 25598.104066357588, 3143.5441860930036, 3233.87064517419, 3290.3090351026312, 3327.184839714261, 3336.9989003299156, 15937.840621593752, 16007.519774406786, 16330.0, 16406.753898691528, 16432.096234707085, 2211.901997558879, 2251.223327951854, 2254.2673631069806, 2288.4622113803243, 2297.4715815362392, 116089.0, 119942.40028799887, 120547.397263014, 120576.0, 120788.39605801915, 18021.369252076114, 18153.27386904509, 18155.818441815678, 18197.818021819876, 18248.908755456137, 31901.680983190334, 31909.840450797597, 31920.201994950312, 32014.83992580022, 32039.359212815612, 9898.307118501636, 10029.949850250701, 10085.29402941787, 10101.797964040825, 10206.642767503101, 8360.623771930277, 8361.874571881426, 8457.238848503664, 8594.527300998505, 8641.481511109354, 9044.638214471348, 9313.487758173365, 9484.95257523708, 9503.144716975505, 9652.75868103303, 3638.8362523686496, 3658.7804731716183, 3831.1762970961377, 3831.214601006787, 3861.4400911867624, 982.513655740413, 982.7493989032894, 985.8028394321021, 988.17981075707, 1000.6147633161286, 21065.473363166042, 21320.78679213219, 21538.676919846213, 21599.075529809612, 21605.243816466347, 997.9351342162736, 998.9350692204983, 1003.9849402258972, 1017.811704834608, 1020.8877023527406, 11030.558777648805, 11624.128190385754, 11713.0, 11788.823167652492, 11901.750316216672, 677964.0, 680096.8474652442, 700344.0, 703269.0, 708648.654053832, 106118.0, 108226.0, 109407.0, 109634.0, 110375.0, 1201.8798120188087, 1223.6390264871884, 1230.3233221728015, 1236.2706003458004, 1255.5982085732478, 17440.651186975934, 18063.367782128087, 18188.181531831364, 18458.538536536693, 18757.155927983, 48254.75872620755, 48359.5164048355, 48990.26514602355, 49133.0, 49336.0, 62931.37068629438, 63017.68491157515, 63935.68032159809, 65064.0, 66098.0, 11832.881671183522, 12319.0, 12371.876281237073, 12477.438515266653, 12726.136619260593, 13333.933330333286, 13708.794368084686, 14044.227567483478, 14115.364808583843, 14284.928575357057, 6340.936590634219, 11866.169368144147, 12284.877151228373, 12321.938390308349, 12428.56500022531, 11290.209685321948, 11479.770404592029, 11480.368569745828, 11671.941640291745, 11673.649790506299, 11373.488193031499, 11478.770424591294, 11685.591004314809, 12656.17734847232, 12889.0, 11352.772944541228, 11453.854489705041, 11580.826287605862, 11623.941880290544, 11694.88305116938, 11152.275102118336, 11296.717582060513, 11327.15046371542, 11580.6525804226, 11653.708657283636], "value": 11434.507730658801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.7829825526164, 1584.9683006339874, 1602.4471557312731, 1607.7749115123884, 1608.0592853180888, 9219.262459003281, 10904.727381815448, 11249.718757031069, 11659.475323610437, 11681.182317237797, 11346.886531134685, 11400.714982125452, 11427.828582571268, 11447.656570302905, 11453.083753299716, 1982.851286153537, 2051.989740051305, 2390.091765129253, 2428.344347026302, 2429.769171928664, 2333.684952531412, 2416.9637455438183, 2423.854568725882, 2424.539337525873, 2439.7926176275037, 2375.1449478187837, 2392.605220138675, 2398.0, 2400.3399065257017, 2404.903803847853, 2353.846999944998, 2373.501564671421, 2378.9881050594636, 2379.0840526397287, 2381.2856143156982, 2186.1364760919505, 2225.977740222593, 2228.955420891573, 2229.7547269800307, 2230.0856648773924, 11450.65648030552, 11530.192886497949, 11538.826917596243, 11559.711007224803, 11569.074474042056, 5362.195670649395, 5511.917321240185, 5522.144067669517, 5529.861753456155, 5533.22534845122, 3178.7457003439667, 3270.1661076425357, 3418.5555877735983, 3431.0564594736397, 3439.07145070832, 1304.4456106154846, 1309.8624644412296, 1313.3958379145604, 1321.055445356565, 1378.2488543743684, 17175.742363864552, 17199.398021069326, 17251.0, 17282.30870765168, 17286.13569321529, 4472.530384309665, 4648.86053418398, 4745.738984355825, 4801.351817504662, 4815.398075240595, 13906.58280251594, 13920.164790112625, 13930.164190148624, 13935.790963135561, 13944.372503237175, 5758.309002919593, 5764.308283005982, 5766.711664416763, 5770.0, 5782.045962416154, 5125.820596279187, 5153.072446959583, 5199.038177937094, 5205.427402985669, 5269.235960785664, 3975.3043217436834, 4064.9390259146135, 4081.89795255121, 4128.318827393507, 4153.54311025787, 1033.1992705653029, 1041.6614600254948, 1057.5293994172519, 1058.0371861605902, 1058.523664351045, 1000.1348833259324, 1005.4470041477158, 1015.8323876560432, 1017.7608262058559, 1022.9335093218917, 6289.811305660837, 6425.228972523327, 6523.282438931714, 6727.798166055026, 6807.115075040312, 1042.61944390297, 1046.0899017854515, 1066.2696053203426, 1072.5120070367964, 1110.3781882146025, 4266.4240327556, 4324.308110702273, 4330.242207613718, 4337.349397590387, 4346.500152482505, 14424.134551926918, 15014.24928753558, 15079.623009424851, 15117.54647360581, 15133.772993405108, 14620.0, 14700.77948830747, 14740.852591474162, 14849.851501484847, 15009.324580393908, 10463.84304235437, 10476.952304769615, 10599.099076578423, 10627.681169564925, 10656.62701805433, 6593.208814942238, 6629.7679581214425, 6639.0, 6640.435562977201, 6701.765438209639, 12475.376231188586, 12504.499820007099, 12654.87345126537, 12761.106722529705, 12836.358182090671, 11122.88877111251, 13000.479980800663, 13034.0, 13038.934805325913, 13154.34228288601, 7843.333316667919, 8617.870731938901, 8747.60635771379, 8765.298776098025, 8781.780455488664, 10726.302790318605, 10922.399268040333, 10984.835227471754, 11008.119350452105, 11030.448477575928, 8974.596143173703, 9042.502662353634, 9059.547022648972, 9168.404053736485, 9402.247820174232, 8986.32602554798, 9041.231495322709, 9065.184133428023, 9128.49793261377, 9320.627174912928, 7895.131535531202, 8007.639656215601, 8035.196480351918, 8039.799005024921, 8042.195780422145, 6273.278572964167, 6274.403931626488, 6324.272708638566, 6326.683665816783, 6369.745210191726, 6003.7898673546215, 6008.459238668541, 6049.3950604939155, 6117.296510901126, 6171.259448866164], "value": 5274.151690279975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.5616246778417, 416.58549743005716, 433.23101494846645, 449.96175325097374, 449.9752513611751, 5169.948300516994, 5258.44786297439, 5262.500312351641, 5264.8157314494, 5339.759710813013, 5080.288759573663, 5121.257417674435, 5133.281340612317, 5176.602317374307, 5261.973690131554, 4337.978310108445, 4346.36977638243, 4347.4131941841315, 4359.367891655708, 4371.737695738266, 4361.629261512775, 4396.978015109936, 4428.291473364262, 4437.290033594625, 4451.332300154971, 4247.343536020939, 4313.590208930146, 4320.28715261983, 4373.300271956487, 4441.422615060038, 4352.759463552904, 4357.2592659248, 4358.65130789536, 4373.747520027188, 4379.75911324877, 4094.9181016379507, 4120.196561670476, 4144.772037537934, 4171.0615111599955, 4173.770442625654, 4984.850454486371, 5037.697738135724, 5082.466341034212, 5207.583393328525, 5264.368275806889, 5053.84838454847, 5072.797088116507, 5096.541311281965, 5116.488351164891, 5126.000859664734, 5494.862628434281, 5505.889882202374, 5533.972330138324, 5535.086710692731, 5550.500454959033, 4490.7530085845265, 4577.29051996941, 4598.977005114953, 4601.585857272828, 4606.078784243131, 5437.265969094161, 5529.474699903543, 5534.616345913521, 5700.745835916092, 5708.1723150143, 4655.928111990132, 4659.95340046602, 4666.953330466652, 4716.9056618867435, 4724.0, 4578.374676989621, 4660.464046634613, 4787.42550893888, 4790.664653474223, 4800.399950006249, 4963.851084467472, 5010.69935803853, 5062.519060689228, 5066.290719299301, 5106.770195341219, 5467.261919640877, 5467.4532546745, 5499.560035197174, 5516.200150978086, 5521.779128834802, 1571.8192407873012, 1634.9671359627957, 1656.4119737493018, 1662.8420300071473, 1665.7923005820683, 1266.5060626355728, 1291.179436993834, 1292.7414517096431, 1293.275765571283, 1310.7247478029722, 5418.593605479605, 5421.864453388618, 5447.0, 5452.182172674051, 5470.343558772973, 5650.943490565124, 5656.208130861683, 5658.830235092953, 5686.402927692616, 5692.345380281238, 5269.894602107936, 5381.811636592701, 5382.973085134549, 5444.972775136099, 5471.23402723619, 2941.8232706916974, 2954.9214536693953, 2960.319126600894, 2965.383865793148, 2969.7624190064744, 5502.669839809624, 5822.767089316465, 5836.474717275466, 5850.765969361263, 5884.588078834526, 5761.769529218868, 5801.7389217485315, 5807.738651760681, 5826.950269562698, 5836.358000619928, 5831.571265040007, 5851.912221316598, 5890.823275301748, 5909.527237820964, 5959.553033522503, 5319.823938208488, 5572.3034620672415, 5613.214150018919, 5641.0, 5658.9151162733415, 5316.548093411949, 5410.918836217381, 5446.21029950647, 5552.69460179694, 5588.6646801192055, 5818.0, 5838.883222335445, 5845.3570107289015, 5882.49998750111, 5897.498712609477, 4596.701214421052, 4719.0, 4735.976320118378, 4747.335373047707, 4792.808287668454, 5578.665280083208, 5616.522595579421, 5648.661080335193, 5661.0752344203165, 5690.2887139107615, 5452.482014208644, 5494.313210848644, 5522.978249023783, 5546.278983732089, 5563.193336966198, 5276.894462110813, 5348.625596208383, 5392.299001129828, 5449.64577302474, 5465.480779325958, 5387.407385187548, 5391.514763671288, 5423.484768946943, 5483.629092726818, 5517.641353312022, 5396.190571414162, 5456.399796022513, 5491.258680078298, 5538.529225015757, 5543.334799824047, 5152.613553983541, 5347.812826551052, 5350.357957045179, 5386.892262154814, 5464.617476776588], "value": 4428.356144366492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [847.8134810341727, 857.2798848966867, 910.1126401758285, 920.6685593186454, 929.7350255177275, 17373.7393939091, 18132.72800907986, 18458.815411845877, 18711.0, 18834.246630134778, 16472.34110635576, 16781.41265055723, 16829.66340673186, 16926.74609880853, 16937.745933811002, 875.7066382761783, 880.0671288434264, 903.6114470777563, 906.075802681268, 908.3323757038571, 916.1479823763897, 916.2761418479394, 919.2691810011057, 919.3564504846601, 919.6413398774483, 959.4435227567993, 960.5149399553251, 960.9087136722035, 961.2838435365662, 961.4904100826573, 947.0245646983584, 947.9004704506066, 948.2461443152711, 948.2603569215988, 948.4593781544556, 704.9118860142482, 704.9612271325075, 705.5590256089944, 705.7459314646723, 705.8941158826166, 17056.82943170565, 17113.144342782813, 17206.311747530082, 17532.561685957953, 17720.468385948443, 15980.0, 16036.75944860828, 16293.59266018347, 16577.91711041449, 16693.582660433465, 13560.186388816703, 13849.307534623329, 13991.30043497821, 14294.213818239992, 14786.482473113389, 4083.3670781028986, 4083.7958102094776, 4092.9590704092875, 4149.7095203335775, 4232.746035237865, 86668.56665716694, 87555.3629166857, 88001.55999219963, 88357.0, 90233.09766902313, 12301.876981230072, 12310.630681079581, 12346.81479777804, 12382.690432739073, 12484.375781210903, 24082.87958560231, 24192.637110443357, 24205.152819651226, 24212.515749684906, 24242.393940151636, 8304.792380190424, 8379.413441059189, 8493.830123397498, 8575.742727718178, 8590.355723320776, 4810.7113573185725, 4858.368412106474, 4863.392075990501, 4873.269009648581, 4951.084049450863, 3673.540807399075, 3716.1267102230954, 3745.3632882410056, 3771.132639492932, 3786.3752480840353, 574.4858351775138, 584.0713265907228, 590.4538302070654, 591.2432086928738, 592.3365830270125, 798.5767543201996, 815.5962798414822, 816.420341557489, 833.4540875726409, 855.6149732620346, 11011.28426652281, 11282.830757538644, 11305.773884522263, 11360.715982100513, 11803.468843902045, 860.802223987294, 863.9611217495228, 871.8648609465542, 877.2105105405186, 878.7978764883985, 5490.505854473116, 5506.284183056177, 5529.944700553024, 5594.216809646571, 5606.243157173811, 65554.0, 68615.0, 75393.62303188449, 75734.0, 80410.0, 63247.0, 64247.0, 65923.34076659172, 66643.33356666469, 67612.32387676158, 62330.37669623246, 62650.686746565974, 63528.999999999076, 63531.0, 63565.68217158884, 11443.542258309908, 11625.302481851117, 11726.94136529312, 11977.820332695192, 11980.820287695866, 41907.580924191585, 41941.58058419377, 41947.58052419559, 42576.0, 43294.13411731685, 46181.0, 46708.53291467042, 46841.0, 47255.527444725114, 48819.75590122027, 13021.283829389475, 13568.728625427633, 13612.982180345552, 13790.931045344709, 14000.509982150576, 16180.352785888432, 16409.91795041017, 16618.41835535799, 16664.916675416545, 16864.915675421544, 15390.230488475756, 15445.84554154444, 15501.069935803887, 15620.765688514453, 15809.0, 15349.0, 15501.224938752792, 15715.292811823723, 15866.286017129027, 16230.756538652166, 15096.866395830364, 15349.769753453484, 15670.608234794221, 15689.29398177062, 15953.601159971093, 8786.042937131399, 8891.866622000545, 8924.152205540464, 9076.500792456478, 9157.809484766938, 11203.495842686936, 11487.425628718698, 11664.125190610568, 13340.933295333462, 13360.398782054637], "value": 7860.54905724788, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 111.0, 111.0, 111.0, 110.0, 112.0, 111.0, 111.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 117.0, 155.0, 117.0, 117.0, 115.0, 114.0, 135.0, 116.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 162.0, 162.0, 160.0, 159.0, 160.0, 161.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 112.0, 76.0, 75.0, 76.0, 75.0, 90.0, 75.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 117.0, 82.0, 81.0, 82.0, 98.0, 82.0, 82.0, 102.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 53.0, 53.0, 53.0, 55.0, 53.0, 55.0, 53.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 54.0, 54.0, 53.0, 53.0, 53.0, 54.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 75.0, 74.0, 80.0, 78.0, 75.0, 79.0, 79.0, 80.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 125.0, 128.0, 123.0, 119.0, 133.0, 119.0, 160.0, 140.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 158.0, 162.0, 162.0, 156.0, 164.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 59.0, 60.0, 60.0, 64.0, 59.0, 61.0, 58.0, 62.0], "value": 60.9, "unit": "score"}]}]} 09:32:51 INFO - Return code: 0 09:32:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:32:51 ERROR - # TBPL SUCCESS # 09:32:51 INFO - Running post-action listener: _resource_record_post_action 09:32:51 INFO - [mozharness: 2017-04-14 16:32:51.518193Z] Finished run-tests step (success) 09:32:51 INFO - Running post-run listener: _resource_record_post_run 09:32:51 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 7778304; Write bytes: 376594432; Read time: 1764; Write time: 288376 09:32:51 INFO - TinderboxPrint: CPU usage
13.1% 09:32:51 INFO - TinderboxPrint: I/O read bytes / time
7,778,304 / 1,764 09:32:51 INFO - TinderboxPrint: I/O write bytes / time
376,594,432 / 288,376 09:32:51 INFO - TinderboxPrint: CPU idle
8,285.8 (86.9%) 09:32:51 INFO - TinderboxPrint: CPU user
1,202.2 (12.6%) 09:32:51 INFO - TinderboxPrint: Swap in / out
0 / 0 09:32:51 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 120504320; Read time: 80; Write time: 120356 09:32:51 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 6029312; Write bytes: 255488000; Read time: 1632; Write time: 167980 09:32:52 INFO - Running post-run listener: _upload_blobber_files 09:32:52 INFO - Blob upload gear active. 09:32:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:32:52 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 09:32:52 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:32:52 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:32:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:32:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:32:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:32:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:53 INFO - (blobuploader) - INFO - Done attempting. 09:32:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:32:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:32:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:56 INFO - (blobuploader) - INFO - Done attempting. 09:32:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:32:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:32:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:57 INFO - (blobuploader) - INFO - Done attempting. 09:32:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:32:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:32:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:58 INFO - (blobuploader) - INFO - Done attempting. 09:32:58 INFO - (blobuploader) - INFO - Iteration through files over. 09:32:58 INFO - Return code: 0 09:32:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:32:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:32:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dd8419764cfe2c7f6804b1adeb55791024fc86b8d58c3323a568538b8af9711ba72c81e856e721d5f522cdd0b588c4e215ee978690ac5498bd2cd679249656e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b2fe41c020eb6d6a8047730d7827db5202cd95f5fd66dc676dab695a8a0695c42ed624644293bc84d4fa9619d7f94427cd557352b0d05d2b411a4ed40c0e09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/981d0b3a9f04ec9319334f63da9be6f550737818c00dc9bdc5501ef894f578da9d335b75b36b80ff0740bf5e8befedb49a9a92c0338318d498708c29043b3a3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29352e9e5dedc1dd69ba7ad3d5edf44a1580a10bd74c70a87dba39fd3e6a581984095f3133db8d4477817df6776035db764122ef9592a67148f26dcd49fff17e"} 09:32:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:32:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:32:58 INFO - Contents: 09:32:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dd8419764cfe2c7f6804b1adeb55791024fc86b8d58c3323a568538b8af9711ba72c81e856e721d5f522cdd0b588c4e215ee978690ac5498bd2cd679249656e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b2fe41c020eb6d6a8047730d7827db5202cd95f5fd66dc676dab695a8a0695c42ed624644293bc84d4fa9619d7f94427cd557352b0d05d2b411a4ed40c0e09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/981d0b3a9f04ec9319334f63da9be6f550737818c00dc9bdc5501ef894f578da9d335b75b36b80ff0740bf5e8befedb49a9a92c0338318d498708c29043b3a3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29352e9e5dedc1dd69ba7ad3d5edf44a1580a10bd74c70a87dba39fd3e6a581984095f3133db8d4477817df6776035db764122ef9592a67148f26dcd49fff17e"} 09:32:58 INFO - Running post-run listener: copy_logs_to_upload_dir 09:32:58 INFO - Copying logs to upload dir... 09:32:58 INFO - mkdir: /builds/slave/test/build/upload/logs 09:32:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.002982 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-04-14 09:32:58.588178) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 09:32:58.591594) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274 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/sha512/6dd8419764cfe2c7f6804b1adeb55791024fc86b8d58c3323a568538b8af9711ba72c81e856e721d5f522cdd0b588c4e215ee978690ac5498bd2cd679249656e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b2fe41c020eb6d6a8047730d7827db5202cd95f5fd66dc676dab695a8a0695c42ed624644293bc84d4fa9619d7f94427cd557352b0d05d2b411a4ed40c0e09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/981d0b3a9f04ec9319334f63da9be6f550737818c00dc9bdc5501ef894f578da9d335b75b36b80ff0740bf5e8befedb49a9a92c0338318d498708c29043b3a3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29352e9e5dedc1dd69ba7ad3d5edf44a1580a10bd74c70a87dba39fd3e6a581984095f3133db8d4477817df6776035db764122ef9592a67148f26dcd49fff17e"} build_url:https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011243 build_url: 'https://queue.taskcluster.net/v1/task/VHWJsPokQVuGOv2cPQrzyg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dd8419764cfe2c7f6804b1adeb55791024fc86b8d58c3323a568538b8af9711ba72c81e856e721d5f522cdd0b588c4e215ee978690ac5498bd2cd679249656e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b2fe41c020eb6d6a8047730d7827db5202cd95f5fd66dc676dab695a8a0695c42ed624644293bc84d4fa9619d7f94427cd557352b0d05d2b411a4ed40c0e09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/981d0b3a9f04ec9319334f63da9be6f550737818c00dc9bdc5501ef894f578da9d335b75b36b80ff0740bf5e8befedb49a9a92c0338318d498708c29043b3a3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29352e9e5dedc1dd69ba7ad3d5edf44a1580a10bd74c70a87dba39fd3e6a581984095f3133db8d4477817df6776035db764122ef9592a67148f26dcd49fff17e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 09:32:58.618076) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:32:58.618405) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1492186314.765168-2084335274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004339 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:32:58.700027) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 09:32:58.700385) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 09:32:58.700669) ========= ========= Total master_lag: 0.16 =========