builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-074 starttime: 1491491257.26 results: success (0) revision: e03c56f3a839d1575e87dbd782638ad4894429e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.264343) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.264814) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.265094) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.279585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.280000) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-06 08:07:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-04-06 08:07:37 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.386024) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.386814) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021180 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:37.438909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 08:07:37.439225) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e03c56f3a839d1575e87dbd782638ad4894429e8 --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 e03c56f3a839d1575e87dbd782638ad4894429e8 --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=23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-06 08:07:37,848 truncating revision to first 12 chars 2017-04-06 08:07:37,848 Setting DEBUG logging. 2017-04-06 08:07:37,848 attempt 1/10 2017-04-06 08:07:37,848 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e03c56f3a839?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 08:07:39,105 unpacking tar archive at: mozilla-aurora-e03c56f3a839/testing/mozharness/ program finished with exit code 0 elapsedTime=1.527442 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 08:07:38.980295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:38.980664) ========= script_repo_revision: e03c56f3a839d1575e87dbd782638ad4894429e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:38.981114) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:38.981391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 08:07:38.992212) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-04-06 08:07:38.992498) ========= /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=23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:07:39 INFO - MultiFileLogger online at 20170406 08:07:39 in /builds/slave/test 08:07:39 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 08:07:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:07:39 INFO - {'append_to_log': False, 08:07:39 INFO - 'base_work_dir': '/builds/slave/test', 08:07:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:07:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:07:39 INFO - 'branch': 'Mozilla-Aurora', 08:07:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:07:39 INFO - 'config_files': ('talos/linux_config.py',), 08:07:39 INFO - 'default_actions': ('clobber', 08:07:39 INFO - 'read-buildbot-config', 08:07:39 INFO - 'download-and-extract', 08:07:39 INFO - 'populate-webroot', 08:07:39 INFO - 'create-virtualenv', 08:07:39 INFO - 'install', 08:07:39 INFO - 'run-tests'), 08:07:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:07:39 INFO - 'download_minidump_stackwalk': True, 08:07:39 INFO - 'download_symbols': 'ondemand', 08:07:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:07:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:07:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:07:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:07:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:07:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:07:39 INFO - 'gecko_profile': False, 08:07:39 INFO - 'gecko_profile_interval': 0, 08:07:39 INFO - 'installer_path': 'installer.exe', 08:07:39 INFO - 'log_level': 'info', 08:07:39 INFO - 'log_name': 'talos', 08:07:39 INFO - 'log_to_console': True, 08:07:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:07:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:07:39 INFO - 'opt_config_files': (), 08:07:39 INFO - 'pip_index': False, 08:07:39 INFO - 'suite': 'dromaeojs', 08:07:39 INFO - 'system_bits': '32', 08:07:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:07:39 INFO - 'title': 'talos-linux64-ix-074', 08:07:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:07:39 INFO - 'use_talos_json': True, 08:07:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:07:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:07:39 INFO - 'work_dir': 'build'} 08:07:39 INFO - [mozharness: 2017-04-06 15:07:39.498319Z] Running clobber step. 08:07:39 INFO - Running pre-action listener: _resource_record_pre_action 08:07:39 INFO - Running main action method: clobber 08:07:39 INFO - rmtree: /builds/slave/test/build 08:07:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:07:40 INFO - Running post-action listener: _resource_record_post_action 08:07:40 INFO - [mozharness: 2017-04-06 15:07:40.418133Z] Finished clobber step (success) 08:07:40 INFO - [mozharness: 2017-04-06 15:07:40.418233Z] Running read-buildbot-config step. 08:07:40 INFO - Running pre-action listener: _resource_record_pre_action 08:07:40 INFO - Running main action method: read_buildbot_config 08:07:40 INFO - Using buildbot properties: 08:07:40 INFO - { 08:07:40 INFO - "project": "", 08:07:40 INFO - "product": "firefox", 08:07:40 INFO - "who": "bhearsum@mozilla.com", 08:07:40 INFO - "installer_path": "public/build/target.tar.bz2", 08:07:40 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:07:40 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:07:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:07:40 INFO - "stage_platform": "linux64-pgo", 08:07:40 INFO - "basedir": "/builds/slave/test", 08:07:40 INFO - "buildnumber": 1141, 08:07:40 INFO - "platform": "ubuntu64_hw", 08:07:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:07:40 INFO - "slavebuilddir": "test", 08:07:40 INFO - "taskId": "FYZ2qKr_QI6pD8Y9iWnh4g", 08:07:40 INFO - "branch": "mozilla-aurora", 08:07:40 INFO - "script_repo_revision": "production", 08:07:40 INFO - "revision": "e03c56f3a839d1575e87dbd782638ad4894429e8", 08:07:40 INFO - "slavename": "talos-linux64-ix-074", 08:07:40 INFO - "repo_path": "releases/mozilla-aurora" 08:07:40 INFO - } 08:07:40 INFO - Finding installer, test and symbols from parent task. 08:07:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FYZ2qKr_QI6pD8Y9iWnh4g'}, attempt #1 08:07:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FYZ2qKr_QI6pD8Y9iWnh4g'}, attempt #1 08:07:41 INFO - Task dependencies: CJsdSQTZQAiLYTiwgCyCXg 08:07:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg'}, attempt #1 08:07:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2 08:07:41 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/test_packages.json 08:07:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.crashreporter-symbols.zip 08:07:41 INFO - Running post-action listener: _resource_record_post_action 08:07:41 INFO - [mozharness: 2017-04-06 15:07:41.761759Z] Finished read-buildbot-config step (success) 08:07:41 INFO - [mozharness: 2017-04-06 15:07:41.762021Z] Running download-and-extract step. 08:07:41 INFO - Running pre-action listener: _resource_record_pre_action 08:07:41 INFO - Running main action method: download_and_extract 08:07:41 INFO - mkdir: /builds/slave/test/build/tests 08:07: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')]} 08:07:41 INFO - trying https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.test_packages.json 08:07:41 INFO - Downloading https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:07:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:07:42 INFO - Downloaded 896 bytes. 08:07:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:07:42 INFO - Using the following test package requirements: 08:07:42 INFO - {u'common': [u'target.common.tests.zip'], 08:07:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:07:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:07:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:07:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:07:42 INFO - u'mozbase': [u'target.common.tests.zip'], 08:07:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:07:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:07:42 INFO - u'web-platform': [u'target.common.tests.zip', 08:07:42 INFO - u'target.web-platform.tests.zip'], 08:07:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:07:42 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:07:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.common.tests.zip 08:07:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:07:42 INFO - Fetch https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.common.tests.zip into memory 08:07:44 INFO - Expected file size: 59541873 08:07:44 INFO - Obtained file size: 59541873 08:07:54 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:07:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.common.tests.zip 08:07:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:07:54 INFO - Fetch https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.common.tests.zip into memory 08:07:55 INFO - Expected file size: 59541873 08:07:55 INFO - Obtained file size: 59541873 08:08:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.talos.tests.zip 08:08:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:08:05 INFO - Fetch https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.talos.tests.zip into memory 08:08:07 INFO - Expected file size: 14404438 08:08:07 INFO - Obtained file size: 14404438 08:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:08 INFO - trying https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2 08:08:08 INFO - Downloading https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:08:10 INFO - Downloaded 66745894 bytes. 08:08:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2 08:08:10 INFO - mkdir: /builds/slave/test/properties 08:08:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:08:10 INFO - Writing to file /builds/slave/test/properties/build_url 08:08:10 INFO - Contents: 08:08:10 INFO - build_url:https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2 08:08:10 INFO - Running post-action listener: _resource_record_post_action 08:08:10 INFO - Running post-action listener: set_extra_try_arguments 08:08:10 INFO - [mozharness: 2017-04-06 15:08:10.250076Z] Finished download-and-extract step (success) 08:08:10 INFO - [mozharness: 2017-04-06 15:08:10.250172Z] Running populate-webroot step. 08:08:10 INFO - Running pre-action listener: _resource_record_pre_action 08:08:10 INFO - Running main action method: populate_webroot 08:08:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:10 INFO - '--tppagecycles', 08:08:10 INFO - '7'], 08:08:10 INFO - 'tests': ['tsvgm']}}, 08:08:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:08:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:08:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:08:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:08:10 INFO - 'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:08:10 INFO - 'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['damp', 'tps']}, 08:08:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:08:10 INFO - 'tests': ['damp', 'tps']}, 08:08:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['dromaeo_dom']}, 08:08:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['basic_compositor_video']}, 08:08:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:08:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['a11yr', 08:08:10 INFO - 'ts_paint', 08:08:10 INFO - 'tpaint', 08:08:10 INFO - 'sessionrestore', 08:08:10 INFO - 'sessionrestore_no_auto_restore', 08:08:10 INFO - 'tabpaint']}, 08:08:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:08:10 INFO - 'ts_paint', 08:08:10 INFO - 'tpaint', 08:08:10 INFO - 'sessionrestore', 08:08:10 INFO - 'sessionrestore_no_auto_restore', 08:08:10 INFO - 'tabpaint']}, 08:08:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:08:10 INFO - 'ts_paint', 08:08:10 INFO - 'tpaint', 08:08:10 INFO - 'sessionrestore', 08:08:10 INFO - 'sessionrestore_no_auto_restore', 08:08:10 INFO - 'tabpaint']}, 08:08:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:08:10 INFO - 'ts_paint', 08:08:10 INFO - 'tpaint', 08:08:10 INFO - 'sessionrestore', 08:08:10 INFO - 'sessionrestore_no_auto_restore', 08:08:10 INFO - 'tabpaint']}, 08:08:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['a11yr', 08:08:10 INFO - 'ts_paint', 08:08:10 INFO - 'tpaint', 08:08:10 INFO - 'sessionrestore', 08:08:10 INFO - 'sessionrestore_no_auto_restore', 08:08:10 INFO - 'tabpaint']}, 08:08:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['a11yr', 08:08:10 INFO - 'ts_paint', 08:08:10 INFO - 'tpaint', 08:08:10 INFO - 'sessionrestore', 08:08:10 INFO - 'sessionrestore_no_auto_restore', 08:08:10 INFO - 'tabpaint']}, 08:08:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['tsvgx', 08:08:10 INFO - 'tsvgr_opacity', 08:08:10 INFO - 'tart', 08:08:10 INFO - 'tscrollx', 08:08:10 INFO - 'cart', 08:08:10 INFO - 'tsvg_static']}, 08:08:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:08:10 INFO - 'tsvgr_opacity', 08:08:10 INFO - 'tart', 08:08:10 INFO - 'tscrollx', 08:08:10 INFO - 'cart', 08:08:10 INFO - 'tsvg_static']}, 08:08:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:08:10 INFO - 'talos_options': ['--disable-e10s'], 08:08:10 INFO - 'tests': ['tp5o']}, 08:08:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:08:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:08:10 INFO - 'talos_options': ['--disable-e10s', 08:08:10 INFO - '--xperf_path', 08:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:10 INFO - 'tests': ['tp5n']}, 08:08:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:08:10 INFO - 'talos_options': ['--xperf_path', 08:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:10 INFO - 'tests': ['tp5n']}}, 08:08:10 INFO - 'talos.zip': {'path': '', 08:08:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:10 INFO - Running post-action listener: _resource_record_post_action 08:08:10 INFO - [mozharness: 2017-04-06 15:08:10.263264Z] Finished populate-webroot step (success) 08:08:10 INFO - [mozharness: 2017-04-06 15:08:10.263354Z] Running create-virtualenv step. 08:08:10 INFO - Running pre-action listener: _resource_record_pre_action 08:08:10 INFO - Running main action method: create_virtualenv 08:08:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:08:10 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 08:08:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:08:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:10 INFO - Using real prefix '/usr' 08:08:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:08:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:08:12 INFO - Installing pip.................done. 08:08:12 INFO - Return code: 0 08:08:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:08:13 INFO - Reading from file tmpfile_stdout 08:08:13 INFO - Output received: 08:08:13 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) 08:08:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:13 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:13 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 08:08:13 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 08:08:13 INFO - Using env: {'DISPLAY': ':0', 08:08:13 INFO - 'HOME': '/home/cltbld', 08:08:13 INFO - 'LANG': 'en_US.UTF-8', 08:08:13 INFO - 'LANGUAGE': 'en_US:en', 08:08:13 INFO - 'LOGNAME': 'cltbld', 08:08:13 INFO - 'MAIL': '/var/mail/cltbld', 08:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:13 INFO - 'MOZ_NO_REMOTE': '1', 08:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:13 INFO - 'NO_EM_RESTART': '1', 08:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:13 INFO - 'PWD': '/builds/slave/test', 08:08:13 INFO - 'SHELL': '/bin/bash', 08:08:13 INFO - 'SHLVL': '1', 08:08:13 INFO - 'TERM': 'linux', 08:08:13 INFO - 'TMOUT': '86400', 08:08:13 INFO - 'USER': 'cltbld', 08:08:13 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:13 INFO - '_': '/tools/buildbot/bin/python'} 08:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:13 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 08:08:13 INFO - Cleaning up... 08:08:13 INFO - Return code: 0 08:08:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:13 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:13 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 08:08:13 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 08:08:13 INFO - Using env: {'DISPLAY': ':0', 08:08:13 INFO - 'HOME': '/home/cltbld', 08:08:13 INFO - 'LANG': 'en_US.UTF-8', 08:08:13 INFO - 'LANGUAGE': 'en_US:en', 08:08:13 INFO - 'LOGNAME': 'cltbld', 08:08:13 INFO - 'MAIL': '/var/mail/cltbld', 08:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:13 INFO - 'MOZ_NO_REMOTE': '1', 08:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:13 INFO - 'NO_EM_RESTART': '1', 08:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:13 INFO - 'PWD': '/builds/slave/test', 08:08:13 INFO - 'SHELL': '/bin/bash', 08:08:13 INFO - 'SHLVL': '1', 08:08:13 INFO - 'TERM': 'linux', 08:08:13 INFO - 'TMOUT': '86400', 08:08:13 INFO - 'USER': 'cltbld', 08:08:13 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:13 INFO - '_': '/tools/buildbot/bin/python'} 08:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:13 INFO - Downloading/unpacking psutil>=3.1.1 08:08:13 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 08:08:13 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 08:08:13 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 08:08:13 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 08:08:13 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 08:08:13 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 08:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:08:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:15 INFO - Installing collected packages: psutil 08:08:15 INFO - Running setup.py install for psutil 08:08:15 INFO - building 'psutil._psutil_linux' extension 08:08:15 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 08:08:15 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 08:08:15 INFO - building 'psutil._psutil_posix' extension 08:08:15 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 08:08:16 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 08:08:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:16 INFO - Successfully installed psutil 08:08:16 INFO - Cleaning up... 08:08:16 INFO - Return code: 0 08:08:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:08:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:16 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:16 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 08:08:16 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 08:08:16 INFO - Using env: {'DISPLAY': ':0', 08:08:16 INFO - 'HOME': '/home/cltbld', 08:08:16 INFO - 'LANG': 'en_US.UTF-8', 08:08:16 INFO - 'LANGUAGE': 'en_US:en', 08:08:16 INFO - 'LOGNAME': 'cltbld', 08:08:16 INFO - 'MAIL': '/var/mail/cltbld', 08:08:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:16 INFO - 'MOZ_NO_REMOTE': '1', 08:08:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:16 INFO - 'NO_EM_RESTART': '1', 08:08:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:16 INFO - 'PWD': '/builds/slave/test', 08:08:16 INFO - 'SHELL': '/bin/bash', 08:08:16 INFO - 'SHLVL': '1', 08:08:16 INFO - 'TERM': 'linux', 08:08:16 INFO - 'TMOUT': '86400', 08:08:16 INFO - 'USER': 'cltbld', 08:08:16 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:16 INFO - '_': '/tools/buildbot/bin/python'} 08:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:08:16 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 08:08:16 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 08:08:16 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 08:08:16 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 08:08:16 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 08:08:16 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 08:08:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:08:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:08:18 INFO - Installing collected packages: mozsystemmonitor 08:08:18 INFO - Running setup.py install for mozsystemmonitor 08:08:18 INFO - Successfully installed mozsystemmonitor 08:08:18 INFO - Cleaning up... 08:08:18 INFO - Return code: 0 08:08:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:08:18 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')]} 08:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:18 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')]} 08:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:18 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:18 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 08:08:18 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 08:08:18 INFO - Using env: {'DISPLAY': ':0', 08:08:18 INFO - 'HOME': '/home/cltbld', 08:08:18 INFO - 'LANG': 'en_US.UTF-8', 08:08:18 INFO - 'LANGUAGE': 'en_US:en', 08:08:18 INFO - 'LOGNAME': 'cltbld', 08:08:18 INFO - 'MAIL': '/var/mail/cltbld', 08:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:18 INFO - 'MOZ_NO_REMOTE': '1', 08:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:18 INFO - 'NO_EM_RESTART': '1', 08:08:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:18 INFO - 'PWD': '/builds/slave/test', 08:08:18 INFO - 'SHELL': '/bin/bash', 08:08:18 INFO - 'SHLVL': '1', 08:08:18 INFO - 'TERM': 'linux', 08:08:18 INFO - 'TMOUT': '86400', 08:08:18 INFO - 'USER': 'cltbld', 08:08:18 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:18 INFO - '_': '/tools/buildbot/bin/python'} 08:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:18 INFO - Downloading/unpacking jsonschema==2.5.1 08:08:18 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 08:08:18 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 08:08:18 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 08:08:18 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 08:08:18 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 08:08:18 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 08:08:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:08:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:08:20 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 08:08:20 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 08:08:20 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 08:08:20 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 08:08:20 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 08:08:20 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 08:08:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:08:21 INFO - warning: no files found matching '*.txt' 08:08:21 INFO - no previously-included directories found matching 'build' 08:08:21 INFO - no previously-included directories found matching 'dist' 08:08:21 INFO - no previously-included directories found matching '.git*' 08:08:21 INFO - Installing collected packages: jsonschema, functools32 08:08:21 INFO - Running setup.py install for functools32 08:08:21 INFO - warning: no files found matching '*.txt' 08:08:21 INFO - no previously-included directories found matching 'build' 08:08:21 INFO - no previously-included directories found matching 'dist' 08:08:21 INFO - no previously-included directories found matching '.git*' 08:08:21 INFO - Successfully installed jsonschema functools32 08:08:21 INFO - Cleaning up... 08:08:21 INFO - Return code: 0 08:08:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:21 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:21 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 08:08:21 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 08:08:21 INFO - Using env: {'DISPLAY': ':0', 08:08:21 INFO - 'HOME': '/home/cltbld', 08:08:21 INFO - 'LANG': 'en_US.UTF-8', 08:08:21 INFO - 'LANGUAGE': 'en_US:en', 08:08:21 INFO - 'LOGNAME': 'cltbld', 08:08:21 INFO - 'MAIL': '/var/mail/cltbld', 08:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:21 INFO - 'MOZ_NO_REMOTE': '1', 08:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:21 INFO - 'NO_EM_RESTART': '1', 08:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:21 INFO - 'PWD': '/builds/slave/test', 08:08:21 INFO - 'SHELL': '/bin/bash', 08:08:21 INFO - 'SHLVL': '1', 08:08:21 INFO - 'TERM': 'linux', 08:08:21 INFO - 'TMOUT': '86400', 08:08:21 INFO - 'USER': 'cltbld', 08:08:21 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:21 INFO - '_': '/tools/buildbot/bin/python'} 08:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:08:21 INFO - Cleaning up... 08:08:21 INFO - Return code: 0 08:08:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:21 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:21 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 08:08:21 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 08:08:21 INFO - Using env: {'DISPLAY': ':0', 08:08:21 INFO - 'HOME': '/home/cltbld', 08:08:21 INFO - 'LANG': 'en_US.UTF-8', 08:08:21 INFO - 'LANGUAGE': 'en_US:en', 08:08:21 INFO - 'LOGNAME': 'cltbld', 08:08:21 INFO - 'MAIL': '/var/mail/cltbld', 08:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:21 INFO - 'MOZ_NO_REMOTE': '1', 08:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:21 INFO - 'NO_EM_RESTART': '1', 08:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:21 INFO - 'PWD': '/builds/slave/test', 08:08:21 INFO - 'SHELL': '/bin/bash', 08:08:21 INFO - 'SHLVL': '1', 08:08:21 INFO - 'TERM': 'linux', 08:08:21 INFO - 'TMOUT': '86400', 08:08:21 INFO - 'USER': 'cltbld', 08:08:21 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:21 INFO - '_': '/tools/buildbot/bin/python'} 08:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:21 INFO - Downloading/unpacking blobuploader==1.2.4 08:08:21 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 08:08:21 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 08:08:21 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 08:08:21 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 08:08:21 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 08:08:21 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 08:08:23 INFO - Downloading blobuploader-1.2.4.tar.gz 08:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:08:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:08:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 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 08:08:24 INFO - Downloading docopt-0.6.1.tar.gz 08:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:08:24 INFO - Installing collected packages: blobuploader, requests, docopt 08:08:24 INFO - Running setup.py install for blobuploader 08:08:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:08:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:08:24 INFO - Running setup.py install for requests 08:08:25 INFO - Running setup.py install for docopt 08:08:25 INFO - Successfully installed blobuploader requests docopt 08:08:25 INFO - Cleaning up... 08:08:25 INFO - Return code: 0 08:08:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:25 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:25 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 08:08:25 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 08:08:25 INFO - Using env: {'DISPLAY': ':0', 08:08:25 INFO - 'HOME': '/home/cltbld', 08:08:25 INFO - 'LANG': 'en_US.UTF-8', 08:08:25 INFO - 'LANGUAGE': 'en_US:en', 08:08:25 INFO - 'LOGNAME': 'cltbld', 08:08:25 INFO - 'MAIL': '/var/mail/cltbld', 08:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:25 INFO - 'MOZ_NO_REMOTE': '1', 08:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:25 INFO - 'NO_EM_RESTART': '1', 08:08:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:25 INFO - 'PWD': '/builds/slave/test', 08:08:25 INFO - 'SHELL': '/bin/bash', 08:08:25 INFO - 'SHLVL': '1', 08:08:25 INFO - 'TERM': 'linux', 08:08:25 INFO - 'TMOUT': '86400', 08:08:25 INFO - 'USER': 'cltbld', 08:08:25 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:25 INFO - '_': '/tools/buildbot/bin/python'} 08:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:25 INFO - Running setup.py (path:/tmp/pip-yUrYUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:25 INFO - Running setup.py (path:/tmp/pip-C8Pclz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:25 INFO - Running setup.py (path:/tmp/pip-Sf5oV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:25 INFO - Running setup.py (path:/tmp/pip-WZYZgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:25 INFO - Running setup.py (path:/tmp/pip-SLozc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:25 INFO - Running setup.py (path:/tmp/pip-sns5B6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:26 INFO - Running setup.py (path:/tmp/pip-_oxn_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:26 INFO - Running setup.py (path:/tmp/pip-1u6vik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:26 INFO - Running setup.py (path:/tmp/pip-AZz5Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:26 INFO - Running setup.py (path:/tmp/pip-e4LLrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:26 INFO - Running setup.py (path:/tmp/pip-ZaudX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:26 INFO - Running setup.py (path:/tmp/pip-W4WvNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:26 INFO - Running setup.py (path:/tmp/pip-Yh2xNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:26 INFO - Running setup.py (path:/tmp/pip-uGof6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:26 INFO - Running setup.py (path:/tmp/pip-OHL1yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:26 INFO - Running setup.py (path:/tmp/pip-m1tGga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:26 INFO - Running setup.py (path:/tmp/pip-1BGq7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:08:26 INFO - Running setup.py install for manifestparser 08:08:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:08:26 INFO - Running setup.py install for mozcrash 08:08:26 INFO - Running setup.py install for mozdebug 08:08:27 INFO - Running setup.py install for mozdevice 08:08:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Running setup.py install for mozfile 08:08:27 INFO - Running setup.py install for mozhttpd 08:08:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Running setup.py install for mozinfo 08:08:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Running setup.py install for mozInstall 08:08:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Running setup.py install for mozleak 08:08:27 INFO - Running setup.py install for mozlog 08:08:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Running setup.py install for moznetwork 08:08:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:08:27 INFO - Running setup.py install for mozprocess 08:08:28 INFO - Running setup.py install for mozprofile 08:08:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:08:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:08:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:08:28 INFO - Running setup.py install for mozrunner 08:08:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:08:28 INFO - Running setup.py install for mozscreenshot 08:08:28 INFO - Running setup.py install for moztest 08:08:28 INFO - Running setup.py install for mozversion 08:08:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:08:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:08:28 INFO - Cleaning up... 08:08:28 INFO - Return code: 0 08:08:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:28 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:28 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 08:08:28 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 08:08:28 INFO - Using env: {'DISPLAY': ':0', 08:08:28 INFO - 'HOME': '/home/cltbld', 08:08:28 INFO - 'LANG': 'en_US.UTF-8', 08:08:28 INFO - 'LANGUAGE': 'en_US:en', 08:08:28 INFO - 'LOGNAME': 'cltbld', 08:08:28 INFO - 'MAIL': '/var/mail/cltbld', 08:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:28 INFO - 'MOZ_NO_REMOTE': '1', 08:08:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:28 INFO - 'NO_EM_RESTART': '1', 08:08:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:28 INFO - 'PWD': '/builds/slave/test', 08:08:28 INFO - 'SHELL': '/bin/bash', 08:08:28 INFO - 'SHLVL': '1', 08:08:28 INFO - 'TERM': 'linux', 08:08:28 INFO - 'TMOUT': '86400', 08:08:28 INFO - 'USER': 'cltbld', 08:08:28 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:28 INFO - '_': '/tools/buildbot/bin/python'} 08:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:28 INFO - Running setup.py (path:/tmp/pip-Yj9H2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:28 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)) 08:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:28 INFO - Running setup.py (path:/tmp/pip-3Qw6kF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:28 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)) 08:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:28 INFO - Running setup.py (path:/tmp/pip-7n3Bxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:29 INFO - Running setup.py (path:/tmp/pip-RqobJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:29 INFO - Running setup.py (path:/tmp/pip-m2fFNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:29 INFO - Running setup.py (path:/tmp/pip-hLU7uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:29 INFO - Running setup.py (path:/tmp/pip-2k2Okv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:29 INFO - Running setup.py (path:/tmp/pip-Rz22uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:29 INFO - Running setup.py (path:/tmp/pip-YjjjJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:29 INFO - Running setup.py (path:/tmp/pip-VchUwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:29 INFO - Running setup.py (path:/tmp/pip-2PluRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:29 INFO - Running setup.py (path:/tmp/pip-8wt9F6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:29 INFO - Running setup.py (path:/tmp/pip-dUsq47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:29 INFO - Running setup.py (path:/tmp/pip-7vr40W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:29 INFO - Running setup.py (path:/tmp/pip-HoLry9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:29 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)) 08:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:29 INFO - Running setup.py (path:/tmp/pip-vKqD4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:30 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)) 08:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:30 INFO - Running setup.py (path:/tmp/pip-k2_d5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:30 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)) 08:08:30 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)) 08:08:30 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)) 08:08:30 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)) 08:08:30 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)) 08:08:30 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)) 08:08:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:08:30 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 08:08:30 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 08:08:30 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 08:08:30 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 08:08:30 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 08:08:30 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 08:08:32 INFO - Downloading blessings-1.6.tar.gz 08:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:08:32 INFO - Installing collected packages: blessings 08:08:32 INFO - Running setup.py install for blessings 08:08:32 INFO - Successfully installed blessings 08:08:32 INFO - Cleaning up... 08:08:32 INFO - Return code: 0 08:08:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:32 INFO - Reading from file tmpfile_stdout 08:08:32 INFO - Current package versions: 08:08:32 INFO - argparse == 1.2.1 08:08:32 INFO - blessings == 1.6 08:08:32 INFO - blobuploader == 1.2.4 08:08:32 INFO - docopt == 0.6.1 08:08:32 INFO - functools32 == 3.2.3-2 08:08:32 INFO - jsonschema == 2.5.1 08:08:32 INFO - manifestparser == 1.1 08:08:32 INFO - mozInstall == 1.12 08:08:32 INFO - mozcrash == 1.0 08:08:32 INFO - mozdebug == 0.1 08:08:32 INFO - mozdevice == 0.49 08:08:32 INFO - mozfile == 1.2 08:08:32 INFO - mozhttpd == 0.7 08:08:32 INFO - mozinfo == 0.9 08:08:32 INFO - mozleak == 0.1 08:08:32 INFO - mozlog == 3.4 08:08:32 INFO - moznetwork == 0.27 08:08:32 INFO - mozprocess == 0.25 08:08:32 INFO - mozprofile == 0.28 08:08:32 INFO - mozrunner == 6.13 08:08:32 INFO - mozscreenshot == 0.1 08:08:32 INFO - mozsystemmonitor == 0.3 08:08:32 INFO - moztest == 0.8 08:08:32 INFO - mozversion == 1.4 08:08:32 INFO - psutil == 3.1.1 08:08:32 INFO - requests == 1.2.3 08:08:32 INFO - wsgiref == 0.1.2 08:08:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:32 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:32 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 08:08:32 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 08:08:32 INFO - Using env: {'DISPLAY': ':0', 08:08:32 INFO - 'HOME': '/home/cltbld', 08:08:32 INFO - 'LANG': 'en_US.UTF-8', 08:08:32 INFO - 'LANGUAGE': 'en_US:en', 08:08:32 INFO - 'LOGNAME': 'cltbld', 08:08:32 INFO - 'MAIL': '/var/mail/cltbld', 08:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:32 INFO - 'MOZ_NO_REMOTE': '1', 08:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:32 INFO - 'NO_EM_RESTART': '1', 08:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:32 INFO - 'PWD': '/builds/slave/test', 08:08:32 INFO - 'SHELL': '/bin/bash', 08:08:32 INFO - 'SHLVL': '1', 08:08:32 INFO - 'TERM': 'linux', 08:08:32 INFO - 'TMOUT': '86400', 08:08:32 INFO - 'USER': 'cltbld', 08:08:32 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:32 INFO - '_': '/tools/buildbot/bin/python'} 08:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 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)) 08:08:32 INFO - Cleaning up... 08:08:32 INFO - Return code: 0 08:08:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1272a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1417b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, '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': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', '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 08:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:08:32 INFO - Using env: {'DISPLAY': ':0', 08:08:32 INFO - 'HOME': '/home/cltbld', 08:08:32 INFO - 'LANG': 'en_US.UTF-8', 08:08:32 INFO - 'LANGUAGE': 'en_US:en', 08:08:32 INFO - 'LOGNAME': 'cltbld', 08:08:32 INFO - 'MAIL': '/var/mail/cltbld', 08:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:32 INFO - 'MOZ_NO_REMOTE': '1', 08:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:32 INFO - 'NO_EM_RESTART': '1', 08:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:32 INFO - 'PWD': '/builds/slave/test', 08:08:32 INFO - 'SHELL': '/bin/bash', 08:08:32 INFO - 'SHLVL': '1', 08:08:32 INFO - 'TERM': 'linux', 08:08:32 INFO - 'TMOUT': '86400', 08:08:32 INFO - 'USER': 'cltbld', 08:08:32 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:32 INFO - '_': '/tools/buildbot/bin/python'} 08:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:08:33 INFO - Cleaning up... 08:08:33 INFO - Return code: 0 08:08:33 INFO - Running post-action listener: _resource_record_post_action 08:08:33 INFO - Running post-action listener: _start_resource_monitoring 08:08:33 INFO - Starting resource monitoring. 08:08:33 INFO - [mozharness: 2017-04-06 15:08:33.077537Z] Finished create-virtualenv step (success) 08:08:33 INFO - [mozharness: 2017-04-06 15:08:33.078076Z] Running install step. 08:08:33 INFO - Running pre-action listener: _resource_record_pre_action 08:08:33 INFO - Running main action method: install 08:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:33 INFO - Reading from file tmpfile_stdout 08:08:33 INFO - Detecting whether we're running mozinstall >=1.0... 08:08:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:08:33 INFO - Reading from file tmpfile_stdout 08:08:33 INFO - Output received: 08:08:33 INFO - Usage: mozinstall [options] installer 08:08:33 INFO - Options: 08:08:33 INFO - -h, --help show this help message and exit 08:08:33 INFO - -d DEST, --destination=DEST 08:08:33 INFO - Directory to install application into. [default: 08:08:33 INFO - "/builds/slave/test"] 08:08:33 INFO - --app=APP Application being installed. [default: firefox] 08:08:33 INFO - mkdir: /builds/slave/test/build/application 08:08:33 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'] 08:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:08:49 INFO - Reading from file tmpfile_stdout 08:08:49 INFO - Output received: 08:08:49 INFO - /builds/slave/test/build/application/firefox/firefox 08:08:49 INFO - Running post-action listener: _resource_record_post_action 08:08:49 INFO - [mozharness: 2017-04-06 15:08:49.940868Z] Finished install step (success) 08:08:49 INFO - [mozharness: 2017-04-06 15:08:49.941140Z] Running run-tests step. 08:08:49 INFO - Running pre-action listener: _resource_record_pre_action 08:08:49 INFO - Running main action method: run_tests 08:08:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:08:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:08:49 INFO - Python 2.7.3 08:08:49 INFO - Return code: 0 08:08:49 INFO - grabbing minidump binary from tooltool 08:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:49 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 0x1415290>, '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 0x1415b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1416040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:08:49 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 08:08:49 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 08:08:49 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 08:08:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:08:50 INFO - Return code: 0 08:08:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:08:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:08:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:08:50 INFO - ENV: RUST_BACKTRACE is now 1 08:08:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:08:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:08:50 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/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 08:08:50 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/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-074 --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 08:08:50 INFO - Using env: {'DISPLAY': ':0', 08:08:50 INFO - 'HOME': '/home/cltbld', 08:08:50 INFO - 'LANG': 'en_US.UTF-8', 08:08:50 INFO - 'LANGUAGE': 'en_US:en', 08:08:50 INFO - 'LOGNAME': 'cltbld', 08:08:50 INFO - 'MAIL': '/var/mail/cltbld', 08:08:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:08:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:50 INFO - 'MOZ_NO_REMOTE': '1', 08:08:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:50 INFO - 'NO_EM_RESTART': '1', 08:08:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:50 INFO - 'PWD': '/builds/slave/test', 08:08:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:08:50 INFO - 'RUST_BACKTRACE': '1', 08:08:50 INFO - 'SHELL': '/bin/bash', 08:08:50 INFO - 'SHLVL': '1', 08:08:50 INFO - 'TERM': 'linux', 08:08:50 INFO - 'TMOUT': '86400', 08:08:50 INFO - 'USER': 'cltbld', 08:08:50 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707', 08:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:50 INFO - '_': '/tools/buildbot/bin/python'} 08:08:50 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/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 08:08:50 INFO - mozversion application_buildid: 20170406141857 08:08:50 INFO - mozversion application_changeset: e03c56f3a839d1575e87dbd782638ad4894429e8 08:08:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:08:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:08:50 INFO - mozversion application_name: Firefox 08:08:50 INFO - mozversion application_remotingname: firefox-dev 08:08:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:08:50 INFO - mozversion application_vendor: Mozilla 08:08:50 INFO - mozversion application_version: 54.0a2 08:08:50 INFO - mozversion platform_buildid: 20170406141857 08:08:50 INFO - mozversion platform_changeset: e03c56f3a839d1575e87dbd782638ad4894429e8 08:08:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:08:50 INFO - mozversion platform_version: 54.0a2 08:08:50 INFO - using testdate: 1491491330 08:08:50 INFO - actual date: 1491491330 08:08:50 INFO - starting webserver on 'localhost:37812' 08:08:50 INFO - SUITE-START | Running 2 tests 08:08:50 INFO - TEST-START | dromaeo_css 08:08:50 INFO - operating with platform_type : linux_ 08:08:50 INFO - Initialising browser for dromaeo_css test... 08:08:50 INFO - TEST-INFO | started process 22274 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJvEuZA/profile http://localhost:37812/getInfo.html) 08:08:54 INFO - PROCESS | 22274 | __metrics Screen width/height:1600/1200 08:08:54 INFO - PROCESS | 22274 | colorDepth:24 08:08:54 INFO - PROCESS | 22274 | Browser inner width/height: 1024/697 08:08:54 INFO - PROCESS | 22274 | __metrics 08:08:55 INFO - PROCESS | 22274 | 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" 08:08:55 INFO - PROCESS | 22274 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:56 INFO - TEST-INFO | 22274: exit 0 08:08:56 INFO - Browser initialized. 08:08:56 INFO - Running cycle 1/1 for dromaeo_css test... 08:08:56 INFO - TEST-INFO | started process 22371 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJvEuZA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:08 INFO - PROCESS | 22371 | RSS: Main: 139608064 08:09:08 INFO - PROCESS | 22371 | 08:12:09 INFO - PROCESS | 22371 | Cycle 1(1): loaded http://localhost:37812/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37812/tests/dromaeo/cssquery-ext.html) 08:12:10 INFO - PROCESS | 22371 | RSS: Main: 272277504 08:12:10 INFO - PROCESS | 22371 | 08:13:58 INFO - PROCESS | 22371 | ************************* 08:13:58 INFO - PROCESS | 22371 | A coding exception was thrown and uncaught in a Task. 08:13:58 INFO - PROCESS | 22371 | 08:13:58 ERROR - PROCESS | 22371 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:13:58 INFO - PROCESS | 22371 | Full stack: 08:13:58 INFO - PROCESS | 22371 | ************************* 08:13:58 INFO - PROCESS | 22371 | ************************* 08:13:58 INFO - PROCESS | 22371 | A coding exception was thrown and uncaught in a Task. 08:13:58 INFO - PROCESS | 22371 | 08:13:58 ERROR - PROCESS | 22371 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:13:58 INFO - PROCESS | 22371 | Full stack: 08:13:58 INFO - PROCESS | 22371 | ************************* 08:13:58 INFO - PROCESS | 22371 | ************************* 08:13:58 INFO - PROCESS | 22371 | A coding exception was thrown and uncaught in a Task. 08:13:58 INFO - PROCESS | 22371 | 08:13:58 ERROR - PROCESS | 22371 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:13:58 INFO - PROCESS | 22371 | Full stack: 08:13:58 INFO - PROCESS | 22371 | ************************* 08:13:58 INFO - PROCESS | 22371 | ************************* 08:13:58 INFO - PROCESS | 22371 | A coding exception was thrown and uncaught in a Task. 08:13:58 INFO - PROCESS | 22371 | 08:13:58 ERROR - PROCESS | 22371 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:13:58 INFO - PROCESS | 22371 | Full stack: 08:13:58 INFO - PROCESS | 22371 | ************************* 08:15:13 INFO - PROCESS | 22371 | Cycle 1(1): loaded http://localhost:37812/tests/dromaeo/cssquery-ext.html (next: http://localhost:37812/tests/dromaeo/cssquery-jquery.html) 08:15:13 INFO - PROCESS | 22371 | RSS: Main: 226160640 08:15:13 INFO - PROCESS | 22371 | 08:18:15 INFO - PROCESS | 22371 | Cycle 1(1): loaded http://localhost:37812/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37812/tests/dromaeo/cssquery-mootools.html) 08:18:16 INFO - PROCESS | 22371 | RSS: Main: 171446272 08:18:16 INFO - PROCESS | 22371 | 08:21:18 INFO - PROCESS | 22371 | Cycle 1(1): loaded http://localhost:37812/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37812/tests/dromaeo/cssquery-prototype.html) 08:21:18 INFO - PROCESS | 22371 | RSS: Main: 214519808 08:21:18 INFO - PROCESS | 22371 | 08:24:21 INFO - PROCESS | 22371 | Cycle 1(1): loaded http://localhost:37812/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37812/tests/dromaeo/cssquery-yui.html) 08:24:22 INFO - PROCESS | 22371 | RSS: Main: 175505408 08:24:22 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | Cycle 1(1): loaded http://localhost:37812/tests/dromaeo/cssquery-yui.html (next: http://localhost:37812/tests/dromaeo/cssquery-dojo.html) 08:27:24 INFO - PROCESS | 22371 | RSS: Main: 268517376 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | __start_tp_report 08:27:24 INFO - PROCESS | 22371 | _x_x_mozilla_page_load 08:27:24 INFO - PROCESS | 22371 | _x_x_mozilla_page_load_details 08:27:24 INFO - PROCESS | 22371 | |i|pagename|runs| 08:27:24 INFO - PROCESS | 22371 | |0;dojo.html;3360.42872711639;3407.863685452582;3416.009357286387;3416.419208734514;3460.965390346097;5729.997250481165;5797.826065218045;5799.18811366409;5811.476967072963;5812.273465816773;5766.221560089387;5816.156657284691;5817.505512031472;5826.9708651456785;5831.183634291203;5148.227765835118;5163.948360516403;5193.922091168635;5199.194124910645;5235.031519168947;4997.925031124536;5022.974885125569;5229.320188375525;5248.737563121847;5249.685018898859;5054.570361519275;5118.513741194599;5120.385553733558;5204.063268611649;5205.583553315726;5174.197999310094;5211.2443695663915;5214.791408343662;5217.582593392539;5220.582353411718;4949.82675606352;5063.518965698253;5129.615278854064;5166.044281807877;5181.948180518184;5601.3558440779425;5619.80330688424;5661.179129026309;5739.053056245714;5742.6841523716175;6058.121572372025;6109.114178444145;6119.296280927706;6126.938730612681;6128.3258841527395;6162.414570615784;6164.291106522763;6168.506519478431;6182.505399568024;6186.072089186613;5574.470425309589;5611.298587676541;5724.284464441903;5756.481916627524;5768.538516918637;6313.3686631336495;6314.210723659543;6315.55791094619;6321.841953951188;6324.683765811783;6025.849353766191;6072.240969878765;6084.269887613426;6089.391060893875;6100.206973093558;5912.822615321547;5947.1079338099635;6052.848678782978;6092.025275955826;6187.16473276111;6101.054336577838;6124.265088189446;6124.38756124384;6124.479419249414;6130.63216207029;5910.290765108214;5944.7027648617395;5948.67282299469;6122.173506576555;6127.387261273926;4630.699004564693;4638.141943740419;4648.070385922829;4664.836730714409;4676.602488788423;4219.894502637398;4233.830646774156;4236.2586547354085;4243.236217480883;4243.6605071594195;5707.229524014205;5761.39505351932;5808.796692115756;5812.59311848175;5823.097419899922;5983.37174596661;5995.910061349084;6006.249218847644;6018.2176317079375;6019.307779605314;5782.132680097935;5796.913046304308;5919.378465261086;5922.733476993546;5961.791337303173;5229.032628963654;5279.340082489689;5282.550983166474;5288.3918349390315;5313.362396512443;6466.4503517201565;6500.934990650033;6511.153550038464;6516.967415162894;6519.3154718754795;5949.494292985145;5991.071383935497;5991.430814072656;5995.7901473449965;6224.1597384352535;6258.249010118815;6261.24865016201;6269.090981807703;6276.9372306276355;6354.6822658867795;6150.446459818638;6161.199044124235;6164.599301045418;6167.383261673976;6168.074788781719;6207.503399728102;6257.499400047895;6275.435210831047;6276.529260305404;6279.058141278845;5785.479306862402;5838.970805145947;6095.2685677718955;6101.420365065312;6102.6643534604245;6015.187949626919;6018.578699490994;6101.908471373022;6133.172021777003;6136.20229370179;5996.940030599812;6049.062395328731;6054.878902422015;6059.72731227087;6063.272407311151;5816.069428891357;6089.54328425361;6095.908561371672;6102.847428814315;6105.267367915879;5772.134179873053;5838;5875.41245875409;5962.433568810956;5982.820515384546 08:27:24 INFO - PROCESS | 22371 | |1;ext.html;734.8713975054368;786.7757689058618;790.0598288037233;864.5374724522378;868.3140319147874;29991.55012674809;43899.12201755967;44319.556804431944;44374;44463.33305000428;44036;44157.55842441574;44320.55679443213;44331.33502997449;44506.777466112624;7623.733169339074;7700.768976930673;9043.140901614335;9201.355905086646;9208.079192080771;10465.215108866865;10733.731656708567;10739.570417183304;10740.838887416696;11033.779324413546;10789.352638841694;10803.78392432151;10830.241883068189;10838.67483975482;10858;10736.838947415796;10778.5688572457;10794.136469082454;10809.027187553158;10809.459527023697;10640.840387394195;10665.680029599125;10681.893181068246;10698.46507674621;10699.358038517714;41579.376309355386;41752.791236043624;41752.79123604393;41765;41801;4203.159368126387;4207.158568286324;4229.2598795210715;4277.358396240589;4305.9784701076605;28500.572491412644;28695.71304286972;28711;29035.41929161405;29731;4301.677374196947;4366.8034938427845;4369.737815731066;4412.205802955466;4461.531539188403;243005;243176;243749.99999999822;244211;244949;51613.483865160866;51669.22496162561;51756.48243517517;51764.48235517672;51770.74114629403;53511.732441338325;53563;53739;53782;53889.46110538923;14817.40730370773;15232.086074835544;15250;15265.923670381577;15274.770878436831;10132.797344053077;10291.794164116676;11992.400379980965;13160.934195329153;13174.802377964339;12924.870751292554;12931;12967.416466259041;12997.545085921947;13052.412641430969;5283.392409872837;5290.867728306823;5295.4969277918535;5300.4945796073025;5358.812441564604;1201.7956947318803;1206.3847437806605;1219.0978675779859;1234.4876876096318;1240.55340077572;27263.45473090567;27266.31834204121;27289.181324560293;27462.45075098487;27588.44823103527;1271.2944315904576;1284.5054653958293;1292.915960462567;1310.60026691858;1313.1333320008748;14634.268286585628;14648.797831870635;14729.926350368394;14731.926340368229;14738.705225895423;314426;315488;316338;316446;316546;53680.46319536833;53841.1923821143;53854.46145538573;53888.73055634775;54036.18945715739;1420.375034984624;1465.5365265591083;1468.3759402254213;1475.7860110283948;1500.8048953635957;24828.131015415096;26058.478830422908;26276;26288;26543;41571.1685766289;41678.1664366717;41809;42038;42205.155896882505;281548;281717;281923;282366;282593;28900.710992889803;29577.556336655398;33454;33703.83148084244;34797;43114.353284700126;43130.35304470373;43210.13579728325;43211.56788432076;43260.35109473298;33600.831995839864;33741;33936.660633394335;33987.83006084954;34228.657713422544;33185.83407082949;33555.23195340125;33688.1577960553;33717.49423758694;33874.49188262227;37872.24255514929;38682;38903.80548097241;39136;39247.018824529616;31662.36675266436;32039.67960320367;32396;32854.67145328516;32956.17609559684;24429.877850610632;24993.250202493953;25740.227793166236;25778.871105644354;26239.4752104953 08:27:24 INFO - PROCESS | 22371 | |2;jquery.html;444.6620568368039;460.89860230749235;464.50530185352596;469.1414711078724;476.5044353871973;15229.771553426679;15728.921355393235;21133.04901279442;21186.152553897846;21915.99999999998;21528.56942861142;21912.45218869529;21998.34004979849;22136.557268854613;22219.333419997427;6665;6962.965185174093;7109.751158709446;7113.715451381939;7117.217106118323;6207.906881396782;6764.154480689914;6987;7076.080109585747;7109.715611375565;6824.863502729967;7094.858102837966;7112.4665650076195;7114.217436082027;7114.964425177867;6879.828004299882;6911.2397636259975;6921.619310937922;6931.514793964425;6971.721131154748;6593.967030164867;6719.63042032688;6753.561018533829;6761.661916904135;6763.222229443628;26268.73731262682;26298.6055209172;26395.736042639714;26436.206913792616;26553.73446265532;3590.838412271454;3712.7586706864236;3762.6613604775703;3772.5472943246714;3775.528058992626;16751.916240418843;16886.74669879953;17173;17571.385001524886;17881.37415190462;2221.580498685578;2284.156647781243;2307.849989750661;2360.1739391212936;2407;97636;114021;114661.426692866;115541.422292888;115847;19547.413577592695;19997;20313.390598282076;20489.2828750993;20965.580688386148;37487.062823429085;37557.62442375596;37600.43599346012;37621.43567846485;37631.62368376336;10713.339432387993;10845.566177352976;10904.672859814218;10907.83638245427;11059.72350691223;8885.911140888638;8908.420952638058;8954.104589540993;9159;9180.8622870657;10040.79918401628;10759.56961721538;10866.61966831157;10872.238943274053;10895.746989096197;3576.803275819855;3614.4759009943937;3876.8061596920043;4103.138340948434;4120.75275483472;989.851522271665;999.860019597257;1008.369768894441;1016.5425558498557;1016.7813920007234;23072.038876695333;23484;23594;23941.64087538688;24047.158349457684;1002.7994401119805;1016.1362841584594;1016.6552043775895;1021.3310281765456;1028.0695970147028;12695.746085078246;13087.280199588924;13098.476060957455;13287.13633613812;13473.326333683277;706731;706982;707077;710332;718397;109124;109203.45398272958;111229.44385278021;112360;112425;1289.1749280460513;1294.3916359311108;1331.034999625264;1343.0867010432999;1346.3941226448333;19600.333971612574;20040.198392064154;20440.28459003928;20469.488262793548;20589.691154632394;43568.782156089015;49009.50990490192;49861.750691246314;50580;50928.23607645814;64237;64293;64353.356466436606;65459;65513.34486655263;10948.233623646269;11141.888581114084;11542;11699.23954942926;12003.63989080329;13673.179609223478;13767.586972390844;13778.104423212459;13787.103838250481;13803.309834508435;11140.665780026613;12139;12294.446749896097;12410.689732756753;12664.87335126637;11594.5362185515;11805.645830625095;12022.218555793845;12068.094892883244;12091.334976576372;12958.28729419891;13001.934990325366;13018.15382000167;13020.153690010498;13065.281409522568;11145.88854111481;12236.388180590757;12246.448909798903;12739.872601274239;12749.745005099661;12705.682357940756;12727.554535591209;12752.872471275168;12776.488940442277;12785.744285114431 08:27:24 INFO - PROCESS | 22371 | |3;mootools.html;1562.8046494188227;1567.6472793621429;1573.6773961337924;1584.397928787061;1595.0828273742593;10683.732906677327;11403.600873969413;11407.714807129816;11416.31502109873;11420.714482137952;11299.209055366127;11599.130065245097;11961;11976.281423114619;11978.700532486691;2424.733279339271;2458.5451691437142;2459.4958033603157;2461.655368248447;2463.581191197492;2297.310806757974;2385.0698227691214;2389.0324418610403;2395.592749232626;2398.07674045492;2231.107556977206;2265.422317309084;2271.2277825539313;2426.708794944609;2436.7319594844553;2250.246167533878;2283.1666441748725;2289.7252329720372;2299.666548350488;2412.975870241293;2327.1156960354956;2350.3301559055585;2366.7751563601432;2368.585497537941;2370.0519792083305;11446.771064578661;11585.246958947726;11637.650870473899;11653.475593598308;11657.591984280509;5839.416058394169;5993.0710739835395;6008.3390827009;6009;6016.308124565643;3437.914052148691;3500.964990350115;3530.858765649371;3564.32277867207;3565.964340356589;1262.7663882181735;1304.2826445454962;1327.1307293722627;1328.6080606221187;1368.4457794593206;15080.773788393182;15410.460633877872;15837.366505339773;17531;17781.288748450046;4681.8361357353015;4808.783604737795;4827.2035114206465;4835.1296766581645;4873.366462359942;10619.20355973305;10904.61833835812;13716.862831371558;13727.862721372858;13746.450141994408;4912.066707325596;5561.110222364402;5715.914261286084;5769.711514424262;5770.394108618619;5602.6918519481005;5615.241942337732;5624.325080990226;5676.687782171937;5692.089265717465;4052.5947405259826;4124.628783409507;4134.4005119258145;4194.412782210493;4201.852935147255;1100.471773548701;1110.2062025651699;1110.6834552152598;1126.808442564766;1130.0789856267281;891.3315013739696;919.7516670498928;922.262190247799;950.6150009246267;1097.8298363753631;6906.171259448898;7009.088818453569;7106.111736032996;7145.428365730729;7308;1111.6442738323658;1112.360392774142;1128.2779021426293;1138.385271953136;1147.5008371358485;5014.398272207359;5108.897822043538;5158.484151584887;5163.66436181647;5264.473552644705;14383.28083595816;14749.557513274618;14808;14867.628309292355;14883.702325953636;14093.78859317111;14571.489997850025;14579.489717859824;14732.263386830615;15325.693486130438;10600.893991060146;10791.622293219856;10845.566177352976;10866.402347870942;10868.076213521781;6653.63405012729;6852.246252912076;6916.55042422241;6936.410405115622;7018.684159212949;13187.670308241986;13262.668433289246;13512.59462216135;13551;13564.86435135636;12714.554990575285;12872.16330938486;13043.086983911415;13670.794938075738;13689.24709140967;9547.665831695856;9661.661841835783;9851.359661621971;9886.50567471638;9893.55479003461;10220.846687299547;10282.845757313797;10322.2258330624;10401.791964160522;10406.167506599606;9143.908560914306;9275.814483710423;9285.535723213678;9338.626454941998;9485.905140948778;9035.051319611346;9069.274458043208;9109.453432794053;9122.452652840851;9245.815083698153;8142.307903828004;8271.172882711922;8295.211954864279;8723.77867098594;8969;6520.673966301761;6541.672916354259;6581.308962558863;6728.889733193968;6920.515563910478;6725.697343619451;6730.293319201413;6730.730770769114;6857.5199736018;6885.2770459101075 08:27:24 INFO - PROCESS | 22371 | |4;prototype.html;408.09199531043413;416.79368712487326;433.13589389168595;434.6696510651904;437.59303847421893;5144.279800827882;5277.868053298665;5288.2596436498825;5355.446920393086;5358.946410535894;5025.773840177191;5191.792328306863;5203.791848326063;5248.5538729207965;5250.606204534656;4388.297872340426;4388.97805510972;4392.846250381237;4405.933910991338;4413.249747542925;4414.249577571821;4424.646028317743;4428.911421771579;4430.180416622935;4431.335299705038;4285.4214681017975;4342.891427714301;4398.296272596385;4425.203463376591;4452.866414007569;4335.739855608674;4352.606066663354;4362.8438463807115;4373.890652733691;4383.4739831220295;4076;4126.195391898581;4156.709030367845;4162.146759914211;4173.747875637297;4904.705717656952;5030.874228144326;5040.823571174991;5152.433232344477;5156.432792392871;5063.164577844688;5071.974640126813;5093.745312734385;5127.410347810012;5141.408737995103;5425.83344580917;5504.284443022381;5600.327960644708;5607.8878422431735;5617.38208797032;4455.532169122228;4554.4990051094355;4557.789472106059;4589.518100599423;4592.265237562007;5435.347758268994;5544.63959842609;5562.554995600382;5698;5713.971430142864;4720.88197795051;4723.95276047242;4735.881602959885;4749.622609443292;4759.405074365704;4562.270036794097;4635.791389387486;4805.399325084364;4832.637552183601;4862.149123903303;5021.372328458942;5063.367079115111;5077.238414237894;5093.191916869532;5096.184610462308;5489.972550137303;5497.5601951843755;5513.807016754395;5543.944560554342;5592.664440133605;1557.6651020030747;1640.7702921590987;1661.7008938390964;1667.940857555461;1675.2628843308905;1245.9222772302076;1265.7848165811833;1270.8220849081135;1283.4866053578457;1287.2147989726259;5372.140457526777;5415;5421.484958928895;5439.782408703687;5441.673499589958;5676.148577713294;5687.317521897398;5715;5720.742566584514;5727.856803579861;5380.139177731544;5417.702026388586;5471.316085489314;5497.587680923867;5510.201020852034;2975.9256018599276;2994.7304742573274;2998;2998.9550156747664;3000.2799328161523;5579.2746942897975;5740.052891272893;5893.705314734332;5924.940750592526;5934.376890426443;5690.573207009491;5739.995500787346;5744.195812586241;5757.395473475309;5770.221020162225;5888.910551547978;5891;5897.882042359214;5898.233229680114;5922.496587790086;5319.893602128013;5619;5623.3533143689;5635.8591035224445;5677.659340439587;5376.25810836484;5475.589330800126;5478.91781623268;5594.608377413705;5630.971845140748;5883.440888164725;5910.852228694317;5912.67480288571;5912.674802885883;5927.970360148344;4578.221702310549;4616.261398176276;4702.341672165831;4749.382580264543;4789.95210047909;5521.217965693513;5545.639533430314;5585.832425027255;5606.187102870143;5624.625224865212;5427.402985671652;5436.864078398072;5497.780088796403;5531.8893622126525;5542.226487523953;5333.866653333697;5426.918596220981;5471.917921231265;5478.534324582296;5501.642393244587;5363.731813409392;5387.407385187705;5396.9190462142315;5406.972965135149;5407.83776486706;5444.564434845282;5493.747563498946;5512.944870551243;5514.834554963358;5520.944790552043;5153.5361817436615;5318.388385335736;5363.892722145613;5399.973000135132;5477.454441941842 08:27:24 INFO - PROCESS | 22371 | |5;yui.html;884.3367474394204;895.1853813030144;897.407710910799;919.0946917286473;924.6486335192626;16259.512214633565;16695;17226.82773172268;17519.47441576755;17607.031613261282;15365.308561114749;15444.459443919464;16175.919120404382;16327.346906123741;16354.264058117384;873.8077622922947;883.266888482558;899.6491368366343;903.5978989349742;904.5341649050744;906.8911730592338;909.4497828813571;910.1308250620674;910.1944778870713;910.8724778531011;685.5269863793991;687.4191308344441;687.9484038697093;688.1260798785539;688.3357559954643;658.7068754404291;661.7948435984836;662.0036844548947;666.4634968850074;668.9096971908779;483.14724511237694;484.5082241524855;488.975551222441;492.2984746735887;495.0322120254893;15612.141332226724;15732.134732589702;17126.914365428216;17211.397601083903;17258.30966761328;15684.92157539205;15784.842151578452;15880.920595396949;16133.83866161335;16434;12923;13324.600261992156;13338;13497.730045399136;13534.917206623446;3912.608739126093;3963.1083006323347;3967.98016009921;4056.9594304057173;4108.979455102735;82857.58571207106;83256.16743832544;84148;84814.15185848186;86190;11600.18798684084;11677.416129193676;11730.76538469243;11757.059435245159;11781.410929453494;20595.382138535868;20743.377698669065;20864.165433382797;20910.790892091187;21013;8623.223909848144;8697.739067827975;8830.426022308659;8963.865542016874;9043.276537876955;4824.204006338985;4825.396825396825;4878.390201224847;4964.255361695775;4994.900101998012;3563.3407819553468;3660.2862441824122;3666.651668091527;3667.5598928128798;3669.541307336583;599.2090440618342;607.2318517075903;612.9233845769279;613.0773186354508;613.5183880653755;737.0896942276285;741.1254719431123;754.5057987018512;769.3268390158611;793.0641842625757;10497.13564907836;10562.366258024544;10646.680599582025;10757;10908.290961087503;712.6151877985933;723.0311382747144;724.5978481942467;738.8042168825266;778.2140038561095;5115.283860259567;5234.947650523446;5243.9213411798855;5253.947460525423;5269.077911365495;63739.6813015932;66199.66900165468;70870;72815;78189.60905195438;64040;65130;65483.345166547726;66985;67302;61188.38811611916;61635;62020.689896550226;62235.37764622386;62254.37745622576;11600.24598401135;11857.229280096766;11992.340421276913;12182.695432614255;12240.265584064984;40987.38518922159;41696;41839.37240941449;42210;43110.95645257556;47798.76100619475;47826.5217347822;48415.5158448411;48444.515554844;50268.994620108126;12404;12629.115961882948;12639.3680315982;12740.108192426442;13000.219986800823;13782;14495;15215.315310811262;15261;16430.01419914809;14523.491677791226;14975.475858344906;15572.92213538925;16116;17019.659606808043;14749.188794616399;14817.40730370773;14952.331167699525;15431.418411773764;15645.765313520078;13675.24786136772;13842.875662466304;14172.503962361268;14283;14438.566842994727;10830.241883068109;10930.180236482136;10981.725456863323;11035.227534072537;11258.437078145897;11288.153388495732;11348.432578370883;11647.88352116468;11678.88321116778;11725.237859539102 08:27:24 INFO - PROCESS | 22371 | __end_tp_report 08:27:24 INFO - PROCESS | 22371 | __start_cc_report 08:27:24 INFO - PROCESS | 22371 | _x_x_mozilla_cycle_collect,1332 08:27:24 INFO - PROCESS | 22371 | __end_cc_report 08:27:24 INFO - PROCESS | 22371 | __startTimestamp1491492444923__endTimestamp 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | ------- Summary: start ------- 08:27:24 INFO - PROCESS | 22371 | Number of tests: 6 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | [#0] dojo.html Cycles:165 Average:5700.26 Median:5944.70 stddev:649.31 (10.9%) stddev-sans-first:624.82 08:27:24 INFO - PROCESS | 22371 | Values: 3360.4 3407.9 3416.0 3416.4 3461.0 5730.0 5797.8 5799.2 5811.5 5812.3 5766.2 5816.2 5817.5 5827.0 5831.2 5148.2 5163.9 5193.9 5199.2 5235.0 4997.9 5023.0 5229.3 5248.7 5249.7 5054.6 5118.5 5120.4 5204.1 5205.6 5174.2 5211.2 5214.8 5217.6 5220.6 4949.8 5063.5 5129.6 5166.0 5181.9 5601.4 5619.8 5661.2 5739.1 5742.7 6058.1 6109.1 6119.3 6126.9 6128.3 6162.4 6164.3 6168.5 6182.5 6186.1 5574.5 5611.3 5724.3 5756.5 5768.5 6313.4 6314.2 6315.6 6321.8 6324.7 6025.8 6072.2 6084.3 6089.4 6100.2 5912.8 5947.1 6052.8 6092.0 6187.2 6101.1 6124.3 6124.4 6124.5 6130.6 5910.3 5944.7 5948.7 6122.2 6127.4 4630.7 4638.1 4648.1 4664.8 4676.6 4219.9 4233.8 4236.3 4243.2 4243.7 5707.2 5761.4 5808.8 5812.6 5823.1 5983.4 5995.9 6006.2 6018.2 6019.3 5782.1 5796.9 5919.4 5922.7 5961.8 5229.0 5279.3 5282.6 5288.4 5313.4 6466.5 6500.9 6511.2 6517.0 6519.3 5949.5 5991.1 5991.4 5995.8 6224.2 6258.2 6261.2 6269.1 6276.9 6354.7 6150.4 6161.2 6164.6 6167.4 6168.1 6207.5 6257.5 6275.4 6276.5 6279.1 5785.5 5839.0 6095.3 6101.4 6102.7 6015.2 6018.6 6101.9 6133.2 6136.2 5996.9 6049.1 6054.9 6059.7 6063.3 5816.1 6089.5 6095.9 6102.8 6105.3 5772.1 5838.0 5875.4 5962.4 5982.8 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | [#1] ext.html Cycles:180 Average:44911.27 Median:316392.00 stddev:73575.89 (23.3%) stddev-sans-first:73707.52 08:27:24 INFO - PROCESS | 22371 | Values: 734.9 786.8 790.1 864.5 868.3 29991.6 43899.1 44319.6 44374.0 44463.3 44036.0 44157.6 44320.6 44331.3 44506.8 7623.7 7700.8 9043.1 9201.4 9208.1 10465.2 10733.7 10739.6 10740.8 11033.8 10789.4 10803.8 10830.2 10838.7 10858.0 10736.8 10778.6 10794.1 10809.0 10809.5 10640.8 10665.7 10681.9 10698.5 10699.4 41579.4 41752.8 41752.8 41765.0 41801.0 4203.2 4207.2 4229.3 4277.4 4306.0 28500.6 28695.7 28711.0 29035.4 29731.0 4301.7 4366.8 4369.7 4412.2 4461.5 243005.0 243176.0 243750.0 244211.0 244949.0 51613.5 51669.2 51756.5 51764.5 51770.7 53511.7 53563.0 53739.0 53782.0 53889.5 14817.4 15232.1 15250.0 15265.9 15274.8 10132.8 10291.8 11992.4 13160.9 13174.8 12924.9 12931.0 12967.4 12997.5 13052.4 5283.4 5290.9 5295.5 5300.5 5358.8 1201.8 1206.4 1219.1 1234.5 1240.6 27263.5 27266.3 27289.2 27462.5 27588.4 1271.3 1284.5 1292.9 1310.6 1313.1 14634.3 14648.8 14729.9 14731.9 14738.7 314426.0 315488.0 316338.0 316446.0 316546.0 53680.5 53841.2 53854.5 53888.7 54036.2 1420.4 1465.5 1468.4 1475.8 1500.8 24828.1 26058.5 26276.0 26288.0 26543.0 41571.2 41678.2 41809.0 42038.0 42205.2 281548.0 281717.0 281923.0 282366.0 282593.0 28900.7 29577.6 33454.0 33703.8 34797.0 43114.4 43130.4 43210.1 43211.6 43260.4 33600.8 33741.0 33936.7 33987.8 34228.7 33185.8 33555.2 33688.2 33717.5 33874.5 37872.2 38682.0 38903.8 39136.0 39247.0 31662.4 32039.7 32396.0 32854.7 32956.2 24429.9 24993.3 25740.2 25778.9 26239.5 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | [#2] jquery.html Cycles:180 Average:39205.98 Median:21357.36 stddev:116574.53 (545.8%) stddev-sans-first:116865.22 08:27:24 INFO - PROCESS | 22371 | Values: 444.7 460.9 464.5 469.1 476.5 15229.8 15728.9 21133.0 21186.2 21916.0 21528.6 21912.5 21998.3 22136.6 22219.3 6665.0 6963.0 7109.8 7113.7 7117.2 6207.9 6764.2 6987.0 7076.1 7109.7 6824.9 7094.9 7112.5 7114.2 7115.0 6879.8 6911.2 6921.6 6931.5 6971.7 6594.0 6719.6 6753.6 6761.7 6763.2 26268.7 26298.6 26395.7 26436.2 26553.7 3590.8 3712.8 3762.7 3772.5 3775.5 16751.9 16886.7 17173.0 17571.4 17881.4 2221.6 2284.2 2307.8 2360.2 2407.0 97636.0 114021.0 114661.4 115541.4 115847.0 19547.4 19997.0 20313.4 20489.3 20965.6 37487.1 37557.6 37600.4 37621.4 37631.6 10713.3 10845.6 10904.7 10907.8 11059.7 8885.9 8908.4 8954.1 9159.0 9180.9 10040.8 10759.6 10866.6 10872.2 10895.7 3576.8 3614.5 3876.8 4103.1 4120.8 989.9 999.9 1008.4 1016.5 1016.8 23072.0 23484.0 23594.0 23941.6 24047.2 1002.8 1016.1 1016.7 1021.3 1028.1 12695.7 13087.3 13098.5 13287.1 13473.3 706731.0 706982.0 707077.0 710332.0 718397.0 109124.0 109203.5 111229.4 112360.0 112425.0 1289.2 1294.4 1331.0 1343.1 1346.4 19600.3 20040.2 20440.3 20469.5 20589.7 43568.8 49009.5 49861.8 50580.0 50928.2 64237.0 64293.0 64353.4 65459.0 65513.3 10948.2 11141.9 11542.0 11699.2 12003.6 13673.2 13767.6 13778.1 13787.1 13803.3 11140.7 12139.0 12294.4 12410.7 12664.9 11594.5 11805.6 12022.2 12068.1 12091.3 12958.3 13001.9 13018.2 13020.2 13065.3 11145.9 12236.4 12246.4 12739.9 12749.7 12705.7 12727.6 12752.9 12776.5 12785.7 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | [#3] mootools.html Cycles:180 Average:7149.94 Median:2392.31 stddev:4531.26 (189.4%) stddev-sans-first:4524.52 08:27:24 INFO - PROCESS | 22371 | Values: 1562.8 1567.6 1573.7 1584.4 1595.1 10683.7 11403.6 11407.7 11416.3 11420.7 11299.2 11599.1 11961.0 11976.3 11978.7 2424.7 2458.5 2459.5 2461.7 2463.6 2297.3 2385.1 2389.0 2395.6 2398.1 2231.1 2265.4 2271.2 2426.7 2436.7 2250.2 2283.2 2289.7 2299.7 2413.0 2327.1 2350.3 2366.8 2368.6 2370.1 11446.8 11585.2 11637.7 11653.5 11657.6 5839.4 5993.1 6008.3 6009.0 6016.3 3437.9 3501.0 3530.9 3564.3 3566.0 1262.8 1304.3 1327.1 1328.6 1368.4 15080.8 15410.5 15837.4 17531.0 17781.3 4681.8 4808.8 4827.2 4835.1 4873.4 10619.2 10904.6 13716.9 13727.9 13746.5 4912.1 5561.1 5715.9 5769.7 5770.4 5602.7 5615.2 5624.3 5676.7 5692.1 4052.6 4124.6 4134.4 4194.4 4201.9 1100.5 1110.2 1110.7 1126.8 1130.1 891.3 919.8 922.3 950.6 1097.8 6906.2 7009.1 7106.1 7145.4 7308.0 1111.6 1112.4 1128.3 1138.4 1147.5 5014.4 5108.9 5158.5 5163.7 5264.5 14383.3 14749.6 14808.0 14867.6 14883.7 14093.8 14571.5 14579.5 14732.3 15325.7 10600.9 10791.6 10845.6 10866.4 10868.1 6653.6 6852.2 6916.6 6936.4 7018.7 13187.7 13262.7 13512.6 13551.0 13564.9 12714.6 12872.2 13043.1 13670.8 13689.2 9547.7 9661.7 9851.4 9886.5 9893.6 10220.8 10282.8 10322.2 10401.8 10406.2 9143.9 9275.8 9285.5 9338.6 9485.9 9035.1 9069.3 9109.5 9122.5 9245.8 8142.3 8271.2 8295.2 8723.8 8969.0 6520.7 6541.7 6581.3 6728.9 6920.5 6725.7 6730.3 6730.7 6857.5 6885.3 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | [#4] prototype.html Cycles:180 Average:4802.37 Median:5326.88 stddev:1275.07 (23.9%) stddev-sans-first:1235.26 08:27:24 INFO - PROCESS | 22371 | Values: 408.1 416.8 433.1 434.7 437.6 5144.3 5277.9 5288.3 5355.4 5358.9 5025.8 5191.8 5203.8 5248.6 5250.6 4388.3 4389.0 4392.8 4405.9 4413.2 4414.2 4424.6 4428.9 4430.2 4431.3 4285.4 4342.9 4398.3 4425.2 4452.9 4335.7 4352.6 4362.8 4373.9 4383.5 4076.0 4126.2 4156.7 4162.1 4173.7 4904.7 5030.9 5040.8 5152.4 5156.4 5063.2 5072.0 5093.7 5127.4 5141.4 5425.8 5504.3 5600.3 5607.9 5617.4 4455.5 4554.5 4557.8 4589.5 4592.3 5435.3 5544.6 5562.6 5698.0 5714.0 4720.9 4724.0 4735.9 4749.6 4759.4 4562.3 4635.8 4805.4 4832.6 4862.1 5021.4 5063.4 5077.2 5093.2 5096.2 5490.0 5497.6 5513.8 5543.9 5592.7 1557.7 1640.8 1661.7 1667.9 1675.3 1245.9 1265.8 1270.8 1283.5 1287.2 5372.1 5415.0 5421.5 5439.8 5441.7 5676.1 5687.3 5715.0 5720.7 5727.9 5380.1 5417.7 5471.3 5497.6 5510.2 2975.9 2994.7 2998.0 2999.0 3000.3 5579.3 5740.1 5893.7 5924.9 5934.4 5690.6 5740.0 5744.2 5757.4 5770.2 5888.9 5891.0 5897.9 5898.2 5922.5 5319.9 5619.0 5623.4 5635.9 5677.7 5376.3 5475.6 5478.9 5594.6 5631.0 5883.4 5910.9 5912.7 5912.7 5928.0 4578.2 4616.3 4702.3 4749.4 4790.0 5521.2 5545.6 5585.8 5606.2 5624.6 5427.4 5436.9 5497.8 5531.9 5542.2 5333.9 5426.9 5471.9 5478.5 5501.6 5363.7 5387.4 5396.9 5407.0 5407.8 5444.6 5493.7 5512.9 5514.8 5520.9 5153.5 5318.4 5363.9 5400.0 5477.5 08:27:24 INFO - PROCESS | 22371 | 08:27:24 INFO - PROCESS | 22371 | [#5] yui.html Cycles:180 Average:17645.05 Median:22902.49 stddev:21602.06 (94.3%) stddev-sans-first:21625.99 08:27:24 INFO - PROCESS | 22371 | Values: 884.3 895.2 897.4 919.1 924.6 16259.5 16695.0 17226.8 17519.5 17607.0 15365.3 15444.5 16175.9 16327.3 16354.3 873.8 883.3 899.6 903.6 904.5 906.9 909.4 910.1 910.2 910.9 685.5 687.4 687.9 688.1 688.3 658.7 661.8 662.0 666.5 668.9 483.1 484.5 489.0 492.3 495.0 15612.1 15732.1 17126.9 17211.4 17258.3 15684.9 15784.8 15880.9 16133.8 16434.0 12923.0 13324.6 13338.0 13497.7 13534.9 3912.6 3963.1 3968.0 4057.0 4109.0 82857.6 83256.2 84148.0 84814.2 86190.0 11600.2 11677.4 11730.8 11757.1 11781.4 20595.4 20743.4 20864.2 20910.8 21013.0 8623.2 8697.7 8830.4 8963.9 9043.3 4824.2 4825.4 4878.4 4964.3 4994.9 3563.3 3660.3 3666.7 3667.6 3669.5 599.2 607.2 612.9 613.1 613.5 737.1 741.1 754.5 769.3 793.1 10497.1 10562.4 10646.7 10757.0 10908.3 712.6 723.0 724.6 738.8 778.2 5115.3 5234.9 5243.9 5253.9 5269.1 63739.7 66199.7 70870.0 72815.0 78189.6 64040.0 65130.0 65483.3 66985.0 67302.0 61188.4 61635.0 62020.7 62235.4 62254.4 11600.2 11857.2 11992.3 12182.7 12240.3 40987.4 41696.0 41839.4 42210.0 43111.0 47798.8 47826.5 48415.5 48444.5 50269.0 12404.0 12629.1 12639.4 12740.1 13000.2 13782.0 14495.0 15215.3 15261.0 16430.0 14523.5 14975.5 15572.9 16116.0 17019.7 14749.2 14817.4 14952.3 15431.4 15645.8 13675.2 13842.9 14172.5 14283.0 14438.6 10830.2 10930.2 10981.7 11035.2 11258.4 11288.2 11348.4 11647.9 11678.9 11725.2 08:27:24 INFO - PROCESS | 22371 | -------- Summary: end -------- 08:27:24 INFO - PROCESS | 22371 | 08:27:25 INFO - TEST-INFO | 22371: exit 0 08:27:25 INFO - TEST-OK | dromaeo_css | took 1115521ms 08:27:25 INFO - TEST-START | kraken 08:27:25 INFO - operating with platform_type : linux_ 08:27:25 INFO - Initialising browser for kraken test... 08:27:25 INFO - TEST-INFO | started process 22695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEmdGx7/profile http://localhost:37812/getInfo.html) 08:27:29 INFO - PROCESS | 22695 | __metrics Screen width/height:1600/1200 08:27:29 INFO - PROCESS | 22695 | colorDepth:24 08:27:29 INFO - PROCESS | 22695 | Browser inner width/height: 1024/697 08:27:29 INFO - PROCESS | 22695 | __metrics 08:27:30 INFO - PROCESS | 22695 | 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" 08:27:30 INFO - PROCESS | 22695 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:27:31 INFO - TEST-INFO | 22695: exit 0 08:27:31 INFO - Browser initialized. 08:27:31 INFO - Running cycle 1/1 for kraken test... 08:27:31 INFO - TEST-INFO | started process 22789 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEmdGx7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:43 INFO - PROCESS | 22789 | RSS: Main: 139780096 08:27:43 INFO - PROCESS | 22789 | 08:27:46 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37812/tests/kraken/driver.html?testName=audio-beat-detection) 08:27:46 INFO - PROCESS | 22789 | RSS: Main: 184467456 08:27:46 INFO - PROCESS | 22789 | 08:27:49 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37812/tests/kraken/driver.html?testName=audio-dft) 08:27:49 INFO - PROCESS | 22789 | RSS: Main: 173527040 08:27:49 INFO - PROCESS | 22789 | 08:27:52 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37812/tests/kraken/driver.html?testName=audio-fft) 08:27:53 INFO - PROCESS | 22789 | RSS: Main: 265764864 08:27:53 INFO - PROCESS | 22789 | 08:27:55 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37812/tests/kraken/driver.html?testName=audio-oscillator) 08:27:56 INFO - PROCESS | 22789 | RSS: Main: 172089344 08:27:56 INFO - PROCESS | 22789 | 08:27:58 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37812/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:27:58 INFO - PROCESS | 22789 | RSS: Main: 175230976 08:27:58 INFO - PROCESS | 22789 | 08:28:01 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37812/tests/kraken/driver.html?testName=imaging-darkroom) 08:28:02 INFO - PROCESS | 22789 | RSS: Main: 213598208 08:28:02 INFO - PROCESS | 22789 | 08:28:05 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37812/tests/kraken/driver.html?testName=imaging-desaturate) 08:28:06 INFO - PROCESS | 22789 | RSS: Main: 212262912 08:28:06 INFO - PROCESS | 22789 | 08:28:08 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37812/tests/kraken/driver.html?testName=json-parse-financial) 08:28:09 INFO - PROCESS | 22789 | RSS: Main: 210628608 08:28:09 INFO - PROCESS | 22789 | 08:28:11 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37812/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:28:11 INFO - PROCESS | 22789 | RSS: Main: 173355008 08:28:11 INFO - PROCESS | 22789 | 08:28:13 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:28:13 INFO - PROCESS | 22789 | RSS: Main: 180645888 08:28:13 INFO - PROCESS | 22789 | 08:28:15 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:28:16 INFO - PROCESS | 22789 | RSS: Main: 189956096 08:28:16 INFO - PROCESS | 22789 | 08:28:19 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:28:19 INFO - PROCESS | 22789 | RSS: Main: 199065600 08:28:19 INFO - PROCESS | 22789 | 08:28:22 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:28:23 INFO - PROCESS | 22789 | RSS: Main: 195424256 08:28:23 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | Cycle 1(1): loaded http://localhost:37812/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37812/tests/kraken/driver.html?testName=ai-astar) 08:28:24 INFO - PROCESS | 22789 | RSS: Main: 246562816 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | __start_tp_report 08:28:24 INFO - PROCESS | 22789 | _x_x_mozilla_page_load 08:28:24 INFO - PROCESS | 22789 | _x_x_mozilla_page_load_details 08:28:24 INFO - PROCESS | 22789 | |i|pagename|runs| 08:28:24 INFO - PROCESS | 22789 | |0;ai-astar;102;111;112;112;116;112;111;112;111;111 08:28:24 INFO - PROCESS | 22789 | |1;audio-beat-detection;138;114;116;158;115;120;115;115;136;116 08:28:24 INFO - PROCESS | 22789 | |2;audio-dft;161;161;161;159;161;160;161;161;159;160 08:28:24 INFO - PROCESS | 22789 | |3;audio-fft;75;74;75;111;75;75;77;77;95;76 08:28:24 INFO - PROCESS | 22789 | |4;audio-oscillator;82;83;107;82;82;82;96;88;82;101 08:28:24 INFO - PROCESS | 22789 | |5;imaging-gaussian-blur;114;113;114;113;114;113;113;113;113;113 08:28:24 INFO - PROCESS | 22789 | |6;imaging-darkroom;146;145;147;146;146;146;147;146;147;147 08:28:24 INFO - PROCESS | 22789 | |7;imaging-desaturate;95;94;94;94;94;94;94;94;94;94 08:28:24 INFO - PROCESS | 22789 | |8;json-parse-financial;54;61;66;56;55;56;56;55;55;55 08:28:24 INFO - PROCESS | 22789 | |9;json-stringify-tinderbox;52;52;53;52;51;52;52;51;51;52 08:28:24 INFO - PROCESS | 22789 | |10;stanford-crypto-aes;76;75;76;78;81;80;79;75;78;75 08:28:24 INFO - PROCESS | 22789 | |11;stanford-crypto-ccm;126;120;121;132;125;119;125;119;158;137 08:28:24 INFO - PROCESS | 22789 | |12;stanford-crypto-pbkdf2;161;160;160;164;159;165;165;161;159;160 08:28:24 INFO - PROCESS | 22789 | |13;stanford-crypto-sha256-iterative;64;62;61;63;59;61;63;58;59;58 08:28:24 INFO - PROCESS | 22789 | __end_tp_report 08:28:24 INFO - PROCESS | 22789 | __start_cc_report 08:28:24 INFO - PROCESS | 22789 | _x_x_mozilla_cycle_collect,1670 08:28:24 INFO - PROCESS | 22789 | __end_cc_report 08:28:24 INFO - PROCESS | 22789 | __startTimestamp1491492504975__endTimestamp 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | ------- Summary: start ------- 08:28:24 INFO - PROCESS | 22789 | Number of tests: 14 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:3.50 (3.1%) stddev-sans-first:1.58 08:28:24 INFO - PROCESS | 22789 | Values: 102.0 111.0 112.0 112.0 116.0 112.0 111.0 112.0 111.0 111.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:118.00 stddev:14.84 (12.6%) stddev-sans-first:14.89 08:28:24 INFO - PROCESS | 22789 | Values: 138.0 114.0 116.0 158.0 115.0 120.0 115.0 115.0 136.0 116.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 08:28:24 INFO - PROCESS | 22789 | Values: 161.0 161.0 161.0 159.0 161.0 160.0 161.0 161.0 159.0 160.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.50 stddev:12.23 (16.2%) stddev-sans-first:12.78 08:28:24 INFO - PROCESS | 22789 | Values: 75.0 74.0 75.0 111.0 75.0 75.0 77.0 77.0 95.0 76.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:82.00 stddev:9.41 (11.5%) stddev-sans-first:9.68 08:28:24 INFO - PROCESS | 22789 | Values: 82.0 83.0 107.0 82.0 82.0 82.0 96.0 88.0 82.0 101.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 08:28:24 INFO - PROCESS | 22789 | Values: 114.0 113.0 114.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 08:28:24 INFO - PROCESS | 22789 | Values: 146.0 145.0 147.0 146.0 146.0 146.0 147.0 146.0 147.0 147.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 08:28:24 INFO - PROCESS | 22789 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:56.00 stddev:3.73 (6.7%) stddev-sans-first:3.80 08:28:24 INFO - PROCESS | 22789 | Values: 54.0 61.0 66.0 56.0 55.0 56.0 56.0 55.0 55.0 55.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#9] json-stringify-tinderbox Cycles:10 Average:51.80 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 08:28:24 INFO - PROCESS | 22789 | Values: 52.0 52.0 53.0 52.0 51.0 52.0 52.0 51.0 51.0 52.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:78.00 stddev:2.21 (2.8%) stddev-sans-first:2.30 08:28:24 INFO - PROCESS | 22789 | Values: 76.0 75.0 76.0 78.0 81.0 80.0 79.0 75.0 78.0 75.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:125.50 stddev:11.99 (9.6%) stddev-sans-first:12.69 08:28:24 INFO - PROCESS | 22789 | Values: 126.0 120.0 121.0 132.0 125.0 119.0 125.0 119.0 158.0 137.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.40 Median:161.00 stddev:2.37 (1.5%) stddev-sans-first:2.51 08:28:24 INFO - PROCESS | 22789 | Values: 161.0 160.0 160.0 164.0 159.0 165.0 165.0 161.0 159.0 160.0 08:28:24 INFO - PROCESS | 22789 | 08:28:24 INFO - PROCESS | 22789 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:61.50 stddev:2.20 (3.6%) stddev-sans-first:2.01 08:28:24 INFO - PROCESS | 22789 | Values: 64.0 62.0 61.0 63.0 59.0 61.0 63.0 58.0 59.0 58.0 08:28:24 INFO - PROCESS | 22789 | -------- Summary: end -------- 08:28:24 INFO - PROCESS | 22789 | 08:28:25 INFO - TEST-INFO | 22789: exit 0 08:28:26 INFO - TEST-OK | kraken | took 60275ms 08:28:26 INFO - SUITE-END | took 1175s 08:28:26 INFO - Completed test suite (00:19:35) 08:28:26 INFO - javascript benchmark 08:28:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7794.768009728827, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.42872711639, 3407.863685452582, 3416.009357286387, 3416.419208734514, 3460.965390346097, 5729.997250481165, 5797.826065218045, 5799.18811366409, 5811.476967072963, 5812.273465816773, 5766.221560089387, 5816.156657284691, 5817.505512031472, 5826.9708651456785, 5831.183634291203, 5148.227765835118, 5163.948360516403, 5193.922091168635, 5199.194124910645, 5235.031519168947, 4997.925031124536, 5022.974885125569, 5229.320188375525, 5248.737563121847, 5249.685018898859, 5054.570361519275, 5118.513741194599, 5120.385553733558, 5204.063268611649, 5205.583553315726, 5174.197999310094, 5211.2443695663915, 5214.791408343662, 5217.582593392539, 5220.582353411718, 4949.82675606352, 5063.518965698253, 5129.615278854064, 5166.044281807877, 5181.948180518184, 5601.3558440779425, 5619.80330688424, 5661.179129026309, 5739.053056245714, 5742.6841523716175, 6058.121572372025, 6109.114178444145, 6119.296280927706, 6126.938730612681, 6128.3258841527395, 6162.414570615784, 6164.291106522763, 6168.506519478431, 6182.505399568024, 6186.072089186613, 5574.470425309589, 5611.298587676541, 5724.284464441903, 5756.481916627524, 5768.538516918637, 6313.3686631336495, 6314.210723659543, 6315.55791094619, 6321.841953951188, 6324.683765811783, 6025.849353766191, 6072.240969878765, 6084.269887613426, 6089.391060893875, 6100.206973093558, 5912.822615321547, 5947.1079338099635, 6052.848678782978, 6092.025275955826, 6187.16473276111, 6101.054336577838, 6124.265088189446, 6124.38756124384, 6124.479419249414, 6130.63216207029, 5910.290765108214, 5944.7027648617395, 5948.67282299469, 6122.173506576555, 6127.387261273926, 4630.699004564693, 4638.141943740419, 4648.070385922829, 4664.836730714409, 4676.602488788423, 4219.894502637398, 4233.830646774156, 4236.2586547354085, 4243.236217480883, 4243.6605071594195, 5707.229524014205, 5761.39505351932, 5808.796692115756, 5812.59311848175, 5823.097419899922, 5983.37174596661, 5995.910061349084, 6006.249218847644, 6018.2176317079375, 6019.307779605314, 5782.132680097935, 5796.913046304308, 5919.378465261086, 5922.733476993546, 5961.791337303173, 5229.032628963654, 5279.340082489689, 5282.550983166474, 5288.3918349390315, 5313.362396512443, 6466.4503517201565, 6500.934990650033, 6511.153550038464, 6516.967415162894, 6519.3154718754795, 5949.494292985145, 5991.071383935497, 5991.430814072656, 5995.7901473449965, 6224.1597384352535, 6258.249010118815, 6261.24865016201, 6269.090981807703, 6276.9372306276355, 6354.6822658867795, 6150.446459818638, 6161.199044124235, 6164.599301045418, 6167.383261673976, 6168.074788781719, 6207.503399728102, 6257.499400047895, 6275.435210831047, 6276.529260305404, 6279.058141278845, 5785.479306862402, 5838.970805145947, 6095.2685677718955, 6101.420365065312, 6102.6643534604245, 6015.187949626919, 6018.578699490994, 6101.908471373022, 6133.172021777003, 6136.20229370179, 5996.940030599812, 6049.062395328731, 6054.878902422015, 6059.72731227087, 6063.272407311151, 5816.069428891357, 6089.54328425361, 6095.908561371672, 6102.847428814315, 6105.267367915879, 5772.134179873053, 5838.0, 5875.41245875409, 5962.433568810956, 5982.820515384546], "value": 5657.2095124525285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.8713975054368, 786.7757689058618, 790.0598288037233, 864.5374724522378, 868.3140319147874, 29991.55012674809, 43899.12201755967, 44319.556804431944, 44374.0, 44463.33305000428, 44036.0, 44157.55842441574, 44320.55679443213, 44331.33502997449, 44506.777466112624, 7623.733169339074, 7700.768976930673, 9043.140901614335, 9201.355905086646, 9208.079192080771, 10465.215108866865, 10733.731656708567, 10739.570417183304, 10740.838887416696, 11033.779324413546, 10789.352638841694, 10803.78392432151, 10830.241883068189, 10838.67483975482, 10858.0, 10736.838947415796, 10778.5688572457, 10794.136469082454, 10809.027187553158, 10809.459527023697, 10640.840387394195, 10665.680029599125, 10681.893181068246, 10698.46507674621, 10699.358038517714, 41579.376309355386, 41752.791236043624, 41752.79123604393, 41765.0, 41801.0, 4203.159368126387, 4207.158568286324, 4229.2598795210715, 4277.358396240589, 4305.9784701076605, 28500.572491412644, 28695.71304286972, 28711.0, 29035.41929161405, 29731.0, 4301.677374196947, 4366.8034938427845, 4369.737815731066, 4412.205802955466, 4461.531539188403, 243005.0, 243176.0, 243749.99999999822, 244211.0, 244949.0, 51613.483865160866, 51669.22496162561, 51756.48243517517, 51764.48235517672, 51770.74114629403, 53511.732441338325, 53563.0, 53739.0, 53782.0, 53889.46110538923, 14817.40730370773, 15232.086074835544, 15250.0, 15265.923670381577, 15274.770878436831, 10132.797344053077, 10291.794164116676, 11992.400379980965, 13160.934195329153, 13174.802377964339, 12924.870751292554, 12931.0, 12967.416466259041, 12997.545085921947, 13052.412641430969, 5283.392409872837, 5290.867728306823, 5295.4969277918535, 5300.4945796073025, 5358.812441564604, 1201.7956947318803, 1206.3847437806605, 1219.0978675779859, 1234.4876876096318, 1240.55340077572, 27263.45473090567, 27266.31834204121, 27289.181324560293, 27462.45075098487, 27588.44823103527, 1271.2944315904576, 1284.5054653958293, 1292.915960462567, 1310.60026691858, 1313.1333320008748, 14634.268286585628, 14648.797831870635, 14729.926350368394, 14731.926340368229, 14738.705225895423, 314426.0, 315488.0, 316338.0, 316446.0, 316546.0, 53680.46319536833, 53841.1923821143, 53854.46145538573, 53888.73055634775, 54036.18945715739, 1420.375034984624, 1465.5365265591083, 1468.3759402254213, 1475.7860110283948, 1500.8048953635957, 24828.131015415096, 26058.478830422908, 26276.0, 26288.0, 26543.0, 41571.1685766289, 41678.1664366717, 41809.0, 42038.0, 42205.155896882505, 281548.0, 281717.0, 281923.0, 282366.0, 282593.0, 28900.710992889803, 29577.556336655398, 33454.0, 33703.83148084244, 34797.0, 43114.353284700126, 43130.35304470373, 43210.13579728325, 43211.56788432076, 43260.35109473298, 33600.831995839864, 33741.0, 33936.660633394335, 33987.83006084954, 34228.657713422544, 33185.83407082949, 33555.23195340125, 33688.1577960553, 33717.49423758694, 33874.49188262227, 37872.24255514929, 38682.0, 38903.80548097241, 39136.0, 39247.018824529616, 31662.36675266436, 32039.67960320367, 32396.0, 32854.67145328516, 32956.17609559684, 24429.877850610632, 24993.250202493953, 25740.227793166236, 25778.871105644354, 26239.4752104953], "value": 18827.101463877363, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.6620568368039, 460.89860230749235, 464.50530185352596, 469.1414711078724, 476.5044353871973, 15229.771553426679, 15728.921355393235, 21133.04901279442, 21186.152553897846, 21915.99999999998, 21528.56942861142, 21912.45218869529, 21998.34004979849, 22136.557268854613, 22219.333419997427, 6665.0, 6962.965185174093, 7109.751158709446, 7113.715451381939, 7117.217106118323, 6207.906881396782, 6764.154480689914, 6987.0, 7076.080109585747, 7109.715611375565, 6824.863502729967, 7094.858102837966, 7112.4665650076195, 7114.217436082027, 7114.964425177867, 6879.828004299882, 6911.2397636259975, 6921.619310937922, 6931.514793964425, 6971.721131154748, 6593.967030164867, 6719.63042032688, 6753.561018533829, 6761.661916904135, 6763.222229443628, 26268.73731262682, 26298.6055209172, 26395.736042639714, 26436.206913792616, 26553.73446265532, 3590.838412271454, 3712.7586706864236, 3762.6613604775703, 3772.5472943246714, 3775.528058992626, 16751.916240418843, 16886.74669879953, 17173.0, 17571.385001524886, 17881.37415190462, 2221.580498685578, 2284.156647781243, 2307.849989750661, 2360.1739391212936, 2407.0, 97636.0, 114021.0, 114661.426692866, 115541.422292888, 115847.0, 19547.413577592695, 19997.0, 20313.390598282076, 20489.2828750993, 20965.580688386148, 37487.062823429085, 37557.62442375596, 37600.43599346012, 37621.43567846485, 37631.62368376336, 10713.339432387993, 10845.566177352976, 10904.672859814218, 10907.83638245427, 11059.72350691223, 8885.911140888638, 8908.420952638058, 8954.104589540993, 9159.0, 9180.8622870657, 10040.79918401628, 10759.56961721538, 10866.61966831157, 10872.238943274053, 10895.746989096197, 3576.803275819855, 3614.4759009943937, 3876.8061596920043, 4103.138340948434, 4120.75275483472, 989.851522271665, 999.860019597257, 1008.369768894441, 1016.5425558498557, 1016.7813920007234, 23072.038876695333, 23484.0, 23594.0, 23941.64087538688, 24047.158349457684, 1002.7994401119805, 1016.1362841584594, 1016.6552043775895, 1021.3310281765456, 1028.0695970147028, 12695.746085078246, 13087.280199588924, 13098.476060957455, 13287.13633613812, 13473.326333683277, 706731.0, 706982.0, 707077.0, 710332.0, 718397.0, 109124.0, 109203.45398272958, 111229.44385278021, 112360.0, 112425.0, 1289.1749280460513, 1294.3916359311108, 1331.034999625264, 1343.0867010432999, 1346.3941226448333, 19600.333971612574, 20040.198392064154, 20440.28459003928, 20469.488262793548, 20589.691154632394, 43568.782156089015, 49009.50990490192, 49861.750691246314, 50580.0, 50928.23607645814, 64237.0, 64293.0, 64353.356466436606, 65459.0, 65513.34486655263, 10948.233623646269, 11141.888581114084, 11542.0, 11699.23954942926, 12003.63989080329, 13673.179609223478, 13767.586972390844, 13778.104423212459, 13787.103838250481, 13803.309834508435, 11140.665780026613, 12139.0, 12294.446749896097, 12410.689732756753, 12664.87335126637, 11594.5362185515, 11805.645830625095, 12022.218555793845, 12068.094892883244, 12091.334976576372, 12958.28729419891, 13001.934990325366, 13018.15382000167, 13020.153690010498, 13065.281409522568, 11145.88854111481, 12236.388180590757, 12246.448909798903, 12739.872601274239, 12749.745005099661, 12705.682357940756, 12727.554535591209, 12752.872471275168, 12776.488940442277, 12785.744285114431], "value": 11812.048462711702, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1562.8046494188227, 1567.6472793621429, 1573.6773961337924, 1584.397928787061, 1595.0828273742593, 10683.732906677327, 11403.600873969413, 11407.714807129816, 11416.31502109873, 11420.714482137952, 11299.209055366127, 11599.130065245097, 11961.0, 11976.281423114619, 11978.700532486691, 2424.733279339271, 2458.5451691437142, 2459.4958033603157, 2461.655368248447, 2463.581191197492, 2297.310806757974, 2385.0698227691214, 2389.0324418610403, 2395.592749232626, 2398.07674045492, 2231.107556977206, 2265.422317309084, 2271.2277825539313, 2426.708794944609, 2436.7319594844553, 2250.246167533878, 2283.1666441748725, 2289.7252329720372, 2299.666548350488, 2412.975870241293, 2327.1156960354956, 2350.3301559055585, 2366.7751563601432, 2368.585497537941, 2370.0519792083305, 11446.771064578661, 11585.246958947726, 11637.650870473899, 11653.475593598308, 11657.591984280509, 5839.416058394169, 5993.0710739835395, 6008.3390827009, 6009.0, 6016.308124565643, 3437.914052148691, 3500.964990350115, 3530.858765649371, 3564.32277867207, 3565.964340356589, 1262.7663882181735, 1304.2826445454962, 1327.1307293722627, 1328.6080606221187, 1368.4457794593206, 15080.773788393182, 15410.460633877872, 15837.366505339773, 17531.0, 17781.288748450046, 4681.8361357353015, 4808.783604737795, 4827.2035114206465, 4835.1296766581645, 4873.366462359942, 10619.20355973305, 10904.61833835812, 13716.862831371558, 13727.862721372858, 13746.450141994408, 4912.066707325596, 5561.110222364402, 5715.914261286084, 5769.711514424262, 5770.394108618619, 5602.6918519481005, 5615.241942337732, 5624.325080990226, 5676.687782171937, 5692.089265717465, 4052.5947405259826, 4124.628783409507, 4134.4005119258145, 4194.412782210493, 4201.852935147255, 1100.471773548701, 1110.2062025651699, 1110.6834552152598, 1126.808442564766, 1130.0789856267281, 891.3315013739696, 919.7516670498928, 922.262190247799, 950.6150009246267, 1097.8298363753631, 6906.171259448898, 7009.088818453569, 7106.111736032996, 7145.428365730729, 7308.0, 1111.6442738323658, 1112.360392774142, 1128.2779021426293, 1138.385271953136, 1147.5008371358485, 5014.398272207359, 5108.897822043538, 5158.484151584887, 5163.66436181647, 5264.473552644705, 14383.28083595816, 14749.557513274618, 14808.0, 14867.628309292355, 14883.702325953636, 14093.78859317111, 14571.489997850025, 14579.489717859824, 14732.263386830615, 15325.693486130438, 10600.893991060146, 10791.622293219856, 10845.566177352976, 10866.402347870942, 10868.076213521781, 6653.63405012729, 6852.246252912076, 6916.55042422241, 6936.410405115622, 7018.684159212949, 13187.670308241986, 13262.668433289246, 13512.59462216135, 13551.0, 13564.86435135636, 12714.554990575285, 12872.16330938486, 13043.086983911415, 13670.794938075738, 13689.24709140967, 9547.665831695856, 9661.661841835783, 9851.359661621971, 9886.50567471638, 9893.55479003461, 10220.846687299547, 10282.845757313797, 10322.2258330624, 10401.791964160522, 10406.167506599606, 9143.908560914306, 9275.814483710423, 9285.535723213678, 9338.626454941998, 9485.905140948778, 9035.051319611346, 9069.274458043208, 9109.453432794053, 9122.452652840851, 9245.815083698153, 8142.307903828004, 8271.172882711922, 8295.211954864279, 8723.77867098594, 8969.0, 6520.673966301761, 6541.672916354259, 6581.308962558863, 6728.889733193968, 6920.515563910478, 6725.697343619451, 6730.293319201413, 6730.730770769114, 6857.5199736018, 6885.2770459101075], "value": 5416.053355140829, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.09199531043413, 416.79368712487326, 433.13589389168595, 434.6696510651904, 437.59303847421893, 5144.279800827882, 5277.868053298665, 5288.2596436498825, 5355.446920393086, 5358.946410535894, 5025.773840177191, 5191.792328306863, 5203.791848326063, 5248.5538729207965, 5250.606204534656, 4388.297872340426, 4388.97805510972, 4392.846250381237, 4405.933910991338, 4413.249747542925, 4414.249577571821, 4424.646028317743, 4428.911421771579, 4430.180416622935, 4431.335299705038, 4285.4214681017975, 4342.891427714301, 4398.296272596385, 4425.203463376591, 4452.866414007569, 4335.739855608674, 4352.606066663354, 4362.8438463807115, 4373.890652733691, 4383.4739831220295, 4076.0, 4126.195391898581, 4156.709030367845, 4162.146759914211, 4173.747875637297, 4904.705717656952, 5030.874228144326, 5040.823571174991, 5152.433232344477, 5156.432792392871, 5063.164577844688, 5071.974640126813, 5093.745312734385, 5127.410347810012, 5141.408737995103, 5425.83344580917, 5504.284443022381, 5600.327960644708, 5607.8878422431735, 5617.38208797032, 4455.532169122228, 4554.4990051094355, 4557.789472106059, 4589.518100599423, 4592.265237562007, 5435.347758268994, 5544.63959842609, 5562.554995600382, 5698.0, 5713.971430142864, 4720.88197795051, 4723.95276047242, 4735.881602959885, 4749.622609443292, 4759.405074365704, 4562.270036794097, 4635.791389387486, 4805.399325084364, 4832.637552183601, 4862.149123903303, 5021.372328458942, 5063.367079115111, 5077.238414237894, 5093.191916869532, 5096.184610462308, 5489.972550137303, 5497.5601951843755, 5513.807016754395, 5543.944560554342, 5592.664440133605, 1557.6651020030747, 1640.7702921590987, 1661.7008938390964, 1667.940857555461, 1675.2628843308905, 1245.9222772302076, 1265.7848165811833, 1270.8220849081135, 1283.4866053578457, 1287.2147989726259, 5372.140457526777, 5415.0, 5421.484958928895, 5439.782408703687, 5441.673499589958, 5676.148577713294, 5687.317521897398, 5715.0, 5720.742566584514, 5727.856803579861, 5380.139177731544, 5417.702026388586, 5471.316085489314, 5497.587680923867, 5510.201020852034, 2975.9256018599276, 2994.7304742573274, 2998.0, 2998.9550156747664, 3000.2799328161523, 5579.2746942897975, 5740.052891272893, 5893.705314734332, 5924.940750592526, 5934.376890426443, 5690.573207009491, 5739.995500787346, 5744.195812586241, 5757.395473475309, 5770.221020162225, 5888.910551547978, 5891.0, 5897.882042359214, 5898.233229680114, 5922.496587790086, 5319.893602128013, 5619.0, 5623.3533143689, 5635.8591035224445, 5677.659340439587, 5376.25810836484, 5475.589330800126, 5478.91781623268, 5594.608377413705, 5630.971845140748, 5883.440888164725, 5910.852228694317, 5912.67480288571, 5912.674802885883, 5927.970360148344, 4578.221702310549, 4616.261398176276, 4702.341672165831, 4749.382580264543, 4789.95210047909, 5521.217965693513, 5545.639533430314, 5585.832425027255, 5606.187102870143, 5624.625224865212, 5427.402985671652, 5436.864078398072, 5497.780088796403, 5531.8893622126525, 5542.226487523953, 5333.866653333697, 5426.918596220981, 5471.917921231265, 5478.534324582296, 5501.642393244587, 5363.731813409392, 5387.407385187705, 5396.9190462142315, 5406.972965135149, 5407.83776486706, 5444.564434845282, 5493.747563498946, 5512.944870551243, 5514.834554963358, 5520.944790552043, 5153.5361817436615, 5318.388385335736, 5363.892722145613, 5399.973000135132, 5477.454441941842], "value": 4433.640861746773, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.3367474394204, 895.1853813030144, 897.407710910799, 919.0946917286473, 924.6486335192626, 16259.512214633565, 16695.0, 17226.82773172268, 17519.47441576755, 17607.031613261282, 15365.308561114749, 15444.459443919464, 16175.919120404382, 16327.346906123741, 16354.264058117384, 873.8077622922947, 883.266888482558, 899.6491368366343, 903.5978989349742, 904.5341649050744, 906.8911730592338, 909.4497828813571, 910.1308250620674, 910.1944778870713, 910.8724778531011, 685.5269863793991, 687.4191308344441, 687.9484038697093, 688.1260798785539, 688.3357559954643, 658.7068754404291, 661.7948435984836, 662.0036844548947, 666.4634968850074, 668.9096971908779, 483.14724511237694, 484.5082241524855, 488.975551222441, 492.2984746735887, 495.0322120254893, 15612.141332226724, 15732.134732589702, 17126.914365428216, 17211.397601083903, 17258.30966761328, 15684.92157539205, 15784.842151578452, 15880.920595396949, 16133.83866161335, 16434.0, 12923.0, 13324.600261992156, 13338.0, 13497.730045399136, 13534.917206623446, 3912.608739126093, 3963.1083006323347, 3967.98016009921, 4056.9594304057173, 4108.979455102735, 82857.58571207106, 83256.16743832544, 84148.0, 84814.15185848186, 86190.0, 11600.18798684084, 11677.416129193676, 11730.76538469243, 11757.059435245159, 11781.410929453494, 20595.382138535868, 20743.377698669065, 20864.165433382797, 20910.790892091187, 21013.0, 8623.223909848144, 8697.739067827975, 8830.426022308659, 8963.865542016874, 9043.276537876955, 4824.204006338985, 4825.396825396825, 4878.390201224847, 4964.255361695775, 4994.900101998012, 3563.3407819553468, 3660.2862441824122, 3666.651668091527, 3667.5598928128798, 3669.541307336583, 599.2090440618342, 607.2318517075903, 612.9233845769279, 613.0773186354508, 613.5183880653755, 737.0896942276285, 741.1254719431123, 754.5057987018512, 769.3268390158611, 793.0641842625757, 10497.13564907836, 10562.366258024544, 10646.680599582025, 10757.0, 10908.290961087503, 712.6151877985933, 723.0311382747144, 724.5978481942467, 738.8042168825266, 778.2140038561095, 5115.283860259567, 5234.947650523446, 5243.9213411798855, 5253.947460525423, 5269.077911365495, 63739.6813015932, 66199.66900165468, 70870.0, 72815.0, 78189.60905195438, 64040.0, 65130.0, 65483.345166547726, 66985.0, 67302.0, 61188.38811611916, 61635.0, 62020.689896550226, 62235.37764622386, 62254.37745622576, 11600.24598401135, 11857.229280096766, 11992.340421276913, 12182.695432614255, 12240.265584064984, 40987.38518922159, 41696.0, 41839.37240941449, 42210.0, 43110.95645257556, 47798.76100619475, 47826.5217347822, 48415.5158448411, 48444.515554844, 50268.994620108126, 12404.0, 12629.115961882948, 12639.3680315982, 12740.108192426442, 13000.219986800823, 13782.0, 14495.0, 15215.315310811262, 15261.0, 16430.01419914809, 14523.491677791226, 14975.475858344906, 15572.92213538925, 16116.0, 17019.659606808043, 14749.188794616399, 14817.40730370773, 14952.331167699525, 15431.418411773764, 15645.765313520078, 13675.24786136772, 13842.875662466304, 14172.503962361268, 14283.0, 14438.566842994727, 10830.241883068109, 10930.180236482136, 10981.725456863323, 11035.227534072537, 11258.437078145897, 11288.153388495732, 11348.432578370883, 11647.88352116468, 11678.88321116778, 11725.237859539102], "value": 7423.792695694547, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1455.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 112.0, 116.0, 112.0, 111.0, 112.0, 111.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 114.0, 116.0, 158.0, 115.0, 120.0, 115.0, 115.0, 136.0, 116.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 159.0, 161.0, 160.0, 161.0, 161.0, 159.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 111.0, 75.0, 75.0, 77.0, 77.0, 95.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 107.0, 82.0, 82.0, 82.0, 96.0, 88.0, 82.0, 101.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 66.0, 56.0, 55.0, 56.0, 56.0, 55.0, 55.0, 55.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 52.0, 51.0, 52.0, 52.0, 51.0, 51.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 78.0, 81.0, 80.0, 79.0, 75.0, 78.0, 75.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 120.0, 121.0, 132.0, 125.0, 119.0, 125.0, 119.0, 158.0, 137.0], "value": 128.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 164.0, 159.0, 165.0, 165.0, 161.0, 159.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 63.0, 59.0, 61.0, 63.0, 58.0, 59.0, 58.0], "value": 60.8, "unit": "score"}]}]} 08:28:26 INFO - Return code: 0 08:28:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:28:26 ERROR - # TBPL SUCCESS # 08:28:26 INFO - Running post-action listener: _resource_record_post_action 08:28:26 INFO - [mozharness: 2017-04-06 15:28:26.278291Z] Finished run-tests step (success) 08:28:26 INFO - Running post-run listener: _resource_record_post_run 08:28:26 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 371458048; Read time: 20; Write time: 267976 08:28:26 INFO - TinderboxPrint: CPU usage
13.1% 08:28:26 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 08:28:26 INFO - TinderboxPrint: I/O write bytes / time
371,458,048 / 267,976 08:28:26 INFO - TinderboxPrint: CPU idle
8,270.2 (86.9%) 08:28:26 INFO - TinderboxPrint: CPU user
1,202.6 (12.6%) 08:28:26 INFO - TinderboxPrint: Swap in / out
0 / 0 08:28:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118116352; Read time: 0; Write time: 120312 08:28:26 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 253341696; Read time: 20; Write time: 147664 08:28:26 INFO - Running post-run listener: _upload_blobber_files 08:28:26 INFO - Blob upload gear active. 08:28:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:28:26 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 08:28:26 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'] 08:28:26 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 08:28:27 INFO - (blobuploader) - INFO - Open directory for files ... 08:28:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:28:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:28:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:31 INFO - (blobuploader) - INFO - Done attempting. 08:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:28:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:31 INFO - (blobuploader) - INFO - Done attempting. 08:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:28:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:32 INFO - (blobuploader) - INFO - Done attempting. 08:28:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:28:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:28:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:33 INFO - (blobuploader) - INFO - Done attempting. 08:28:33 INFO - (blobuploader) - INFO - Iteration through files over. 08:28:33 INFO - Return code: 0 08:28:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:28:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:28:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f896538db3933ad19beb5df4fec721587a360a57d9c6a3c89d2069a2cfabc8f722b77075f602fc8e2d5bc000df09520bee50708ee67d63c6ebc14e73671d5346", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3db2c4f4dfa4b793d0494f7a2919b64e3c10a6d30a17a0a192062aa5d6ecb31328b22409e8444861bd67da8632f5bad9c83e5cf1b02974f019504ae5d44eec0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b187d1a8fa1afb716f82155fe80b23098b8c5a9ff9b96155eba707d0ddaf8472d1d168d2bfcc03b7d8ab5f13a42746dc76c63a7d2c627e402a1271343a0af7fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeefcbd3b298ebc9819d80814b642d395f81eebbe13790a4ef43c723a68b710732e11d3da02420da45adc7c24fc4176ab2beaffe8a06755da4c645da87f9fe3f"} 08:28:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:28:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:28:33 INFO - Contents: 08:28:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f896538db3933ad19beb5df4fec721587a360a57d9c6a3c89d2069a2cfabc8f722b77075f602fc8e2d5bc000df09520bee50708ee67d63c6ebc14e73671d5346", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3db2c4f4dfa4b793d0494f7a2919b64e3c10a6d30a17a0a192062aa5d6ecb31328b22409e8444861bd67da8632f5bad9c83e5cf1b02974f019504ae5d44eec0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b187d1a8fa1afb716f82155fe80b23098b8c5a9ff9b96155eba707d0ddaf8472d1d168d2bfcc03b7d8ab5f13a42746dc76c63a7d2c627e402a1271343a0af7fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeefcbd3b298ebc9819d80814b642d395f81eebbe13790a4ef43c723a68b710732e11d3da02420da45adc7c24fc4176ab2beaffe8a06755da4c645da87f9fe3f"} 08:28:33 INFO - Running post-run listener: copy_logs_to_upload_dir 08:28:33 INFO - Copying logs to upload dir... 08:28:33 INFO - mkdir: /builds/slave/test/build/upload/logs 08:28:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.125833 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-04-06 08:28:33.109423) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 08:28:33.113328) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707 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/f896538db3933ad19beb5df4fec721587a360a57d9c6a3c89d2069a2cfabc8f722b77075f602fc8e2d5bc000df09520bee50708ee67d63c6ebc14e73671d5346", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3db2c4f4dfa4b793d0494f7a2919b64e3c10a6d30a17a0a192062aa5d6ecb31328b22409e8444861bd67da8632f5bad9c83e5cf1b02974f019504ae5d44eec0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b187d1a8fa1afb716f82155fe80b23098b8c5a9ff9b96155eba707d0ddaf8472d1d168d2bfcc03b7d8ab5f13a42746dc76c63a7d2c627e402a1271343a0af7fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeefcbd3b298ebc9819d80814b642d395f81eebbe13790a4ef43c723a68b710732e11d3da02420da45adc7c24fc4176ab2beaffe8a06755da4c645da87f9fe3f"} build_url:https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011294 build_url: 'https://queue.taskcluster.net/v1/task/CJsdSQTZQAiLYTiwgCyCXg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f896538db3933ad19beb5df4fec721587a360a57d9c6a3c89d2069a2cfabc8f722b77075f602fc8e2d5bc000df09520bee50708ee67d63c6ebc14e73671d5346", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3db2c4f4dfa4b793d0494f7a2919b64e3c10a6d30a17a0a192062aa5d6ecb31328b22409e8444861bd67da8632f5bad9c83e5cf1b02974f019504ae5d44eec0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b187d1a8fa1afb716f82155fe80b23098b8c5a9ff9b96155eba707d0ddaf8472d1d168d2bfcc03b7d8ab5f13a42746dc76c63a7d2c627e402a1271343a0af7fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeefcbd3b298ebc9819d80814b642d395f81eebbe13790a4ef43c723a68b710732e11d3da02420da45adc7c24fc4176ab2beaffe8a06755da4c645da87f9fe3f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 08:28:33.139154) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:28:33.139486) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1491491238.382983-248035707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004366 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:28:33.224670) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 08:28:33.224995) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 08:28:33.225508) ========= ========= Total master_lag: 0.15 =========