builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-059 starttime: 1484188733.45 results: success (0) revision: d9361bf7a173d95afd1f399ca5ab78ecec0f56fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.445742) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.446188) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.447732) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.493350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.493763) ========= 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=3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-11 18:38:53-- 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% 43.4M=0s 2017-01-11 18:38:54 (43.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.626072) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.626442) ========= 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=3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017138 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.675964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:53.676443) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev d9361bf7a173d95afd1f399ca5ab78ecec0f56fd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/graphics --rev d9361bf7a173d95afd1f399ca5ab78ecec0f56fd --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=3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-11 18:38:54,178 truncating revision to first 12 chars 2017-01-11 18:38:54,178 Setting DEBUG logging. 2017-01-11 18:38:54,178 attempt 1/10 2017-01-11 18:38:54,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/d9361bf7a173?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 18:38:54,756 unpacking tar archive at: graphics-d9361bf7a173/testing/mozharness/ program finished with exit code 0 elapsedTime=0.905384 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:54.595425) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:54.595744) ========= script_repo_revision: d9361bf7a173d95afd1f399ca5ab78ecec0f56fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:54.596182) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:54.596540) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 18:38:54.610405) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-01-11 18:38:54.610724) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:38:55 INFO - MultiFileLogger online at 20170111 18:38:55 in /builds/slave/test 18:38:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 18:38:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:38:55 INFO - {'append_to_log': False, 18:38:55 INFO - 'base_work_dir': '/builds/slave/test', 18:38:55 INFO - 'blob_upload_branch': 'Graphics', 18:38:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:38:55 INFO - 'branch': 'Graphics', 18:38:55 INFO - 'buildbot_json_path': 'buildprops.json', 18:38:55 INFO - 'config_files': ('talos/linux_config.py',), 18:38:55 INFO - 'default_actions': ('clobber', 18:38:55 INFO - 'read-buildbot-config', 18:38:55 INFO - 'download-and-extract', 18:38:55 INFO - 'populate-webroot', 18:38:55 INFO - 'create-virtualenv', 18:38:55 INFO - 'install', 18:38:55 INFO - 'run-tests'), 18:38:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:38:55 INFO - 'download_minidump_stackwalk': True, 18:38:55 INFO - 'download_symbols': 'ondemand', 18:38:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:38:55 INFO - 'tooltool.py': '/tools/tooltool.py', 18:38:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:38:55 INFO - '/tools/misc-python/virtualenv.py')}, 18:38:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:38:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:38:55 INFO - 'installer_path': 'installer.exe', 18:38:55 INFO - 'log_level': 'info', 18:38:55 INFO - 'log_name': 'talos', 18:38:55 INFO - 'log_to_console': True, 18:38:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:38:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:38:55 INFO - 'opt_config_files': (), 18:38:55 INFO - 'pip_index': False, 18:38:55 INFO - 'sps_profile': False, 18:38:55 INFO - 'sps_profile_interval': 0, 18:38:55 INFO - 'suite': 'dromaeojs', 18:38:55 INFO - 'system_bits': '32', 18:38:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:38:55 INFO - 'title': 'talos-linux64-ix-059', 18:38:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:38:55 INFO - 'use_talos_json': True, 18:38:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:38:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:38:55 INFO - 'work_dir': 'build'} 18:38:55 INFO - [mozharness: 2017-01-12 02:38:55.221970Z] Running clobber step. 18:38:55 INFO - Running pre-action listener: _resource_record_pre_action 18:38:55 INFO - Running main action method: clobber 18:38:55 INFO - rmtree: /builds/slave/test/build 18:38:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:38:56 INFO - Running post-action listener: _resource_record_post_action 18:38:56 INFO - [mozharness: 2017-01-12 02:38:56.201305Z] Finished clobber step (success) 18:38:56 INFO - [mozharness: 2017-01-12 02:38:56.201408Z] Running read-buildbot-config step. 18:38:56 INFO - Running pre-action listener: _resource_record_pre_action 18:38:56 INFO - Running main action method: read_buildbot_config 18:38:56 INFO - Using buildbot properties: 18:38:56 INFO - { 18:38:56 INFO - "project": "", 18:38:56 INFO - "product": "firefox", 18:38:56 INFO - "who": "rhunt@eqrion.net", 18:38:56 INFO - "installer_path": "public/build/target.tar.bz2", 18:38:56 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 18:38:56 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 18:38:56 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 18:38:56 INFO - "stage_platform": "linux64", 18:38:56 INFO - "basedir": "/builds/slave/test", 18:38:56 INFO - "buildnumber": 50, 18:38:56 INFO - "platform": "ubuntu64_hw", 18:38:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:38:56 INFO - "slavebuilddir": "test", 18:38:56 INFO - "taskId": "AY7y5i8_QoSeGdgIlWkGsQ", 18:38:56 INFO - "branch": "graphics", 18:38:56 INFO - "script_repo_revision": "default", 18:38:56 INFO - "revision": "d9361bf7a173d95afd1f399ca5ab78ecec0f56fd", 18:38:56 INFO - "slavename": "talos-linux64-ix-059", 18:38:56 INFO - "repo_path": "projects/graphics" 18:38:56 INFO - } 18:38:56 INFO - Finding installer, test and symbols from parent task. 18:38:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AY7y5i8_QoSeGdgIlWkGsQ'}, attempt #1 18:38:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AY7y5i8_QoSeGdgIlWkGsQ'}, attempt #1 18:38:57 INFO - Task dependencies: cGzvhvSmRkaL7-FrPINIOA 18:38:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA'}, attempt #1 18:38:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2 18:38:57 INFO - Set test_url: https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/test_packages.json 18:38:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.crashreporter-symbols.zip 18:38:57 INFO - Running post-action listener: _resource_record_post_action 18:38:57 INFO - [mozharness: 2017-01-12 02:38:57.624250Z] Finished read-buildbot-config step (success) 18:38:57 INFO - [mozharness: 2017-01-12 02:38:57.624468Z] Running download-and-extract step. 18:38:57 INFO - Running pre-action listener: _resource_record_pre_action 18:38:57 INFO - Running main action method: download_and_extract 18:38:57 INFO - mkdir: /builds/slave/test/build/tests 18:38:57 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')]} 18:38:57 INFO - trying https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.test_packages.json 18:38:57 INFO - Downloading https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:38:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:38:58 INFO - Downloaded 896 bytes. 18:38:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:38:58 INFO - Using the following test package requirements: 18:38:58 INFO - {u'common': [u'target.common.tests.zip'], 18:38:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:38:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:38:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:38:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:38:58 INFO - u'mozbase': [u'target.common.tests.zip'], 18:38:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:38:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:38:58 INFO - u'web-platform': [u'target.common.tests.zip', 18:38:58 INFO - u'target.web-platform.tests.zip'], 18:38:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:38:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:38:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.common.tests.zip 18:38:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:38:58 INFO - Fetch https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.common.tests.zip into memory 18:38:59 INFO - Expected file size: 20257948 18:38:59 INFO - Obtained file size: 20257948 18:39:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:39:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.common.tests.zip 18:39:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:39:02 INFO - Fetch https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.common.tests.zip into memory 18:39:03 INFO - Expected file size: 20257948 18:39:03 INFO - Obtained file size: 20257948 18:39:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.talos.tests.zip 18:39:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:39:08 INFO - Fetch https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.talos.tests.zip into memory 18:39:08 INFO - Expected file size: 14403529 18:39:08 INFO - Obtained file size: 14403529 18:39:09 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')]} 18:39:09 INFO - trying https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2 18:39:09 INFO - Downloading https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:39:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:39:10 INFO - Downloaded 56038475 bytes. 18:39:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2 18:39:10 INFO - mkdir: /builds/slave/test/properties 18:39:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:39:10 INFO - Writing to file /builds/slave/test/properties/build_url 18:39:10 INFO - Contents: 18:39:10 INFO - build_url:https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2 18:39:10 INFO - Running post-action listener: _resource_record_post_action 18:39:10 INFO - Running post-action listener: set_extra_try_arguments 18:39:10 INFO - [mozharness: 2017-01-12 02:39:10.690645Z] Finished download-and-extract step (success) 18:39:10 INFO - [mozharness: 2017-01-12 02:39:10.690742Z] Running populate-webroot step. 18:39:10 INFO - Running pre-action listener: _resource_record_pre_action 18:39:10 INFO - Running main action method: populate_webroot 18:39:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:39:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:39:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:39:10 INFO - '--tppagecycles', 18:39:10 INFO - '7'], 18:39:10 INFO - 'tests': ['tsvgm']}}, 18:39:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:39:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:39:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:39:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:39:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:39:10 INFO - 'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:39:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:39:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:39:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:39:10 INFO - 'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['damp', 'tps']}, 18:39:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:39:10 INFO - 'tests': ['damp', 'tps']}, 18:39:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['dromaeo_dom']}, 18:39:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:39:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['basic_compositor_video']}, 18:39:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:39:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['a11yr', 18:39:10 INFO - 'ts_paint', 18:39:10 INFO - 'tpaint', 18:39:10 INFO - 'sessionrestore', 18:39:10 INFO - 'sessionrestore_no_auto_restore', 18:39:10 INFO - 'tabpaint']}, 18:39:10 INFO - 'other-e10s': {'tests': ['a11yr', 18:39:10 INFO - 'ts_paint', 18:39:10 INFO - 'tpaint', 18:39:10 INFO - 'sessionrestore', 18:39:10 INFO - 'sessionrestore_no_auto_restore', 18:39:10 INFO - 'tabpaint']}, 18:39:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:39:10 INFO - 'ts_paint', 18:39:10 INFO - 'tpaint', 18:39:10 INFO - 'sessionrestore', 18:39:10 INFO - 'sessionrestore_no_auto_restore', 18:39:10 INFO - 'tabpaint']}, 18:39:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:39:10 INFO - 'ts_paint', 18:39:10 INFO - 'tpaint', 18:39:10 INFO - 'sessionrestore', 18:39:10 INFO - 'sessionrestore_no_auto_restore', 18:39:10 INFO - 'tabpaint']}, 18:39:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['a11yr', 18:39:10 INFO - 'ts_paint', 18:39:10 INFO - 'tpaint', 18:39:10 INFO - 'sessionrestore', 18:39:10 INFO - 'sessionrestore_no_auto_restore', 18:39:10 INFO - 'tabpaint']}, 18:39:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['a11yr', 18:39:10 INFO - 'ts_paint', 18:39:10 INFO - 'tpaint', 18:39:10 INFO - 'sessionrestore', 18:39:10 INFO - 'sessionrestore_no_auto_restore', 18:39:10 INFO - 'tabpaint']}, 18:39:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['tsvgx', 18:39:10 INFO - 'tsvgr_opacity', 18:39:10 INFO - 'tart', 18:39:10 INFO - 'tscrollx', 18:39:10 INFO - 'cart', 18:39:10 INFO - 'tsvg_static']}, 18:39:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:39:10 INFO - 'tsvgr_opacity', 18:39:10 INFO - 'tart', 18:39:10 INFO - 'tscrollx', 18:39:10 INFO - 'cart', 18:39:10 INFO - 'tsvg_static']}, 18:39:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:39:10 INFO - 'talos_options': ['--disable-e10s'], 18:39:10 INFO - 'tests': ['tp5o']}, 18:39:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:39:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:39:10 INFO - 'talos_options': ['--disable-e10s', 18:39:10 INFO - '--xperf_path', 18:39:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:39:10 INFO - 'tests': ['tp5n']}, 18:39:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:39:10 INFO - 'talos_options': ['--xperf_path', 18:39:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:39:10 INFO - 'tests': ['tp5n']}}, 18:39:10 INFO - 'talos.zip': {'path': '', 18:39:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:39:10 INFO - Running post-action listener: _resource_record_post_action 18:39:10 INFO - [mozharness: 2017-01-12 02:39:10.703842Z] Finished populate-webroot step (success) 18:39:10 INFO - [mozharness: 2017-01-12 02:39:10.703935Z] Running create-virtualenv step. 18:39:10 INFO - Running pre-action listener: _resource_record_pre_action 18:39:10 INFO - Running main action method: create_virtualenv 18:39:10 INFO - Creating virtualenv /builds/slave/test/build/venv 18:39: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 18:39:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:39:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:39:10 INFO - Using real prefix '/usr' 18:39:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:39:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:39:13 INFO - Installing pip.................done. 18:39:13 INFO - Return code: 0 18:39:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:39:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:39:13 INFO - Reading from file tmpfile_stdout 18:39:13 INFO - Output received: 18:39: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) 18:39:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39: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 18:39: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 18:39:13 INFO - Using env: {'DISPLAY': ':0', 18:39:13 INFO - 'HOME': '/home/cltbld', 18:39:13 INFO - 'LANG': 'en_US.UTF-8', 18:39:13 INFO - 'LANGUAGE': 'en_US:en', 18:39:13 INFO - 'LOGNAME': 'cltbld', 18:39:13 INFO - 'MAIL': '/var/mail/cltbld', 18:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:13 INFO - 'MOZ_NO_REMOTE': '1', 18:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:13 INFO - 'NO_EM_RESTART': '1', 18:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:13 INFO - 'PWD': '/builds/slave/test', 18:39:13 INFO - 'SHELL': '/bin/bash', 18:39:13 INFO - 'SHLVL': '1', 18:39:13 INFO - 'TERM': 'linux', 18:39:13 INFO - 'TMOUT': '86400', 18:39:13 INFO - 'USER': 'cltbld', 18:39:13 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:13 INFO - '_': '/tools/buildbot/bin/python'} 18:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39: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 18:39:13 INFO - Cleaning up... 18:39:13 INFO - Return code: 0 18:39:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39: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 18:39: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 18:39:13 INFO - Using env: {'DISPLAY': ':0', 18:39:13 INFO - 'HOME': '/home/cltbld', 18:39:13 INFO - 'LANG': 'en_US.UTF-8', 18:39:13 INFO - 'LANGUAGE': 'en_US:en', 18:39:13 INFO - 'LOGNAME': 'cltbld', 18:39:13 INFO - 'MAIL': '/var/mail/cltbld', 18:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:13 INFO - 'MOZ_NO_REMOTE': '1', 18:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:13 INFO - 'NO_EM_RESTART': '1', 18:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:13 INFO - 'PWD': '/builds/slave/test', 18:39:13 INFO - 'SHELL': '/bin/bash', 18:39:13 INFO - 'SHLVL': '1', 18:39:13 INFO - 'TERM': 'linux', 18:39:13 INFO - 'TMOUT': '86400', 18:39:13 INFO - 'USER': 'cltbld', 18:39:13 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:13 INFO - '_': '/tools/buildbot/bin/python'} 18:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:13 INFO - Downloading/unpacking psutil>=3.1.1 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:39:16 INFO - Installing collected packages: psutil 18:39:16 INFO - Running setup.py install for psutil 18:39:16 INFO - building 'psutil._psutil_linux' extension 18:39:16 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 18:39: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:39:16 INFO - building 'psutil._psutil_posix' extension 18:39:16 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 18:39: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 18:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:39:16 INFO - Successfully installed psutil 18:39:16 INFO - Cleaning up... 18:39:16 INFO - Return code: 0 18:39:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39: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 18:39: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 18:39:16 INFO - Using env: {'DISPLAY': ':0', 18:39:16 INFO - 'HOME': '/home/cltbld', 18:39:16 INFO - 'LANG': 'en_US.UTF-8', 18:39:16 INFO - 'LANGUAGE': 'en_US:en', 18:39:16 INFO - 'LOGNAME': 'cltbld', 18:39:16 INFO - 'MAIL': '/var/mail/cltbld', 18:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:16 INFO - 'MOZ_NO_REMOTE': '1', 18:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:16 INFO - 'NO_EM_RESTART': '1', 18:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:16 INFO - 'PWD': '/builds/slave/test', 18:39:16 INFO - 'SHELL': '/bin/bash', 18:39:16 INFO - 'SHLVL': '1', 18:39:16 INFO - 'TERM': 'linux', 18:39:16 INFO - 'TMOUT': '86400', 18:39:16 INFO - 'USER': 'cltbld', 18:39:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:16 INFO - '_': '/tools/buildbot/bin/python'} 18:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:39:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:39:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:39:18 INFO - Installing collected packages: mozsystemmonitor 18:39:18 INFO - Running setup.py install for mozsystemmonitor 18:39:18 INFO - Successfully installed mozsystemmonitor 18:39:18 INFO - Cleaning up... 18:39:19 INFO - Return code: 0 18:39:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:39:19 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')]} 18:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:19 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')]} 18:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:19 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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39:19 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 18:39:19 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 18:39:19 INFO - Using env: {'DISPLAY': ':0', 18:39:19 INFO - 'HOME': '/home/cltbld', 18:39:19 INFO - 'LANG': 'en_US.UTF-8', 18:39:19 INFO - 'LANGUAGE': 'en_US:en', 18:39:19 INFO - 'LOGNAME': 'cltbld', 18:39:19 INFO - 'MAIL': '/var/mail/cltbld', 18:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:19 INFO - 'MOZ_NO_REMOTE': '1', 18:39:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:19 INFO - 'NO_EM_RESTART': '1', 18:39:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:19 INFO - 'PWD': '/builds/slave/test', 18:39:19 INFO - 'SHELL': '/bin/bash', 18:39:19 INFO - 'SHLVL': '1', 18:39:19 INFO - 'TERM': 'linux', 18:39:19 INFO - 'TMOUT': '86400', 18:39:19 INFO - 'USER': 'cltbld', 18:39:19 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:19 INFO - '_': '/tools/buildbot/bin/python'} 18:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:19 INFO - Downloading/unpacking jsonschema==2.5.1 18:39:19 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 18:39:19 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 18:39:19 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 18:39:19 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 18:39:19 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 18:39:19 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 18:39:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:39:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:39:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:39:21 INFO - warning: no files found matching '*.txt' 18:39:21 INFO - no previously-included directories found matching 'build' 18:39:21 INFO - no previously-included directories found matching 'dist' 18:39:21 INFO - no previously-included directories found matching '.git*' 18:39:21 INFO - Installing collected packages: jsonschema, functools32 18:39:21 INFO - Running setup.py install for functools32 18:39:21 INFO - warning: no files found matching '*.txt' 18:39:21 INFO - no previously-included directories found matching 'build' 18:39:21 INFO - no previously-included directories found matching 'dist' 18:39:21 INFO - no previously-included directories found matching '.git*' 18:39:21 INFO - Successfully installed jsonschema functools32 18:39:21 INFO - Cleaning up... 18:39:21 INFO - Return code: 0 18:39:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39: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 18:39: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 18:39:21 INFO - Using env: {'DISPLAY': ':0', 18:39:21 INFO - 'HOME': '/home/cltbld', 18:39:21 INFO - 'LANG': 'en_US.UTF-8', 18:39:21 INFO - 'LANGUAGE': 'en_US:en', 18:39:21 INFO - 'LOGNAME': 'cltbld', 18:39:21 INFO - 'MAIL': '/var/mail/cltbld', 18:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:21 INFO - 'MOZ_NO_REMOTE': '1', 18:39:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:21 INFO - 'NO_EM_RESTART': '1', 18:39:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:21 INFO - 'PWD': '/builds/slave/test', 18:39:21 INFO - 'SHELL': '/bin/bash', 18:39:21 INFO - 'SHLVL': '1', 18:39:21 INFO - 'TERM': 'linux', 18:39:21 INFO - 'TMOUT': '86400', 18:39:21 INFO - 'USER': 'cltbld', 18:39:21 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:21 INFO - '_': '/tools/buildbot/bin/python'} 18:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:39:21 INFO - Cleaning up... 18:39:21 INFO - Return code: 0 18:39:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:22 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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39:22 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 18:39:22 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 18:39:22 INFO - Using env: {'DISPLAY': ':0', 18:39:22 INFO - 'HOME': '/home/cltbld', 18:39:22 INFO - 'LANG': 'en_US.UTF-8', 18:39:22 INFO - 'LANGUAGE': 'en_US:en', 18:39:22 INFO - 'LOGNAME': 'cltbld', 18:39:22 INFO - 'MAIL': '/var/mail/cltbld', 18:39:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:22 INFO - 'MOZ_NO_REMOTE': '1', 18:39:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:22 INFO - 'NO_EM_RESTART': '1', 18:39:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:22 INFO - 'PWD': '/builds/slave/test', 18:39:22 INFO - 'SHELL': '/bin/bash', 18:39:22 INFO - 'SHLVL': '1', 18:39:22 INFO - 'TERM': 'linux', 18:39:22 INFO - 'TMOUT': '86400', 18:39:22 INFO - 'USER': 'cltbld', 18:39:22 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:22 INFO - '_': '/tools/buildbot/bin/python'} 18:39:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:22 INFO - Downloading/unpacking blobuploader==1.2.4 18:39:22 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 18:39:22 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 18:39:22 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 18:39:22 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 18:39:22 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 18:39:22 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 18:39:24 INFO - Downloading blobuploader-1.2.4.tar.gz 18:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:39:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:39:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39:24 INFO - Downloading docopt-0.6.1.tar.gz 18:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:39:25 INFO - Installing collected packages: blobuploader, requests, docopt 18:39:25 INFO - Running setup.py install for blobuploader 18:39:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:39:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:39:25 INFO - Running setup.py install for requests 18:39:25 INFO - Running setup.py install for docopt 18:39:25 INFO - Successfully installed blobuploader requests docopt 18:39:25 INFO - Cleaning up... 18:39:25 INFO - Return code: 0 18:39:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39: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 18:39: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 18:39:25 INFO - Using env: {'DISPLAY': ':0', 18:39:25 INFO - 'HOME': '/home/cltbld', 18:39:25 INFO - 'LANG': 'en_US.UTF-8', 18:39:25 INFO - 'LANGUAGE': 'en_US:en', 18:39:25 INFO - 'LOGNAME': 'cltbld', 18:39:25 INFO - 'MAIL': '/var/mail/cltbld', 18:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:25 INFO - 'MOZ_NO_REMOTE': '1', 18:39:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:25 INFO - 'NO_EM_RESTART': '1', 18:39:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:25 INFO - 'PWD': '/builds/slave/test', 18:39:25 INFO - 'SHELL': '/bin/bash', 18:39:25 INFO - 'SHLVL': '1', 18:39:25 INFO - 'TERM': 'linux', 18:39:25 INFO - 'TMOUT': '86400', 18:39:25 INFO - 'USER': 'cltbld', 18:39:25 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:25 INFO - '_': '/tools/buildbot/bin/python'} 18:39:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:39:25 INFO - Running setup.py (path:/tmp/pip-2ud0Sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:39:25 INFO - Running setup.py (path:/tmp/pip-QsfzWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:39:25 INFO - Running setup.py (path:/tmp/pip-76bjNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:39:26 INFO - Running setup.py (path:/tmp/pip-Bzq2m_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:39:26 INFO - Running setup.py (path:/tmp/pip-FHy8qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:39:26 INFO - Running setup.py (path:/tmp/pip-lLX2u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:39:26 INFO - Running setup.py (path:/tmp/pip-0Uhmxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:39:26 INFO - Running setup.py (path:/tmp/pip-xVIvDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:39:26 INFO - Running setup.py (path:/tmp/pip-JYOxNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:39:26 INFO - Running setup.py (path:/tmp/pip-EL5_yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:39:26 INFO - Running setup.py (path:/tmp/pip-HkILMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:39:26 INFO - Running setup.py (path:/tmp/pip-2HArKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:26 INFO - Running setup.py (path:/tmp/pip-CBTxH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:26 INFO - Running setup.py (path:/tmp/pip-USBGpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:26 INFO - Running setup.py (path:/tmp/pip-nJ3WY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:26 INFO - Running setup.py (path:/tmp/pip-hXLKeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:26 INFO - Running setup.py (path:/tmp/pip-slEWAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:39:27 INFO - Running setup.py install for manifestparser 18:39:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Running setup.py install for mozcrash 18:39:27 INFO - Running setup.py install for mozdebug 18:39:27 INFO - Running setup.py install for mozdevice 18:39:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Running setup.py install for mozfile 18:39:27 INFO - Running setup.py install for mozhttpd 18:39:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Running setup.py install for mozinfo 18:39:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Running setup.py install for mozInstall 18:39:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:39:27 INFO - Running setup.py install for mozleak 18:39:27 INFO - Running setup.py install for mozlog 18:39:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:39:28 INFO - Running setup.py install for moznetwork 18:39:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:39:28 INFO - Running setup.py install for mozprocess 18:39:28 INFO - Running setup.py install for mozprofile 18:39:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:39:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:39:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:39:28 INFO - Running setup.py install for mozrunner 18:39:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:39:28 INFO - Running setup.py install for mozscreenshot 18:39:28 INFO - Running setup.py install for moztest 18:39:28 INFO - Running setup.py install for mozversion 18:39:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:39:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:39:28 INFO - Cleaning up... 18:39:28 INFO - Return code: 0 18:39:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39: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 18:39: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 18:39:28 INFO - Using env: {'DISPLAY': ':0', 18:39:28 INFO - 'HOME': '/home/cltbld', 18:39:28 INFO - 'LANG': 'en_US.UTF-8', 18:39:28 INFO - 'LANGUAGE': 'en_US:en', 18:39:28 INFO - 'LOGNAME': 'cltbld', 18:39:28 INFO - 'MAIL': '/var/mail/cltbld', 18:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:28 INFO - 'MOZ_NO_REMOTE': '1', 18:39:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:28 INFO - 'NO_EM_RESTART': '1', 18:39:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:28 INFO - 'PWD': '/builds/slave/test', 18:39:28 INFO - 'SHELL': '/bin/bash', 18:39:28 INFO - 'SHLVL': '1', 18:39:28 INFO - 'TERM': 'linux', 18:39:28 INFO - 'TMOUT': '86400', 18:39:28 INFO - 'USER': 'cltbld', 18:39:28 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:28 INFO - '_': '/tools/buildbot/bin/python'} 18:39:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:39:29 INFO - Running setup.py (path:/tmp/pip-WIcJg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:39:29 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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:39:29 INFO - Running setup.py (path:/tmp/pip-LwZ91K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:39:29 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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:39:29 INFO - Running setup.py (path:/tmp/pip-H74n7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:39:29 INFO - Running setup.py (path:/tmp/pip-lsj_e4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:39:29 INFO - Running setup.py (path:/tmp/pip-5phfqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:39:29 INFO - Running setup.py (path:/tmp/pip-tOlvWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:39:29 INFO - Running setup.py (path:/tmp/pip-nZHECI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:39:29 INFO - Running setup.py (path:/tmp/pip-X3uVpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:39:29 INFO - Running setup.py (path:/tmp/pip-JkenDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:39:29 INFO - Running setup.py (path:/tmp/pip-6NprOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:39:29 INFO - Running setup.py (path:/tmp/pip-7rlED_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:39: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)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:39:29 INFO - Running setup.py (path:/tmp/pip-t_ihze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:29 INFO - Running setup.py (path:/tmp/pip-hnfFWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:30 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)) 18:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:30 INFO - Running setup.py (path:/tmp/pip-Xca8Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:30 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)) 18:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:30 INFO - Running setup.py (path:/tmp/pip-DrMLS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:30 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)) 18:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:30 INFO - Running setup.py (path:/tmp/pip-HmR0Bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39: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)) 18:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:30 INFO - Running setup.py (path:/tmp/pip-ueFzLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39: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 18:39:32 INFO - Downloading blessings-1.6.tar.gz 18:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:39:32 INFO - Installing collected packages: blessings 18:39:32 INFO - Running setup.py install for blessings 18:39:32 INFO - Successfully installed blessings 18:39:32 INFO - Cleaning up... 18:39:32 INFO - Return code: 0 18:39:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:39:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:32 INFO - Reading from file tmpfile_stdout 18:39:32 INFO - Current package versions: 18:39:32 INFO - argparse == 1.2.1 18:39:32 INFO - blessings == 1.6 18:39:32 INFO - blobuploader == 1.2.4 18:39:32 INFO - docopt == 0.6.1 18:39:32 INFO - functools32 == 3.2.3-2 18:39:32 INFO - jsonschema == 2.5.1 18:39:32 INFO - manifestparser == 1.1 18:39:32 INFO - mozInstall == 1.12 18:39:32 INFO - mozcrash == 1.0 18:39:32 INFO - mozdebug == 0.1 18:39:32 INFO - mozdevice == 0.49 18:39:32 INFO - mozfile == 1.2 18:39:32 INFO - mozhttpd == 0.7 18:39:32 INFO - mozinfo == 0.9 18:39:32 INFO - mozleak == 0.1 18:39:32 INFO - mozlog == 3.4 18:39:32 INFO - moznetwork == 0.27 18:39:32 INFO - mozprocess == 0.24 18:39:32 INFO - mozprofile == 0.28 18:39:32 INFO - mozrunner == 6.13 18:39:32 INFO - mozscreenshot == 0.1 18:39:32 INFO - mozsystemmonitor == 0.3 18:39:32 INFO - moztest == 0.8 18:39:32 INFO - mozversion == 1.4 18:39:32 INFO - psutil == 3.1.1 18:39:32 INFO - requests == 1.2.3 18:39:32 INFO - wsgiref == 0.1.2 18:39:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39: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')]} 18:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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')]} 18:39:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39: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 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39: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 18:39: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 18:39:32 INFO - Using env: {'DISPLAY': ':0', 18:39:32 INFO - 'HOME': '/home/cltbld', 18:39:32 INFO - 'LANG': 'en_US.UTF-8', 18:39:32 INFO - 'LANGUAGE': 'en_US:en', 18:39:32 INFO - 'LOGNAME': 'cltbld', 18:39:32 INFO - 'MAIL': '/var/mail/cltbld', 18:39:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:32 INFO - 'MOZ_NO_REMOTE': '1', 18:39:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:32 INFO - 'NO_EM_RESTART': '1', 18:39:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:32 INFO - 'PWD': '/builds/slave/test', 18:39:32 INFO - 'SHELL': '/bin/bash', 18:39:32 INFO - 'SHLVL': '1', 18:39:32 INFO - 'TERM': 'linux', 18:39:32 INFO - 'TMOUT': '86400', 18:39:32 INFO - 'USER': 'cltbld', 18:39:32 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:32 INFO - '_': '/tools/buildbot/bin/python'} 18:39:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39: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)) 18:39:32 INFO - Cleaning up... 18:39:33 INFO - Return code: 0 18:39:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2879c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', '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 18:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:39:33 INFO - Using env: {'DISPLAY': ':0', 18:39:33 INFO - 'HOME': '/home/cltbld', 18:39:33 INFO - 'LANG': 'en_US.UTF-8', 18:39:33 INFO - 'LANGUAGE': 'en_US:en', 18:39:33 INFO - 'LOGNAME': 'cltbld', 18:39:33 INFO - 'MAIL': '/var/mail/cltbld', 18:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:33 INFO - 'MOZ_NO_REMOTE': '1', 18:39:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:33 INFO - 'NO_EM_RESTART': '1', 18:39:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:33 INFO - 'PWD': '/builds/slave/test', 18:39:33 INFO - 'SHELL': '/bin/bash', 18:39:33 INFO - 'SHLVL': '1', 18:39:33 INFO - 'TERM': 'linux', 18:39:33 INFO - 'TMOUT': '86400', 18:39:33 INFO - 'USER': 'cltbld', 18:39:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:33 INFO - '_': '/tools/buildbot/bin/python'} 18:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:39:33 INFO - Cleaning up... 18:39:33 INFO - Return code: 0 18:39:33 INFO - Running post-action listener: _resource_record_post_action 18:39:33 INFO - Running post-action listener: _start_resource_monitoring 18:39:33 INFO - Starting resource monitoring. 18:39:33 INFO - [mozharness: 2017-01-12 02:39:33.262012Z] Finished create-virtualenv step (success) 18:39:33 INFO - [mozharness: 2017-01-12 02:39:33.262479Z] Running install step. 18:39:33 INFO - Running pre-action listener: _resource_record_pre_action 18:39:33 INFO - Running main action method: install 18:39:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:33 INFO - Reading from file tmpfile_stdout 18:39:33 INFO - Detecting whether we're running mozinstall >=1.0... 18:39:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:39:33 INFO - Reading from file tmpfile_stdout 18:39:33 INFO - Output received: 18:39:33 INFO - Usage: mozinstall [options] installer 18:39:33 INFO - Options: 18:39:33 INFO - -h, --help show this help message and exit 18:39:33 INFO - -d DEST, --destination=DEST 18:39:33 INFO - Directory to install application into. [default: 18:39:33 INFO - "/builds/slave/test"] 18:39:33 INFO - --app=APP Application being installed. [default: firefox] 18:39:33 INFO - mkdir: /builds/slave/test/build/application 18:39: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'] 18:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:39:49 INFO - Reading from file tmpfile_stdout 18:39:49 INFO - Output received: 18:39:49 INFO - /builds/slave/test/build/application/firefox/firefox 18:39:49 INFO - Running post-action listener: _resource_record_post_action 18:39:49 INFO - [mozharness: 2017-01-12 02:39:49.724369Z] Finished install step (success) 18:39:49 INFO - [mozharness: 2017-01-12 02:39:49.724647Z] Running run-tests step. 18:39:49 INFO - Running pre-action listener: _resource_record_pre_action 18:39:49 INFO - Running main action method: run_tests 18:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:39:49 INFO - Python 2.7.3 18:39:49 INFO - Return code: 0 18:39:49 INFO - grabbing minidump binary from tooltool 18:39: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')]} 18:39: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 0x28773f0>, '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 0x2877cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28781a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:39: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 18:39: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 18:39: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 18:39:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:39:49 INFO - Return code: 0 18:39:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:39:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:39:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:39:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:39:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 18:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 18:39:49 INFO - Using env: {'DISPLAY': ':0', 18:39:49 INFO - 'HOME': '/home/cltbld', 18:39:49 INFO - 'LANG': 'en_US.UTF-8', 18:39:49 INFO - 'LANGUAGE': 'en_US:en', 18:39:49 INFO - 'LOGNAME': 'cltbld', 18:39:49 INFO - 'MAIL': '/var/mail/cltbld', 18:39:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:39:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:49 INFO - 'MOZ_NO_REMOTE': '1', 18:39:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:49 INFO - 'NO_EM_RESTART': '1', 18:39:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:49 INFO - 'PWD': '/builds/slave/test', 18:39:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:39:49 INFO - 'SHELL': '/bin/bash', 18:39:49 INFO - 'SHLVL': '1', 18:39:49 INFO - 'TERM': 'linux', 18:39:49 INFO - 'TMOUT': '86400', 18:39:49 INFO - 'USER': 'cltbld', 18:39:49 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224', 18:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:49 INFO - '_': '/tools/buildbot/bin/python'} 18:39:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 18:39:49 INFO - mozversion application_buildid: 20170112020050 18:39:49 INFO - mozversion application_changeset: d9361bf7a173d95afd1f399ca5ab78ecec0f56fd 18:39:49 INFO - mozversion application_display_name: Nightly 18:39:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:39:49 INFO - mozversion application_name: Firefox 18:39:49 INFO - mozversion application_remotingname: firefox 18:39:49 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 18:39:49 INFO - mozversion application_vendor: Mozilla 18:39:49 INFO - mozversion application_version: 53.0a1 18:39:49 INFO - mozversion platform_buildid: 20170112020050 18:39:49 INFO - mozversion platform_changeset: d9361bf7a173d95afd1f399ca5ab78ecec0f56fd 18:39:49 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 18:39:49 INFO - mozversion platform_version: 53.0a1 18:39:49 INFO - using testdate: 1484188789 18:39:49 INFO - actual date: 1484188789 18:39:49 INFO - starting webserver on 'localhost:43091' 18:39:49 INFO - SUITE-START | Running 2 tests 18:39:49 INFO - TEST-START | dromaeo_css 18:39:49 INFO - operating with platform_type : linux_ 18:39:49 INFO - Initialising browser for dromaeo_css test... 18:39:50 INFO - TEST-INFO | started process 25482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpObj3ms/profile http://localhost:43091/getInfo.html) 18:39:53 INFO - PROCESS | 25482 | __metrics Screen width/height:1600/1200 18:39:53 INFO - PROCESS | 25482 | colorDepth:24 18:39:53 INFO - PROCESS | 25482 | Browser inner width/height: 1024/697 18:39:53 INFO - PROCESS | 25482 | __metrics 18:39:54 INFO - PROCESS | 25482 | 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" 18:39:54 INFO - PROCESS | 25482 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:39:54 INFO - PROCESS | 25482 | console.error: PushService: 18:39:54 INFO - PROCESS | 25482 | stateChangeProcessEnqueue: Error transitioning state 18:39:54 INFO - PROCESS | 25482 | UnknownError 18:39:55 INFO - TEST-INFO | 25482: exit 0 18:39:55 INFO - Browser initialized. 18:39:55 INFO - Running cycle 1/1 for dromaeo_css test... 18:39:55 INFO - TEST-INFO | started process 25563 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpObj3ms/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:07 INFO - PROCESS | 25563 | RSS: Main: 151994368 18:40:07 INFO - PROCESS | 25563 | 18:43:12 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:43091/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43091/tests/dromaeo/cssquery-ext.html) 18:43:14 INFO - PROCESS | 25563 | RSS: Main: 326242304 18:43:14 INFO - PROCESS | 25563 | 18:44:56 INFO - PROCESS | 25563 | ************************* 18:44:56 INFO - PROCESS | 25563 | A coding exception was thrown and uncaught in a Task. 18:44:56 INFO - PROCESS | 25563 | 18:44:56 INFO - PROCESS | 25563 | Full message: ReferenceError: fetch is not defined 18:44:56 INFO - PROCESS | 25563 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 18:44:56 INFO - PROCESS | 25563 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 18:44:56 INFO - PROCESS | 25563 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 18:44:56 INFO - PROCESS | 25563 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:44:56 INFO - PROCESS | 25563 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:44:56 INFO - PROCESS | 25563 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:44:56 INFO - PROCESS | 25563 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 18:44:56 INFO - PROCESS | 25563 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:44:56 INFO - PROCESS | 25563 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:44:56 INFO - PROCESS | 25563 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:44:56 INFO - PROCESS | 25563 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 18:44:56 INFO - PROCESS | 25563 | 18:44:56 INFO - PROCESS | 25563 | ************************* 18:46:16 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:43091/tests/dromaeo/cssquery-ext.html (next: http://localhost:43091/tests/dromaeo/cssquery-jquery.html) 18:46:17 INFO - PROCESS | 25563 | RSS: Main: 238292992 18:46:17 INFO - PROCESS | 25563 | 18:49:19 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:43091/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43091/tests/dromaeo/cssquery-mootools.html) 18:49:20 INFO - PROCESS | 25563 | RSS: Main: 225529856 18:49:20 INFO - PROCESS | 25563 | 18:52:22 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:43091/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43091/tests/dromaeo/cssquery-prototype.html) 18:52:23 INFO - PROCESS | 25563 | RSS: Main: 186613760 18:52:23 INFO - PROCESS | 25563 | 18:55:26 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:43091/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43091/tests/dromaeo/cssquery-yui.html) 18:55:26 INFO - PROCESS | 25563 | RSS: Main: 190943232 18:55:26 INFO - PROCESS | 25563 | 18:58:29 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:43091/tests/dromaeo/cssquery-yui.html (next: http://localhost:43091/tests/dromaeo/cssquery-dojo.html) 18:58:30 INFO - PROCESS | 25563 | RSS: Main: 278253568 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | __start_tp_report 18:58:30 INFO - PROCESS | 25563 | _x_x_mozilla_page_load 18:58:30 INFO - PROCESS | 25563 | _x_x_mozilla_page_load_details 18:58:30 INFO - PROCESS | 25563 | |i|pagename|runs| 18:58:30 INFO - PROCESS | 25563 | |0;dojo.html;2601.284646722151;2624.383269931567;2634.7628713415793;2654.6947101083383;2658.388570628754;4717.080169366975;4788.545088216622;4820.301056346832;4834.927476087857;4870.586000189984;4767.427908650958;4833.130036593412;4845.588125009378;4864.464908860023;4913.729744864031;4348.043430445313;4368.148211098837;4390.363397307388;4449.443819522559;4520.050789334244;4031.2340655275552;4366.148601022802;4445.977770111145;4472.485664148616;4529.909401811962;4374.19077470669;4409.845655402078;4474.328850672393;4484.506704262529;4569.337446070318;4421.093675796454;4447.155040542287;4460.576245256695;4468.597826195658;4544.636429085665;4445.644348452116;4446.777661116931;4448.399466072104;4457.955420445819;4496.033352829158;4805.399325084364;4839.225723884161;4842.297866809328;4847.515248475124;4851.611871050308;5038.496150384969;5079.212722028092;5168.3539557555305;5210.635255532115;5212.765425555859;5244.711540865251;5249.527542521191;5260.421353651095;5265.104932161504;5303.734813259361;4904.5831104356175;4915.262710593368;4920.261960705922;4927.113119638428;4953.108440480748;5163.457836927144;5199.272101905736;5204.219367094966;5277.656952298088;5362.410134885159;5060.418051924003;5122.154844450699;5131.050755610224;5173.689578625294;5185.662931909414;5041.6722913010535;5068.82259120929;5189.558887494605;5191;5199.324087868628;5059.139946209153;5102.974485127551;5108.565771909355;5119.616028797781;5238.292830467838;5027.145385284511;5063.721495317719;5169.767360468788;5177.896442071137;5185.714785686823;4350.651947844165;4365.912681746411;4389.231884420214;4409.470863496401;4447.977760111244;3787.715921305913;3799.2781371539318;3803.9429408558894;3826.4834247376803;3829.5213098362706;4329.978350108229;4445.51099379068;4449.354843547668;4452.48796388413;4493.078918821634;5111.948880511147;5208.635395522353;5237.240600112962;5242.9213561796605;5310.336207974004;4464.910701785946;4467.30756732707;4467.687261891637;4471.441069866267;4505.977470112629;4728.148933192058;4734.171519983989;4737.28940658904;4738.928916066262;4773.665843390999;4781.330613714153;4824.396950381202;4846.854594362175;4855.830045948375;4858.368412106403;5239.790408383775;5246.3704355477585;5249.790008399774;5252.395974462833;5282.577393808563;4894.706317620954;4898.118338699069;4906.926396104132;4911.435184953776;4951.628627852853;4966.776495057803;4998.625103117353;5081.669691470042;5107.336046313955;5147.6654017488745;5232.11054120808;5237.947620523746;5240.082985477602;5274.868128296824;5289.788408463618;4930.556249937523;5041.6470847040355;5077.086124497626;5084.669496482717;5137.8972420550635;4926.655134140576;5048.217526283432;5052.393712754493;5055.696658200372;5056.241563765465;4974.502549744997;4985.376827896513;4995.050940321327;4998;4999.900002000012;5077.466865979167;5094.439611642791;5095.847124586268;5096.159133742891;5102.642815002914;4964.429090654621;4969.378827646544;4970.701757894538;4977.925331119964;5034.924476132787 18:58:30 INFO - PROCESS | 25563 | |1;ext.html;547.4334064243508;547.8739889825338;561.4946548106703;564.4919572384853;565.7256230728096;42041.15917681644;45127;45430.772846135726;45440.3183952241;45505.544944550624;42489.57510424895;43050.35424468628;43199.3520097198;43204.567954320446;43233.35149972753;7504.061992250969;7686.346660533862;7696.961515192416;7864.764057078297;7869.567173805438;10032.69901902944;10258.692239232798;10277.486125693722;10584.153267738562;10585.682429527127;10134;10203.285769996104;10206.387616743019;10209.693709188736;10222.795544089115;10078.143357814595;10128.59485620578;10129.949350253202;10130.797384052277;10141.543630536644;9978;10050.748731281703;10051.396916185053;10051.597936082548;10061.798764024752;39817.80091099526;40133.79933100316;40185.59814401848;40213.79893100545;40214.396784048266;3678.098865777875;3721.5906250312446;3722.9627703722886;3747.7189210809024;3775.0184951912424;28164.436711265866;28272.858635706896;28594.571081433794;28642;29745.25636859074;3500.842462089212;3505.9172335997023;3513.6310687377713;3525.4006818840858;3576.481410195507;181894;182276;182389;182443;182610;56739.77273295349;56961;57055.42944570501;57073.71463142658;57132;59251.70374148102;59493;59612;59661.70169149213;59683.104753428735;14131;14549.418023279162;14561.344739486749;14574.562763116912;14578.708425831424;10324.638637647646;10601.893981060244;11101.83347249792;11170.162237832032;11192.944035279772;16114.435994740128;16507.422240221535;16613.41853035138;16625.83374166267;16630;6329.493640508748;6334.493240540838;6365.936340636627;6385.2656944451055;6630.469562434993;822.7984143884787;830.1947111302028;839.7228914458215;841.4320333774677;863.3611127765533;27684.723152768216;28310.71689283122;28794.895441290264;28804.855975720406;28941;967.8548217767251;991.1624677147798;993.2997236947934;997.7954519323521;1000.8598796168542;14989.325480353411;15096.698066038618;15187.544373668808;15512.922435387976;15520.223988800515;348365;384647;554183;554210;554395;44940.3258951116;45492.3176152358;45512.77243613761;45581.31628025582;45584.77207613941;1240.1690867119053;1251.5869762978195;1253.248051169291;1254.642426908345;1262.4823822233025;25347.87326063358;25847.741522584533;26222;26335.07827226038;26619.467610648066;38159.80920095493;38998.61001390063;39058.414123787596;39095;39141.21717565576;307031;307266;307601;308183;309816;18938;19775;23144.3056708299;23658.88170559136;23939.76060239375;35940.28119437649;36220.81889590641;36766;37014;37152.585081969584;31340.216494586904;31367.05898823039;32236.19409514781;32589.34821303608;32949.3755686879;30264.84867575648;31252.843735781174;31998.121577874696;32431.513527296636;32753;30919.2270193247;31461;31702.682973169973;31776;31814.363712725153;24174.51650967006;25660.87169564215;26746;27128.728712712622;28243.576346355232;21536.569268614854;21592.136314547242;21975;22076.2273320433;22225 18:58:30 INFO - PROCESS | 25563 | |2;jquery.html;367.10975883482547;385.0450881813103;396.2767948493999;397.75538044102865;427.2053979597947;12688.27880653328;14575.416983320667;15551.144687042222;18464.16911238994;18570.814291857077;16436.917815410907;16555.25501352439;16972.83027169728;17021.57446063849;17726.46820595381;4631.16639004979;4838.03239352131;4845.927311090337;4848.733319667417;4849.975750121244;4670.486246512881;4797.2324428091515;4797.568218860302;4826.831060912868;4834.419869615651;4815.66290359675;4822.831200907969;4824.5899098576665;4829.613630909536;4829.951700483003;4677.111348843709;4820.30105634681;4822.855314340558;4829.251466022772;4833.685810422311;4758.476567577563;4760.238361862085;4764.428268607755;4764.952350476486;4767.046590681877;12668.873311266861;12845.229286242855;20179.697304540445;20839.270625528035;21030.369088927357;2229.5429436965383;2236.8322375821717;2445.6576079348906;2490.663760392342;2500.674912261394;9816.803663926681;9996.55015524296;10225.437600931946;10310.278280520366;12628.494860205581;1705.9914700426418;1728.1532049295881;1731.359397023097;1733.2200509770653;1756.9121543922881;59141.704291478694;74803;80671.596642017;81447.18552814514;81566;10233.079022887827;10266.178705703525;10462.163026957825;10467.685969420929;10583.206259530412;14336.928315358356;14358.641033974234;14363.066400683922;14372.784408233676;14409.567712968628;7382.741604043833;7544.735934242385;7607.277308655669;7724.7682569523;7727.343175830118;7167.713291468387;7218.386437152901;7313.268673132644;7360.521566098187;7389.7413590524075;4384.210842048462;4495.100979803987;4512.887177820581;4608;4634.050019745944;1917.9328723494611;1971.940841774749;1985.7815640279557;2005.9799402006086;2008.688653258718;723.2875617516847;749.8950146979428;755.1693137548652;760.2739383888373;776.2005134711256;16436.37018077102;16488.34046638145;16515;16639.50081497557;16653.33386664544;755.2862544895022;755.7468248136826;769.8652735771218;774.2451110167542;776.6000509737489;8707.956460217658;8964.68623598171;9109.863352049724;9317.813643727222;9468.431894086378;515224;518301;519785;531729;534218;91377.54311228401;92582;92652.53673731723;93269;93473;831.2004510235273;834.6911642692121;838.1199740272689;839.3117643532368;839.5550358310105;14183.14901105937;14308.57074287773;14312.355943982337;14412.783808242675;14534.927325363305;38526.42210366903;39022.2195556093;39310.01724956899;39924.401133982436;39976.60023399729;51959.740201300265;52337.7383113082;53906.09770117746;54155.18767218573;55397.446025539226;8082.19178082187;8197.46716463428;8349.624266907882;8371.66513339484;8746.125387461203;7546.962265188639;10394.896051039394;12138.650361911641;12296.569620063256;12633.93683031579;10377;10704.411257380918;11040.116790656568;11061.170412219277;11167.274127181707;10674.46627668629;10754.462276886281;10857.185711071543;11119.666410007712;11125.833112503156;10739.711234651892;10926.836097458703;10938.179636527451;10940.671779846618;11357.37534435614;7848.489848159851;8063.6774529018185;8144.592770361577;8202.876956845772;8208.83582328362;8488.40581159313;10319.587216511256;10381.429021403896;10554.419506927192;10863.13094952386 18:58:30 INFO - PROCESS | 25563 | |3;mootools.html;1209.5585111434323;1230.1450491908122;1231.4212320209504;1232.1005665863925;1249.2941488059246;6141.969290153543;6597.142371491711;7013.614251216181;7015.33354331339;7022.2977702229755;6160.19917410736;6375.139356186925;6382.872342553146;6391.648459334735;6596.109525214095;2062.3297428335777;2105.799949004842;2124.54322320701;2130.5419334843004;2131.6269652810774;2055.650539408304;2100.0339843671927;2106.6734656128247;2108.736407949006;2110.5145816462223;1819.1631849349315;1879.1262063140637;2093.842961777873;2110.472381904524;2115.4394085567337;2062.669972804352;2125.8511904166717;2130.5206328576023;2131.7228760261223;2132.4775430019745;2073.460900165953;2078.6674132138787;2084.2288353309223;2084.3017589107667;2086.3219453677466;6963.547369421022;6992.370686638176;7000.7549735759;7019.754308599175;7026.191987921404;3921.9215615687813;4180.832766689329;4191.7694526801015;4193.958060419418;4194.979025104885;2745.32739478827;2894.2908987298038;2927.16575775905;2935.911922642324;2945.2931296488687;1081.3457857995957;1084.7233955341337;1085.9185561082872;1124.3591153042769;1128.266626692648;8912.24245939096;8958.328125390623;8961.507117108556;8965.955170224108;8988.730338089868;3860.3630400983525;4024.4164596133396;4031.4355990161403;4122.464079669624;4125.1131006833675;8009.279164875189;8024.398170137263;8033;8033.437659363788;8039.67841286342;4421.425214722066;4421.9336709949375;4422.624076953431;4424.535423780457;4429.402030725811;3880.1075752576503;4059.715819892579;4068.613481719232;4071.1247081877536;4073.735207211522;3221.983890080535;3253.8047717137047;3312.1554003729375;3312.585926759155;3334.549835772188;1031.4069410089228;1039.3400190878847;1040.698197522725;1048.2871647279924;1060.5492665617082;672.6838385958591;679.7145199016425;691.3259571917441;693.6081114170478;773.5783997721255;4441.600255976978;4595.425571803525;4676.321933319718;4733.029728905566;4881.194602890554;857.7555396711907;899.8020435504178;929.2705226397246;934.0519372836576;935.9812803743887;3067.831269280211;3160.778745487794;3259.1852036990754;3312.569365982407;3360.193553547131;8012.158723333966;8392.076871544125;8392.4544904581;8405.453645513144;8410.411271211078;8376.58117094155;8387.580620968927;8391.874121888177;8394.412391132684;8396.244338009608;6586.868262634721;6686.431653309523;6689.1972963244725;6692.096566963495;6695.899561506581;4610.585047345755;4660.953390466052;4722.055588882306;4762.499937506512;4768.976155119202;7489.400847932044;7551.546907185587;7564.054493188351;7576.810579735551;7579.772606821804;7562.659680314289;7697;7715.961420192863;7747.612619368896;7750.728724494616;5803.158542011301;5819.156222347651;5960.701964901824;5987.461128498456;6104.969475152773;6352.364763523796;6396.936030639634;6397.040443933447;6397.104405383157;6398.776042838665;5729.4843464088435;5791.47876691083;5808.912866307093;5942.910856337072;5966.492848107786;5733.283339582552;5771.711414429178;5783.16144159103;5801.477866991991;5804.477597016289;5326.36083669962;5334.039872822929;5362.624616276979;5365.382980957083;5371.919421208607;3873.6126387362165;3922.6077392261686;3926.5680775114142;3930.194310166466;4063.756174629532;4099.139180772071;4208.200441916026;4215.178040282116;4284.571542845691;4372.846950356722 18:58:30 INFO - PROCESS | 25563 | |4;prototype.html;322.92734134819653;346.93061387722446;365.7001258967647;368.682932677897;371.44469018816875;4657.278121891108;4780;4854.441739199993;4870.464248932623;4878.731669758162;4642.048380082075;4696.788644510997;4822.710637361752;4836.395450568679;4844.467108618049;3891.3579259422163;3906.1797022625283;3906.628870257321;3907.2771537265553;3911.69210166002;3895.2014836958497;3896.240233154536;3921.647031774033;3923.077691930962;3946.782926939017;3881.3789793633023;3888.358420860555;3916.921661566767;3922.2743792398355;3939.409088636699;3835.2329534093155;3916.1776027034357;3920.5099362579676;3925.7448265862913;3958.6437220650137;3480.686738193575;3497.230609265983;3498.9153362457464;3541.8052007139595;3542.557180352456;4706.552877476634;4707.293905914106;4713.717176969393;4730.86459249781;4738.265568836836;4029.6776257899296;4466.888327791831;4467.218236808546;4471.1728330259;4502.099580083996;5025;5049.84850454487;5058.317127187819;5060.84817455477;5061;3637.781733096023;3642.435422509489;3647.361711700442;3647.726420518445;3656;5299.125644268691;5318.893622127574;5338.065838478251;5339.359276886759;5356.973215133899;3543;3696.815159242027;3704.1665625234646;3717.4609681596016;3735.43968404736;3735.1409175889153;3746.569144548357;3757.4363845423404;3766.37854753968;3784.4890939722786;4100.954256664531;4116.217918595457;4187.880029378132;4224.556421575752;4325.956740432618;5008.574271186917;5018.723970181675;5034.270030845581;5050.898982020339;5053.823116190915;1232.6548566401423;1235.9814602780966;1241.2304371289858;1243.1919252485957;1253.3983687829893;833.1418638801922;848.8641817309201;857.5240741388465;873.9294364403555;877.5787621941504;4261.616454519108;4269.847141271901;4275.615194632436;4275.893102672458;4287.6998610097;5031.52200540948;5034.521720436553;5040.823571174991;5044.495550444926;5049.596032317406;4138.8344466221615;4188.6649068074485;4280.073966714928;4285.785710714452;4300.677449191323;2651.297406187362;2677.236987458604;2677.290518012728;2680.651515302998;2680.9731902680724;4702.576768090888;4703.565412549206;4711.340412342275;4726.456457507362;4735.384400027974;4914.928169893817;5146;5168.81909133186;5182.455842136596;5192.273081768555;4663.300504924288;4701.811927522861;4706.270528068155;4717.080169366941;4722.315264286728;5017.54842064216;5017.767093643293;5043.445221025758;5051.318072060371;5060.810709483342;4647.395838540968;4946.802127914987;4952.504749525018;4963.230699241623;4992.750362481934;4570.4972453030805;4650.465646109049;4686.507916668701;4699.718016918859;4714.717116972855;3551.520544726429;3559.697425718843;3561.287742451572;3567;3592.2276710507367;4885.389326334208;5002.873204247346;5014.799408023784;5050.949490505048;5054.444011158843;4832.758362081811;4866.245731911559;4868.561829435368;4875.24606053344;4901.950980490149;4846.110017093266;4854.354376864547;4879.951200488092;4901.240307752304;4919;4585.065558042922;4760.97619511914;4824.638152138534;4832.540908613676;4856.611471082234;4843.2635560907975;4881.755912204447;4888.6577939543895;4904.6457700303345;4906.460289368238;4755.2153894607;4764.213904705684;4770.475247722678;4772.49888761675;4810.446798618063 18:58:30 INFO - PROCESS | 25563 | |5;yui.html;675.9966200168999;687.4775170870137;735.2500449541468;775.2131586439764;791.7505985614531;11517.884821151785;12399.380030998436;12437.875621243786;12452.501899924004;12453.937730311336;11834.23077499962;12009.159358844885;12013.279203247812;12020.51917923282;12027.398630068483;649.1982401733854;669.5313280703527;793.0721056364039;808.9433739638228;811.8498077855586;816.0941355095839;819.0703551469065;819.8934138562008;820.729159377404;821.1993306526149;847.8685803700437;847.894013248344;848.245061894914;849.4393700157897;850.3027517435705;686.2931180883672;708.8688592610332;833.5873742497503;846.2552953400999;860.6256278518802;637.0603360043945;637.716216283754;637.7990932856136;637.9011253255753;637.9330170332095;12099.334536600483;12125.878741212651;12145.514179432812;12147.878521214852;12160.331181784999;12960.740785184338;13202.421143051195;13267.86732132676;13407.664808379772;13496.865031349756;12230.082743794163;12297.085615652419;12441.688957776038;12547;12608.495660173674;4116.547179810218;4217.8312867485265;4228.619424251801;4239.872803815891;4347.065380943112;65487.672561636886;65768.67115664438;66147.33852661459;66668.99996500104;67330.32669673339;10804.567817287221;10880.945595271973;10955.287906286067;11044.723881903017;11544.538218471167;20312.390628281177;20431.693524597144;20450.386488405373;20540.897295513725;20679.483012924495;6711.530192886548;6728.394444500019;6740.359665831738;6771.72913083481;7006.229314775371;3554.235839294564;3565.3404120237838;3604.7837129772297;3606.404943184398;3669.43123815809;2811.1707046421357;2877.3813630069217;2885.581590669341;2891.277180704824;2900.318425170083;474.24121405751214;475.89768199837204;479.4677907522647;481.7543053042903;486.1492388320439;609.5611159964818;610.6900500151144;611.437477520682;616.8488720263564;626.264139635926;8313.210245026834;8348.499090054494;8449.831003379897;8498.957505212558;8582.098879617675;544.335910189568;551.9034169020406;558.6703844731628;562.361719448429;577.5177726598338;3905.6492605130584;3936.6653834424396;4122.979385103115;4178.6448151907425;4226.535081141072;70039.29960700328;70614;71085.64457177681;71507.64246178736;72921.27078729251;50148.24777628339;50347;61294.693526532974;63058.36941630525;63281.36718632847;53519.732401337744;54553;54659.453405465436;55632.443675562725;57215.14177287344;7904.565248911135;8043.436959412785;8087.069986951341;8112.675492980451;8160.877586836321;37967.43048854324;38141.61858381492;38848.80575597217;38872.6112738869;38980.610193898836;41800;43218.783906080265;43982.78008610065;47178.52821471879;48373.75813120912;11813.354962952833;11933.164678472423;11962.521499139937;11977.940110299742;12112;12696.74606507883;12746.808797867854;12814.935925320313;12983.41574629125;13428.731425371243;12168.148229623841;12360.505579777067;12720.872791272339;12782.36088195568;13425.86574134246;11351.55581214793;11553.595624153115;12177.634670959886;12933.611991640266;13493.257870817199;11875.228110172811;12201.450934707782;12406.62780116598;12562.623121306375;12908.483660653468;9540;9633.277504187074;10155.136813370651;10158.288919775545;10619.415932123808;9552.617895284111;9779.804403912025;9912.405255684682;9962.701118966443;9991.800163996824 18:58:30 INFO - PROCESS | 25563 | __end_tp_report 18:58:30 INFO - PROCESS | 25563 | __start_cc_report 18:58:30 INFO - PROCESS | 25563 | _x_x_mozilla_cycle_collect,2026 18:58:30 INFO - PROCESS | 25563 | __end_cc_report 18:58:30 INFO - PROCESS | 25563 | __startTimestamp1484189910161__endTimestamp 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | ------- Summary: start ------- 18:58:30 INFO - PROCESS | 25563 | Number of tests: 6 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | [#0] dojo.html Cycles:165 Average:4785.87 Median:4926.66 stddev:517.16 (10.5%) stddev-sans-first:489.52 18:58:30 INFO - PROCESS | 25563 | Values: 2601.3 2624.4 2634.8 2654.7 2658.4 4717.1 4788.5 4820.3 4834.9 4870.6 4767.4 4833.1 4845.6 4864.5 4913.7 4348.0 4368.1 4390.4 4449.4 4520.1 4031.2 4366.1 4446.0 4472.5 4529.9 4374.2 4409.8 4474.3 4484.5 4569.3 4421.1 4447.2 4460.6 4468.6 4544.6 4445.6 4446.8 4448.4 4458.0 4496.0 4805.4 4839.2 4842.3 4847.5 4851.6 5038.5 5079.2 5168.4 5210.6 5212.8 5244.7 5249.5 5260.4 5265.1 5303.7 4904.6 4915.3 4920.3 4927.1 4953.1 5163.5 5199.3 5204.2 5277.7 5362.4 5060.4 5122.2 5131.1 5173.7 5185.7 5041.7 5068.8 5189.6 5191.0 5199.3 5059.1 5103.0 5108.6 5119.6 5238.3 5027.1 5063.7 5169.8 5177.9 5185.7 4350.7 4365.9 4389.2 4409.5 4448.0 3787.7 3799.3 3803.9 3826.5 3829.5 4330.0 4445.5 4449.4 4452.5 4493.1 5111.9 5208.6 5237.2 5242.9 5310.3 4464.9 4467.3 4467.7 4471.4 4506.0 4728.1 4734.2 4737.3 4738.9 4773.7 4781.3 4824.4 4846.9 4855.8 4858.4 5239.8 5246.4 5249.8 5252.4 5282.6 4894.7 4898.1 4906.9 4911.4 4951.6 4966.8 4998.6 5081.7 5107.3 5147.7 5232.1 5237.9 5240.1 5274.9 5289.8 4930.6 5041.6 5077.1 5084.7 5137.9 4926.7 5048.2 5052.4 5055.7 5056.2 4974.5 4985.4 4995.1 4998.0 4999.9 5077.5 5094.4 5095.8 5096.2 5102.6 4964.4 4969.4 4970.7 4977.9 5034.9 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | [#1] ext.html Cycles:180 Average:47400.84 Median:31581.84 stddev:93198.12 (295.1%) stddev-sans-first:93393.18 18:58:30 INFO - PROCESS | 25563 | Values: 547.4 547.9 561.5 564.5 565.7 42041.2 45127.0 45430.8 45440.3 45505.5 42489.6 43050.4 43199.4 43204.6 43233.4 7504.1 7686.3 7697.0 7864.8 7869.6 10032.7 10258.7 10277.5 10584.2 10585.7 10134.0 10203.3 10206.4 10209.7 10222.8 10078.1 10128.6 10129.9 10130.8 10141.5 9978.0 10050.7 10051.4 10051.6 10061.8 39817.8 40133.8 40185.6 40213.8 40214.4 3678.1 3721.6 3723.0 3747.7 3775.0 28164.4 28272.9 28594.6 28642.0 29745.3 3500.8 3505.9 3513.6 3525.4 3576.5 181894.0 182276.0 182389.0 182443.0 182610.0 56739.8 56961.0 57055.4 57073.7 57132.0 59251.7 59493.0 59612.0 59661.7 59683.1 14131.0 14549.4 14561.3 14574.6 14578.7 10324.6 10601.9 11101.8 11170.2 11192.9 16114.4 16507.4 16613.4 16625.8 16630.0 6329.5 6334.5 6365.9 6385.3 6630.5 822.8 830.2 839.7 841.4 863.4 27684.7 28310.7 28794.9 28804.9 28941.0 967.9 991.2 993.3 997.8 1000.9 14989.3 15096.7 15187.5 15512.9 15520.2 348365.0 384647.0 554183.0 554210.0 554395.0 44940.3 45492.3 45512.8 45581.3 45584.8 1240.2 1251.6 1253.2 1254.6 1262.5 25347.9 25847.7 26222.0 26335.1 26619.5 38159.8 38998.6 39058.4 39095.0 39141.2 307031.0 307266.0 307601.0 308183.0 309816.0 18938.0 19775.0 23144.3 23658.9 23939.8 35940.3 36220.8 36766.0 37014.0 37152.6 31340.2 31367.1 32236.2 32589.3 32949.4 30264.8 31252.8 31998.1 32431.5 32753.0 30919.2 31461.0 31702.7 31776.0 31814.4 24174.5 25660.9 26746.0 27128.7 28243.6 21536.6 21592.1 21975.0 22076.2 22225.0 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | [#2] jquery.html Cycles:180 Average:28702.88 Median:4632.61 stddev:86270.65 (1862.2%) stddev-sans-first:86486.42 18:58:30 INFO - PROCESS | 25563 | Values: 367.1 385.0 396.3 397.8 427.2 12688.3 14575.4 15551.1 18464.2 18570.8 16436.9 16555.3 16972.8 17021.6 17726.5 4631.2 4838.0 4845.9 4848.7 4850.0 4670.5 4797.2 4797.6 4826.8 4834.4 4815.7 4822.8 4824.6 4829.6 4830.0 4677.1 4820.3 4822.9 4829.3 4833.7 4758.5 4760.2 4764.4 4765.0 4767.0 12668.9 12845.2 20179.7 20839.3 21030.4 2229.5 2236.8 2445.7 2490.7 2500.7 9816.8 9996.6 10225.4 10310.3 12628.5 1706.0 1728.2 1731.4 1733.2 1756.9 59141.7 74803.0 80671.6 81447.2 81566.0 10233.1 10266.2 10462.2 10467.7 10583.2 14336.9 14358.6 14363.1 14372.8 14409.6 7382.7 7544.7 7607.3 7724.8 7727.3 7167.7 7218.4 7313.3 7360.5 7389.7 4384.2 4495.1 4512.9 4608.0 4634.1 1917.9 1971.9 1985.8 2006.0 2008.7 723.3 749.9 755.2 760.3 776.2 16436.4 16488.3 16515.0 16639.5 16653.3 755.3 755.7 769.9 774.2 776.6 8708.0 8964.7 9109.9 9317.8 9468.4 515224.0 518301.0 519785.0 531729.0 534218.0 91377.5 92582.0 92652.5 93269.0 93473.0 831.2 834.7 838.1 839.3 839.6 14183.1 14308.6 14312.4 14412.8 14534.9 38526.4 39022.2 39310.0 39924.4 39976.6 51959.7 52337.7 53906.1 54155.2 55397.4 8082.2 8197.5 8349.6 8371.7 8746.1 7547.0 10394.9 12138.7 12296.6 12633.9 10377.0 10704.4 11040.1 11061.2 11167.3 10674.5 10754.5 10857.2 11119.7 11125.8 10739.7 10926.8 10938.2 10940.7 11357.4 7848.5 8063.7 8144.6 8202.9 8208.8 8488.4 10319.6 10381.4 10554.4 10863.1 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | [#3] mootools.html Cycles:180 Average:4556.59 Median:4727.54 stddev:2413.86 (51.1%) stddev-sans-first:2407.52 18:58:30 INFO - PROCESS | 25563 | Values: 1209.6 1230.1 1231.4 1232.1 1249.3 6142.0 6597.1 7013.6 7015.3 7022.3 6160.2 6375.1 6382.9 6391.6 6596.1 2062.3 2105.8 2124.5 2130.5 2131.6 2055.7 2100.0 2106.7 2108.7 2110.5 1819.2 1879.1 2093.8 2110.5 2115.4 2062.7 2125.9 2130.5 2131.7 2132.5 2073.5 2078.7 2084.2 2084.3 2086.3 6963.5 6992.4 7000.8 7019.8 7026.2 3921.9 4180.8 4191.8 4194.0 4195.0 2745.3 2894.3 2927.2 2935.9 2945.3 1081.3 1084.7 1085.9 1124.4 1128.3 8912.2 8958.3 8961.5 8966.0 8988.7 3860.4 4024.4 4031.4 4122.5 4125.1 8009.3 8024.4 8033.0 8033.4 8039.7 4421.4 4421.9 4422.6 4424.5 4429.4 3880.1 4059.7 4068.6 4071.1 4073.7 3222.0 3253.8 3312.2 3312.6 3334.5 1031.4 1039.3 1040.7 1048.3 1060.5 672.7 679.7 691.3 693.6 773.6 4441.6 4595.4 4676.3 4733.0 4881.2 857.8 899.8 929.3 934.1 936.0 3067.8 3160.8 3259.2 3312.6 3360.2 8012.2 8392.1 8392.5 8405.5 8410.4 8376.6 8387.6 8391.9 8394.4 8396.2 6586.9 6686.4 6689.2 6692.1 6695.9 4610.6 4661.0 4722.1 4762.5 4769.0 7489.4 7551.5 7564.1 7576.8 7579.8 7562.7 7697.0 7716.0 7747.6 7750.7 5803.2 5819.2 5960.7 5987.5 6105.0 6352.4 6396.9 6397.0 6397.1 6398.8 5729.5 5791.5 5808.9 5942.9 5966.5 5733.3 5771.7 5783.2 5801.5 5804.5 5326.4 5334.0 5362.6 5365.4 5371.9 3873.6 3922.6 3926.6 3930.2 4063.8 4099.1 4208.2 4215.2 4284.6 4372.8 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | [#4] prototype.html Cycles:180 Average:4141.64 Median:4702.19 stddev:1168.37 (24.8%) stddev-sans-first:1135.95 18:58:30 INFO - PROCESS | 25563 | Values: 322.9 346.9 365.7 368.7 371.4 4657.3 4780.0 4854.4 4870.5 4878.7 4642.0 4696.8 4822.7 4836.4 4844.5 3891.4 3906.2 3906.6 3907.3 3911.7 3895.2 3896.2 3921.6 3923.1 3946.8 3881.4 3888.4 3916.9 3922.3 3939.4 3835.2 3916.2 3920.5 3925.7 3958.6 3480.7 3497.2 3498.9 3541.8 3542.6 4706.6 4707.3 4713.7 4730.9 4738.3 4029.7 4466.9 4467.2 4471.2 4502.1 5025.0 5049.8 5058.3 5060.8 5061.0 3637.8 3642.4 3647.4 3647.7 3656.0 5299.1 5318.9 5338.1 5339.4 5357.0 3543.0 3696.8 3704.2 3717.5 3735.4 3735.1 3746.6 3757.4 3766.4 3784.5 4101.0 4116.2 4187.9 4224.6 4326.0 5008.6 5018.7 5034.3 5050.9 5053.8 1232.7 1236.0 1241.2 1243.2 1253.4 833.1 848.9 857.5 873.9 877.6 4261.6 4269.8 4275.6 4275.9 4287.7 5031.5 5034.5 5040.8 5044.5 5049.6 4138.8 4188.7 4280.1 4285.8 4300.7 2651.3 2677.2 2677.3 2680.7 2681.0 4702.6 4703.6 4711.3 4726.5 4735.4 4914.9 5146.0 5168.8 5182.5 5192.3 4663.3 4701.8 4706.3 4717.1 4722.3 5017.5 5017.8 5043.4 5051.3 5060.8 4647.4 4946.8 4952.5 4963.2 4992.8 4570.5 4650.5 4686.5 4699.7 4714.7 3551.5 3559.7 3561.3 3567.0 3592.2 4885.4 5002.9 5014.8 5050.9 5054.4 4832.8 4866.2 4868.6 4875.2 4902.0 4846.1 4854.4 4880.0 4901.2 4919.0 4585.1 4761.0 4824.6 4832.5 4856.6 4843.3 4881.8 4888.7 4904.6 4906.5 4755.2 4764.2 4770.5 4772.5 4810.4 18:58:30 INFO - PROCESS | 25563 | 18:58:30 INFO - PROCESS | 25563 | [#5] yui.html Cycles:180 Average:15382.04 Median:45580.65 stddev:19338.55 (42.4%) stddev-sans-first:19361.27 18:58:30 INFO - PROCESS | 25563 | Values: 676.0 687.5 735.3 775.2 791.8 11517.9 12399.4 12437.9 12452.5 12453.9 11834.2 12009.2 12013.3 12020.5 12027.4 649.2 669.5 793.1 808.9 811.8 816.1 819.1 819.9 820.7 821.2 847.9 847.9 848.2 849.4 850.3 686.3 708.9 833.6 846.3 860.6 637.1 637.7 637.8 637.9 637.9 12099.3 12125.9 12145.5 12147.9 12160.3 12960.7 13202.4 13267.9 13407.7 13496.9 12230.1 12297.1 12441.7 12547.0 12608.5 4116.5 4217.8 4228.6 4239.9 4347.1 65487.7 65768.7 66147.3 66669.0 67330.3 10804.6 10880.9 10955.3 11044.7 11544.5 20312.4 20431.7 20450.4 20540.9 20679.5 6711.5 6728.4 6740.4 6771.7 7006.2 3554.2 3565.3 3604.8 3606.4 3669.4 2811.2 2877.4 2885.6 2891.3 2900.3 474.2 475.9 479.5 481.8 486.1 609.6 610.7 611.4 616.8 626.3 8313.2 8348.5 8449.8 8499.0 8582.1 544.3 551.9 558.7 562.4 577.5 3905.6 3936.7 4123.0 4178.6 4226.5 70039.3 70614.0 71085.6 71507.6 72921.3 50148.2 50347.0 61294.7 63058.4 63281.4 53519.7 54553.0 54659.5 55632.4 57215.1 7904.6 8043.4 8087.1 8112.7 8160.9 37967.4 38141.6 38848.8 38872.6 38980.6 41800.0 43218.8 43982.8 47178.5 48373.8 11813.4 11933.2 11962.5 11977.9 12112.0 12696.7 12746.8 12814.9 12983.4 13428.7 12168.1 12360.5 12720.9 12782.4 13425.9 11351.6 11553.6 12177.6 12933.6 13493.3 11875.2 12201.5 12406.6 12562.6 12908.5 9540.0 9633.3 10155.1 10158.3 10619.4 9552.6 9779.8 9912.4 9962.7 9991.8 18:58:30 INFO - PROCESS | 25563 | -------- Summary: end -------- 18:58:30 INFO - PROCESS | 25563 | 18:58:31 INFO - TEST-INFO | 25563: exit 0 18:58:31 INFO - TEST-OK | dromaeo_css | took 1121038ms 18:58:31 INFO - TEST-START | kraken 18:58:31 INFO - operating with platform_type : linux_ 18:58:31 INFO - Initialising browser for kraken test... 18:58:31 INFO - TEST-INFO | started process 25862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGPwACM/profile http://localhost:43091/getInfo.html) 18:58:34 INFO - PROCESS | 25862 | __metrics Screen width/height:1600/1200 18:58:34 INFO - PROCESS | 25862 | colorDepth:24 18:58:34 INFO - PROCESS | 25862 | Browser inner width/height: 1024/697 18:58:34 INFO - PROCESS | 25862 | __metrics 18:58:35 INFO - PROCESS | 25862 | 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" 18:58:35 INFO - PROCESS | 25862 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:58:36 INFO - PROCESS | 25862 | console.error: PushService: 18:58:36 INFO - PROCESS | 25862 | stateChangeProcessEnqueue: Error transitioning state 18:58:36 INFO - PROCESS | 25862 | UnknownError 18:58:36 INFO - TEST-INFO | 25862: exit 0 18:58:36 INFO - Browser initialized. 18:58:36 INFO - Running cycle 1/1 for kraken test... 18:58:36 INFO - TEST-INFO | started process 25946 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGPwACM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:58:48 INFO - PROCESS | 25946 | RSS: Main: 152543232 18:58:48 INFO - PROCESS | 25946 | 18:58:51 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43091/tests/kraken/driver.html?testName=audio-beat-detection) 18:58:52 INFO - PROCESS | 25946 | RSS: Main: 206409728 18:58:52 INFO - PROCESS | 25946 | 18:58:55 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43091/tests/kraken/driver.html?testName=audio-dft) 18:58:55 INFO - PROCESS | 25946 | RSS: Main: 186281984 18:58:55 INFO - PROCESS | 25946 | 18:58:58 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43091/tests/kraken/driver.html?testName=audio-fft) 18:58:59 INFO - PROCESS | 25946 | RSS: Main: 267534336 18:58:59 INFO - PROCESS | 25946 | 18:59:01 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43091/tests/kraken/driver.html?testName=audio-oscillator) 18:59:02 INFO - PROCESS | 25946 | RSS: Main: 179408896 18:59:02 INFO - PROCESS | 25946 | 18:59:04 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43091/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:59:04 INFO - PROCESS | 25946 | RSS: Main: 182964224 18:59:04 INFO - PROCESS | 25946 | 18:59:08 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43091/tests/kraken/driver.html?testName=imaging-darkroom) 18:59:08 INFO - PROCESS | 25946 | RSS: Main: 222150656 18:59:08 INFO - PROCESS | 25946 | 18:59:12 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43091/tests/kraken/driver.html?testName=imaging-desaturate) 18:59:12 INFO - PROCESS | 25946 | RSS: Main: 216055808 18:59:12 INFO - PROCESS | 25946 | 18:59:15 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43091/tests/kraken/driver.html?testName=json-parse-financial) 18:59:16 INFO - PROCESS | 25946 | RSS: Main: 218042368 18:59:16 INFO - PROCESS | 25946 | 18:59:17 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43091/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:59:18 INFO - PROCESS | 25946 | RSS: Main: 181772288 18:59:18 INFO - PROCESS | 25946 | 18:59:20 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:59:21 INFO - PROCESS | 25946 | RSS: Main: 188911616 18:59:21 INFO - PROCESS | 25946 | 18:59:23 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:59:23 INFO - PROCESS | 25946 | RSS: Main: 197386240 18:59:23 INFO - PROCESS | 25946 | 18:59:26 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:59:27 INFO - PROCESS | 25946 | RSS: Main: 206417920 18:59:27 INFO - PROCESS | 25946 | 18:59:30 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:59:30 INFO - PROCESS | 25946 | RSS: Main: 203550720 18:59:30 INFO - PROCESS | 25946 | 18:59:32 INFO - PROCESS | 25946 | Cycle 1(1): loaded http://localhost:43091/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43091/tests/kraken/driver.html?testName=ai-astar) 18:59:33 INFO - PROCESS | 25946 | RSS: Main: 253890560 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | __start_tp_report 18:59:33 INFO - PROCESS | 25946 | _x_x_mozilla_page_load 18:59:33 INFO - PROCESS | 25946 | _x_x_mozilla_page_load_details 18:59:33 INFO - PROCESS | 25946 | |i|pagename|runs| 18:59:33 INFO - PROCESS | 25946 | |0;ai-astar;112;117;115;115;114;113;115;113;114;117 18:59:33 INFO - PROCESS | 25946 | |1;audio-beat-detection;154;126;122;163;125;126;124;122;143;124 18:59:33 INFO - PROCESS | 25946 | |2;audio-dft;161;161;160;161;161;161;160;160;161;160 18:59:33 INFO - PROCESS | 25946 | |3;audio-fft;76;77;76;118;78;77;78;76;90;77 18:59:33 INFO - PROCESS | 25946 | |4;audio-oscillator;85;86;114;85;85;85;102;85;85;111 18:59:33 INFO - PROCESS | 25946 | |5;imaging-gaussian-blur;117;118;117;117;117;117;118;117;119;117 18:59:33 INFO - PROCESS | 25946 | |6;imaging-darkroom;152;149;149;148;149;150;150;149;149;148 18:59:33 INFO - PROCESS | 25946 | |7;imaging-desaturate;95;95;96;95;96;95;95;95;96;95 18:59:33 INFO - PROCESS | 25946 | |8;json-parse-financial;59;58;59;59;61;60;60;61;62;62 18:59:33 INFO - PROCESS | 25946 | |9;json-stringify-tinderbox;67;69;68;67;67;68;67;67;68;68 18:59:33 INFO - PROCESS | 25946 | |10;stanford-crypto-aes;86;85;85;85;85;84;84;83;84;83 18:59:33 INFO - PROCESS | 25946 | |11;stanford-crypto-ccm;142;145;137;134;131;130;130;142;191;134 18:59:33 INFO - PROCESS | 25946 | |12;stanford-crypto-pbkdf2;175;173;175;173;182;178;172;175;173;180 18:59:33 INFO - PROCESS | 25946 | |13;stanford-crypto-sha256-iterative;69;69;68;69;71;68;68;68;69;66 18:59:33 INFO - PROCESS | 25946 | __end_tp_report 18:59:33 INFO - PROCESS | 25946 | __start_cc_report 18:59:33 INFO - PROCESS | 25946 | _x_x_mozilla_cycle_collect,1838 18:59:33 INFO - PROCESS | 25946 | __end_cc_report 18:59:33 INFO - PROCESS | 25946 | __startTimestamp1484189973040__endTimestamp 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | ------- Summary: start ------- 18:59:33 INFO - PROCESS | 25946 | Number of tests: 14 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.00 stddev:1.65 (1.4%) stddev-sans-first:1.48 18:59:33 INFO - PROCESS | 25946 | Values: 112.0 117.0 115.0 115.0 114.0 113.0 115.0 113.0 114.0 117.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:126.00 stddev:14.93 (11.9%) stddev-sans-first:13.75 18:59:33 INFO - PROCESS | 25946 | Values: 154.0 126.0 122.0 163.0 125.0 126.0 124.0 122.0 143.0 124.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 18:59:33 INFO - PROCESS | 25946 | Values: 161.0 161.0 160.0 161.0 161.0 161.0 160.0 160.0 161.0 160.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#3] audio-fft Cycles:10 Average:82.30 Median:77.00 stddev:13.22 (17.2%) stddev-sans-first:13.83 18:59:33 INFO - PROCESS | 25946 | Values: 76.0 77.0 76.0 118.0 78.0 77.0 78.0 76.0 90.0 77.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:85.00 stddev:11.90 (14.0%) stddev-sans-first:12.32 18:59:33 INFO - PROCESS | 25946 | Values: 85.0 86.0 114.0 85.0 85.0 85.0 102.0 85.0 85.0 111.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 18:59:33 INFO - PROCESS | 25946 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 119.0 117.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.00 stddev:1.16 (0.8%) stddev-sans-first:0.71 18:59:33 INFO - PROCESS | 25946 | Values: 152.0 149.0 149.0 148.0 149.0 150.0 150.0 149.0 149.0 148.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:59:33 INFO - PROCESS | 25946 | Values: 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:60.50 stddev:1.37 (2.3%) stddev-sans-first:1.39 18:59:33 INFO - PROCESS | 25946 | Values: 59.0 58.0 59.0 59.0 61.0 60.0 60.0 61.0 62.0 62.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 18:59:33 INFO - PROCESS | 25946 | Values: 67.0 69.0 68.0 67.0 67.0 68.0 67.0 67.0 68.0 68.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:0.83 18:59:33 INFO - PROCESS | 25946 | Values: 86.0 85.0 85.0 85.0 85.0 84.0 84.0 83.0 84.0 83.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:139.50 stddev:18.17 (13.0%) stddev-sans-first:19.27 18:59:33 INFO - PROCESS | 25946 | Values: 142.0 145.0 137.0 134.0 131.0 130.0 130.0 142.0 191.0 134.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:175.00 stddev:3.34 (1.9%) stddev-sans-first:3.54 18:59:33 INFO - PROCESS | 25946 | Values: 175.0 173.0 175.0 173.0 182.0 178.0 172.0 175.0 173.0 180.0 18:59:33 INFO - PROCESS | 25946 | 18:59:33 INFO - PROCESS | 25946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.27 (1.8%) stddev-sans-first:1.33 18:59:33 INFO - PROCESS | 25946 | Values: 69.0 69.0 68.0 69.0 71.0 68.0 68.0 68.0 69.0 66.0 18:59:33 INFO - PROCESS | 25946 | -------- Summary: end -------- 18:59:33 INFO - PROCESS | 25946 | 18:59:34 INFO - TEST-INFO | 25946: exit 0 18:59:34 INFO - TEST-OK | kraken | took 63190ms 18:59:34 INFO - SUITE-END | took 1184s 18:59:34 INFO - Completed test suite (00:19:44) 18:59:34 INFO - javascript benchmark 18:59:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2601.284646722151, 2624.383269931567, 2634.7628713415793, 2654.6947101083383, 2658.388570628754, 4717.080169366975, 4788.545088216622, 4820.301056346832, 4834.927476087857, 4870.586000189984, 4767.427908650958, 4833.130036593412, 4845.588125009378, 4864.464908860023, 4913.729744864031, 4348.043430445313, 4368.148211098837, 4390.363397307388, 4449.443819522559, 4520.050789334244, 4031.2340655275552, 4366.148601022802, 4445.977770111145, 4472.485664148616, 4529.909401811962, 4374.19077470669, 4409.845655402078, 4474.328850672393, 4484.506704262529, 4569.337446070318, 4421.093675796454, 4447.155040542287, 4460.576245256695, 4468.597826195658, 4544.636429085665, 4445.644348452116, 4446.777661116931, 4448.399466072104, 4457.955420445819, 4496.033352829158, 4805.399325084364, 4839.225723884161, 4842.297866809328, 4847.515248475124, 4851.611871050308, 5038.496150384969, 5079.212722028092, 5168.3539557555305, 5210.635255532115, 5212.765425555859, 5244.711540865251, 5249.527542521191, 5260.421353651095, 5265.104932161504, 5303.734813259361, 4904.5831104356175, 4915.262710593368, 4920.261960705922, 4927.113119638428, 4953.108440480748, 5163.457836927144, 5199.272101905736, 5204.219367094966, 5277.656952298088, 5362.410134885159, 5060.418051924003, 5122.154844450699, 5131.050755610224, 5173.689578625294, 5185.662931909414, 5041.6722913010535, 5068.82259120929, 5189.558887494605, 5191.0, 5199.324087868628, 5059.139946209153, 5102.974485127551, 5108.565771909355, 5119.616028797781, 5238.292830467838, 5027.145385284511, 5063.721495317719, 5169.767360468788, 5177.896442071137, 5185.714785686823, 4350.651947844165, 4365.912681746411, 4389.231884420214, 4409.470863496401, 4447.977760111244, 3787.715921305913, 3799.2781371539318, 3803.9429408558894, 3826.4834247376803, 3829.5213098362706, 4329.978350108229, 4445.51099379068, 4449.354843547668, 4452.48796388413, 4493.078918821634, 5111.948880511147, 5208.635395522353, 5237.240600112962, 5242.9213561796605, 5310.336207974004, 4464.910701785946, 4467.30756732707, 4467.687261891637, 4471.441069866267, 4505.977470112629, 4728.148933192058, 4734.171519983989, 4737.28940658904, 4738.928916066262, 4773.665843390999, 4781.330613714153, 4824.396950381202, 4846.854594362175, 4855.830045948375, 4858.368412106403, 5239.790408383775, 5246.3704355477585, 5249.790008399774, 5252.395974462833, 5282.577393808563, 4894.706317620954, 4898.118338699069, 4906.926396104132, 4911.435184953776, 4951.628627852853, 4966.776495057803, 4998.625103117353, 5081.669691470042, 5107.336046313955, 5147.6654017488745, 5232.11054120808, 5237.947620523746, 5240.082985477602, 5274.868128296824, 5289.788408463618, 4930.556249937523, 5041.6470847040355, 5077.086124497626, 5084.669496482717, 5137.8972420550635, 4926.655134140576, 5048.217526283432, 5052.393712754493, 5055.696658200372, 5056.241563765465, 4974.502549744997, 4985.376827896513, 4995.050940321327, 4998.0, 4999.900002000012, 5077.466865979167, 5094.439611642791, 5095.847124586268, 5096.159133742891, 5102.642815002914, 4964.429090654621, 4969.378827646544, 4970.701757894538, 4977.925331119964, 5034.924476132787], "value": 4751.493317642484, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.4334064243508, 547.8739889825338, 561.4946548106703, 564.4919572384853, 565.7256230728096, 42041.15917681644, 45127.0, 45430.772846135726, 45440.3183952241, 45505.544944550624, 42489.57510424895, 43050.35424468628, 43199.3520097198, 43204.567954320446, 43233.35149972753, 7504.061992250969, 7686.346660533862, 7696.961515192416, 7864.764057078297, 7869.567173805438, 10032.69901902944, 10258.692239232798, 10277.486125693722, 10584.153267738562, 10585.682429527127, 10134.0, 10203.285769996104, 10206.387616743019, 10209.693709188736, 10222.795544089115, 10078.143357814595, 10128.59485620578, 10129.949350253202, 10130.797384052277, 10141.543630536644, 9978.0, 10050.748731281703, 10051.396916185053, 10051.597936082548, 10061.798764024752, 39817.80091099526, 40133.79933100316, 40185.59814401848, 40213.79893100545, 40214.396784048266, 3678.098865777875, 3721.5906250312446, 3722.9627703722886, 3747.7189210809024, 3775.0184951912424, 28164.436711265866, 28272.858635706896, 28594.571081433794, 28642.0, 29745.25636859074, 3500.842462089212, 3505.9172335997023, 3513.6310687377713, 3525.4006818840858, 3576.481410195507, 181894.0, 182276.0, 182389.0, 182443.0, 182610.0, 56739.77273295349, 56961.0, 57055.42944570501, 57073.71463142658, 57132.0, 59251.70374148102, 59493.0, 59612.0, 59661.70169149213, 59683.104753428735, 14131.0, 14549.418023279162, 14561.344739486749, 14574.562763116912, 14578.708425831424, 10324.638637647646, 10601.893981060244, 11101.83347249792, 11170.162237832032, 11192.944035279772, 16114.435994740128, 16507.422240221535, 16613.41853035138, 16625.83374166267, 16630.0, 6329.493640508748, 6334.493240540838, 6365.936340636627, 6385.2656944451055, 6630.469562434993, 822.7984143884787, 830.1947111302028, 839.7228914458215, 841.4320333774677, 863.3611127765533, 27684.723152768216, 28310.71689283122, 28794.895441290264, 28804.855975720406, 28941.0, 967.8548217767251, 991.1624677147798, 993.2997236947934, 997.7954519323521, 1000.8598796168542, 14989.325480353411, 15096.698066038618, 15187.544373668808, 15512.922435387976, 15520.223988800515, 348365.0, 384647.0, 554183.0, 554210.0, 554395.0, 44940.3258951116, 45492.3176152358, 45512.77243613761, 45581.31628025582, 45584.77207613941, 1240.1690867119053, 1251.5869762978195, 1253.248051169291, 1254.642426908345, 1262.4823822233025, 25347.87326063358, 25847.741522584533, 26222.0, 26335.07827226038, 26619.467610648066, 38159.80920095493, 38998.61001390063, 39058.414123787596, 39095.0, 39141.21717565576, 307031.0, 307266.0, 307601.0, 308183.0, 309816.0, 18938.0, 19775.0, 23144.3056708299, 23658.88170559136, 23939.76060239375, 35940.28119437649, 36220.81889590641, 36766.0, 37014.0, 37152.585081969584, 31340.216494586904, 31367.05898823039, 32236.19409514781, 32589.34821303608, 32949.3755686879, 30264.84867575648, 31252.843735781174, 31998.121577874696, 32431.513527296636, 32753.0, 30919.2270193247, 31461.0, 31702.682973169973, 31776.0, 31814.363712725153, 24174.51650967006, 25660.87169564215, 26746.0, 27128.728712712622, 28243.576346355232, 21536.569268614854, 21592.136314547242, 21975.0, 22076.2273320433, 22225.0], "value": 17561.080266513753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.10975883482547, 385.0450881813103, 396.2767948493999, 397.75538044102865, 427.2053979597947, 12688.27880653328, 14575.416983320667, 15551.144687042222, 18464.16911238994, 18570.814291857077, 16436.917815410907, 16555.25501352439, 16972.83027169728, 17021.57446063849, 17726.46820595381, 4631.16639004979, 4838.03239352131, 4845.927311090337, 4848.733319667417, 4849.975750121244, 4670.486246512881, 4797.2324428091515, 4797.568218860302, 4826.831060912868, 4834.419869615651, 4815.66290359675, 4822.831200907969, 4824.5899098576665, 4829.613630909536, 4829.951700483003, 4677.111348843709, 4820.30105634681, 4822.855314340558, 4829.251466022772, 4833.685810422311, 4758.476567577563, 4760.238361862085, 4764.428268607755, 4764.952350476486, 4767.046590681877, 12668.873311266861, 12845.229286242855, 20179.697304540445, 20839.270625528035, 21030.369088927357, 2229.5429436965383, 2236.8322375821717, 2445.6576079348906, 2490.663760392342, 2500.674912261394, 9816.803663926681, 9996.55015524296, 10225.437600931946, 10310.278280520366, 12628.494860205581, 1705.9914700426418, 1728.1532049295881, 1731.359397023097, 1733.2200509770653, 1756.9121543922881, 59141.704291478694, 74803.0, 80671.596642017, 81447.18552814514, 81566.0, 10233.079022887827, 10266.178705703525, 10462.163026957825, 10467.685969420929, 10583.206259530412, 14336.928315358356, 14358.641033974234, 14363.066400683922, 14372.784408233676, 14409.567712968628, 7382.741604043833, 7544.735934242385, 7607.277308655669, 7724.7682569523, 7727.343175830118, 7167.713291468387, 7218.386437152901, 7313.268673132644, 7360.521566098187, 7389.7413590524075, 4384.210842048462, 4495.100979803987, 4512.887177820581, 4608.0, 4634.050019745944, 1917.9328723494611, 1971.940841774749, 1985.7815640279557, 2005.9799402006086, 2008.688653258718, 723.2875617516847, 749.8950146979428, 755.1693137548652, 760.2739383888373, 776.2005134711256, 16436.37018077102, 16488.34046638145, 16515.0, 16639.50081497557, 16653.33386664544, 755.2862544895022, 755.7468248136826, 769.8652735771218, 774.2451110167542, 776.6000509737489, 8707.956460217658, 8964.68623598171, 9109.863352049724, 9317.813643727222, 9468.431894086378, 515224.0, 518301.0, 519785.0, 531729.0, 534218.0, 91377.54311228401, 92582.0, 92652.53673731723, 93269.0, 93473.0, 831.2004510235273, 834.6911642692121, 838.1199740272689, 839.3117643532368, 839.5550358310105, 14183.14901105937, 14308.57074287773, 14312.355943982337, 14412.783808242675, 14534.927325363305, 38526.42210366903, 39022.2195556093, 39310.01724956899, 39924.401133982436, 39976.60023399729, 51959.740201300265, 52337.7383113082, 53906.09770117746, 54155.18767218573, 55397.446025539226, 8082.19178082187, 8197.46716463428, 8349.624266907882, 8371.66513339484, 8746.125387461203, 7546.962265188639, 10394.896051039394, 12138.650361911641, 12296.569620063256, 12633.93683031579, 10377.0, 10704.411257380918, 11040.116790656568, 11061.170412219277, 11167.274127181707, 10674.46627668629, 10754.462276886281, 10857.185711071543, 11119.666410007712, 11125.833112503156, 10739.711234651892, 10926.836097458703, 10938.179636527451, 10940.671779846618, 11357.37534435614, 7848.489848159851, 8063.6774529018185, 8144.592770361577, 8202.876956845772, 8208.83582328362, 8488.40581159313, 10319.587216511256, 10381.429021403896, 10554.419506927192, 10863.13094952386], "value": 8299.838861580283, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1209.5585111434323, 1230.1450491908122, 1231.4212320209504, 1232.1005665863925, 1249.2941488059246, 6141.969290153543, 6597.142371491711, 7013.614251216181, 7015.33354331339, 7022.2977702229755, 6160.19917410736, 6375.139356186925, 6382.872342553146, 6391.648459334735, 6596.109525214095, 2062.3297428335777, 2105.799949004842, 2124.54322320701, 2130.5419334843004, 2131.6269652810774, 2055.650539408304, 2100.0339843671927, 2106.6734656128247, 2108.736407949006, 2110.5145816462223, 1819.1631849349315, 1879.1262063140637, 2093.842961777873, 2110.472381904524, 2115.4394085567337, 2062.669972804352, 2125.8511904166717, 2130.5206328576023, 2131.7228760261223, 2132.4775430019745, 2073.460900165953, 2078.6674132138787, 2084.2288353309223, 2084.3017589107667, 2086.3219453677466, 6963.547369421022, 6992.370686638176, 7000.7549735759, 7019.754308599175, 7026.191987921404, 3921.9215615687813, 4180.832766689329, 4191.7694526801015, 4193.958060419418, 4194.979025104885, 2745.32739478827, 2894.2908987298038, 2927.16575775905, 2935.911922642324, 2945.2931296488687, 1081.3457857995957, 1084.7233955341337, 1085.9185561082872, 1124.3591153042769, 1128.266626692648, 8912.24245939096, 8958.328125390623, 8961.507117108556, 8965.955170224108, 8988.730338089868, 3860.3630400983525, 4024.4164596133396, 4031.4355990161403, 4122.464079669624, 4125.1131006833675, 8009.279164875189, 8024.398170137263, 8033.0, 8033.437659363788, 8039.67841286342, 4421.425214722066, 4421.9336709949375, 4422.624076953431, 4424.535423780457, 4429.402030725811, 3880.1075752576503, 4059.715819892579, 4068.613481719232, 4071.1247081877536, 4073.735207211522, 3221.983890080535, 3253.8047717137047, 3312.1554003729375, 3312.585926759155, 3334.549835772188, 1031.4069410089228, 1039.3400190878847, 1040.698197522725, 1048.2871647279924, 1060.5492665617082, 672.6838385958591, 679.7145199016425, 691.3259571917441, 693.6081114170478, 773.5783997721255, 4441.600255976978, 4595.425571803525, 4676.321933319718, 4733.029728905566, 4881.194602890554, 857.7555396711907, 899.8020435504178, 929.2705226397246, 934.0519372836576, 935.9812803743887, 3067.831269280211, 3160.778745487794, 3259.1852036990754, 3312.569365982407, 3360.193553547131, 8012.158723333966, 8392.076871544125, 8392.4544904581, 8405.453645513144, 8410.411271211078, 8376.58117094155, 8387.580620968927, 8391.874121888177, 8394.412391132684, 8396.244338009608, 6586.868262634721, 6686.431653309523, 6689.1972963244725, 6692.096566963495, 6695.899561506581, 4610.585047345755, 4660.953390466052, 4722.055588882306, 4762.499937506512, 4768.976155119202, 7489.400847932044, 7551.546907185587, 7564.054493188351, 7576.810579735551, 7579.772606821804, 7562.659680314289, 7697.0, 7715.961420192863, 7747.612619368896, 7750.728724494616, 5803.158542011301, 5819.156222347651, 5960.701964901824, 5987.461128498456, 6104.969475152773, 6352.364763523796, 6396.936030639634, 6397.040443933447, 6397.104405383157, 6398.776042838665, 5729.4843464088435, 5791.47876691083, 5808.912866307093, 5942.910856337072, 5966.492848107786, 5733.283339582552, 5771.711414429178, 5783.16144159103, 5801.477866991991, 5804.477597016289, 5326.36083669962, 5334.039872822929, 5362.624616276979, 5365.382980957083, 5371.919421208607, 3873.6126387362165, 3922.6077392261686, 3926.5680775114142, 3930.194310166466, 4063.756174629532, 4099.139180772071, 4208.200441916026, 4215.178040282116, 4284.571542845691, 4372.846950356722], "value": 3753.5328979447404, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.92734134819653, 346.93061387722446, 365.7001258967647, 368.682932677897, 371.44469018816875, 4657.278121891108, 4780.0, 4854.441739199993, 4870.464248932623, 4878.731669758162, 4642.048380082075, 4696.788644510997, 4822.710637361752, 4836.395450568679, 4844.467108618049, 3891.3579259422163, 3906.1797022625283, 3906.628870257321, 3907.2771537265553, 3911.69210166002, 3895.2014836958497, 3896.240233154536, 3921.647031774033, 3923.077691930962, 3946.782926939017, 3881.3789793633023, 3888.358420860555, 3916.921661566767, 3922.2743792398355, 3939.409088636699, 3835.2329534093155, 3916.1776027034357, 3920.5099362579676, 3925.7448265862913, 3958.6437220650137, 3480.686738193575, 3497.230609265983, 3498.9153362457464, 3541.8052007139595, 3542.557180352456, 4706.552877476634, 4707.293905914106, 4713.717176969393, 4730.86459249781, 4738.265568836836, 4029.6776257899296, 4466.888327791831, 4467.218236808546, 4471.1728330259, 4502.099580083996, 5025.0, 5049.84850454487, 5058.317127187819, 5060.84817455477, 5061.0, 3637.781733096023, 3642.435422509489, 3647.361711700442, 3647.726420518445, 3656.0, 5299.125644268691, 5318.893622127574, 5338.065838478251, 5339.359276886759, 5356.973215133899, 3543.0, 3696.815159242027, 3704.1665625234646, 3717.4609681596016, 3735.43968404736, 3735.1409175889153, 3746.569144548357, 3757.4363845423404, 3766.37854753968, 3784.4890939722786, 4100.954256664531, 4116.217918595457, 4187.880029378132, 4224.556421575752, 4325.956740432618, 5008.574271186917, 5018.723970181675, 5034.270030845581, 5050.898982020339, 5053.823116190915, 1232.6548566401423, 1235.9814602780966, 1241.2304371289858, 1243.1919252485957, 1253.3983687829893, 833.1418638801922, 848.8641817309201, 857.5240741388465, 873.9294364403555, 877.5787621941504, 4261.616454519108, 4269.847141271901, 4275.615194632436, 4275.893102672458, 4287.6998610097, 5031.52200540948, 5034.521720436553, 5040.823571174991, 5044.495550444926, 5049.596032317406, 4138.8344466221615, 4188.6649068074485, 4280.073966714928, 4285.785710714452, 4300.677449191323, 2651.297406187362, 2677.236987458604, 2677.290518012728, 2680.651515302998, 2680.9731902680724, 4702.576768090888, 4703.565412549206, 4711.340412342275, 4726.456457507362, 4735.384400027974, 4914.928169893817, 5146.0, 5168.81909133186, 5182.455842136596, 5192.273081768555, 4663.300504924288, 4701.811927522861, 4706.270528068155, 4717.080169366941, 4722.315264286728, 5017.54842064216, 5017.767093643293, 5043.445221025758, 5051.318072060371, 5060.810709483342, 4647.395838540968, 4946.802127914987, 4952.504749525018, 4963.230699241623, 4992.750362481934, 4570.4972453030805, 4650.465646109049, 4686.507916668701, 4699.718016918859, 4714.717116972855, 3551.520544726429, 3559.697425718843, 3561.287742451572, 3567.0, 3592.2276710507367, 4885.389326334208, 5002.873204247346, 5014.799408023784, 5050.949490505048, 5054.444011158843, 4832.758362081811, 4866.245731911559, 4868.561829435368, 4875.24606053344, 4901.950980490149, 4846.110017093266, 4854.354376864547, 4879.951200488092, 4901.240307752304, 4919.0, 4585.065558042922, 4760.97619511914, 4824.638152138534, 4832.540908613676, 4856.611471082234, 4843.2635560907975, 4881.755912204447, 4888.6577939543895, 4904.6457700303345, 4906.460289368238, 4755.2153894607, 4764.213904705684, 4770.475247722678, 4772.49888761675, 4810.446798618063], "value": 3783.655854180706, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.9966200168999, 687.4775170870137, 735.2500449541468, 775.2131586439764, 791.7505985614531, 11517.884821151785, 12399.380030998436, 12437.875621243786, 12452.501899924004, 12453.937730311336, 11834.23077499962, 12009.159358844885, 12013.279203247812, 12020.51917923282, 12027.398630068483, 649.1982401733854, 669.5313280703527, 793.0721056364039, 808.9433739638228, 811.8498077855586, 816.0941355095839, 819.0703551469065, 819.8934138562008, 820.729159377404, 821.1993306526149, 847.8685803700437, 847.894013248344, 848.245061894914, 849.4393700157897, 850.3027517435705, 686.2931180883672, 708.8688592610332, 833.5873742497503, 846.2552953400999, 860.6256278518802, 637.0603360043945, 637.716216283754, 637.7990932856136, 637.9011253255753, 637.9330170332095, 12099.334536600483, 12125.878741212651, 12145.514179432812, 12147.878521214852, 12160.331181784999, 12960.740785184338, 13202.421143051195, 13267.86732132676, 13407.664808379772, 13496.865031349756, 12230.082743794163, 12297.085615652419, 12441.688957776038, 12547.0, 12608.495660173674, 4116.547179810218, 4217.8312867485265, 4228.619424251801, 4239.872803815891, 4347.065380943112, 65487.672561636886, 65768.67115664438, 66147.33852661459, 66668.99996500104, 67330.32669673339, 10804.567817287221, 10880.945595271973, 10955.287906286067, 11044.723881903017, 11544.538218471167, 20312.390628281177, 20431.693524597144, 20450.386488405373, 20540.897295513725, 20679.483012924495, 6711.530192886548, 6728.394444500019, 6740.359665831738, 6771.72913083481, 7006.229314775371, 3554.235839294564, 3565.3404120237838, 3604.7837129772297, 3606.404943184398, 3669.43123815809, 2811.1707046421357, 2877.3813630069217, 2885.581590669341, 2891.277180704824, 2900.318425170083, 474.24121405751214, 475.89768199837204, 479.4677907522647, 481.7543053042903, 486.1492388320439, 609.5611159964818, 610.6900500151144, 611.437477520682, 616.8488720263564, 626.264139635926, 8313.210245026834, 8348.499090054494, 8449.831003379897, 8498.957505212558, 8582.098879617675, 544.335910189568, 551.9034169020406, 558.6703844731628, 562.361719448429, 577.5177726598338, 3905.6492605130584, 3936.6653834424396, 4122.979385103115, 4178.6448151907425, 4226.535081141072, 70039.29960700328, 70614.0, 71085.64457177681, 71507.64246178736, 72921.27078729251, 50148.24777628339, 50347.0, 61294.693526532974, 63058.36941630525, 63281.36718632847, 53519.732401337744, 54553.0, 54659.453405465436, 55632.443675562725, 57215.14177287344, 7904.565248911135, 8043.436959412785, 8087.069986951341, 8112.675492980451, 8160.877586836321, 37967.43048854324, 38141.61858381492, 38848.80575597217, 38872.6112738869, 38980.610193898836, 41800.0, 43218.783906080265, 43982.78008610065, 47178.52821471879, 48373.75813120912, 11813.354962952833, 11933.164678472423, 11962.521499139937, 11977.940110299742, 12112.0, 12696.74606507883, 12746.808797867854, 12814.935925320313, 12983.41574629125, 13428.731425371243, 12168.148229623841, 12360.505579777067, 12720.872791272339, 12782.36088195568, 13425.86574134246, 11351.55581214793, 11553.595624153115, 12177.634670959886, 12933.611991640266, 13493.257870817199, 11875.228110172811, 12201.450934707782, 12406.62780116598, 12562.623121306375, 12908.483660653468, 9540.0, 9633.277504187074, 10155.136813370651, 10158.288919775545, 10619.415932123808, 9552.617895284111, 9779.804403912025, 9912.405255684682, 9962.701118966443, 9991.800163996824], "value": 6442.251532889306, "unit": "score"}], "name": "dromaeo_css", "value": 6314.161697959776, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 115.0, 115.0, 114.0, 113.0, 115.0, 113.0, 114.0, 117.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 122.0, 163.0, 125.0, 126.0, 124.0, 122.0, 143.0, 124.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 118.0, 78.0, 77.0, 78.0, 76.0, 90.0, 77.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 114.0, 85.0, 85.0, 85.0, 102.0, 85.0, 85.0, 111.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 119.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 149.0, 148.0, 149.0, 150.0, 150.0, 149.0, 149.0, 148.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 59.0, 59.0, 61.0, 60.0, 60.0, 61.0, 62.0, 62.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 67.0, 67.0, 68.0, 67.0, 67.0, 68.0, 68.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 85.0, 84.0, 84.0, 83.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 137.0, 134.0, 131.0, 130.0, 130.0, 142.0, 191.0, 134.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 175.0, 173.0, 182.0, 178.0, 172.0, 175.0, 173.0, 180.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 69.0, 71.0, 68.0, 68.0, 68.0, 69.0, 66.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1542.3999999999999, "alertThreshold": 2.0}]} 18:59:34 INFO - Return code: 0 18:59:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:59:34 INFO - # TBPL SUCCESS # 18:59:34 INFO - Running post-action listener: _resource_record_post_action 18:59:34 INFO - [mozharness: 2017-01-12 02:59:34.825997Z] Finished run-tests step (success) 18:59:34 INFO - Running post-run listener: _resource_record_post_run 18:59:34 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 399556608; Read time: 92; Write time: 269472 18:59:34 INFO - TinderboxPrint: CPU usage
13.1% 18:59:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 92 18:59:34 INFO - TinderboxPrint: I/O write bytes / time
399,556,608 / 269,472 18:59:34 INFO - TinderboxPrint: CPU idle
8,325.9 (86.8%) 18:59:34 INFO - TinderboxPrint: CPU user
1,216.9 (12.7%) 18:59:34 INFO - TinderboxPrint: Swap in / out
0 / 0 18:59:34 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99586048; Read time: 0; Write time: 94588 18:59:34 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283041792; Read time: 92; Write time: 167248 18:59:35 INFO - Running post-run listener: _upload_blobber_files 18:59:35 INFO - Blob upload gear active. 18:59:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:59:35 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 18:59:35 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:59:35 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:59:35 INFO - (blobuploader) - INFO - Open directory for files ... 18:59:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:59:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:59:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:39 INFO - (blobuploader) - INFO - Done attempting. 18:59:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:59:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:59:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:40 INFO - (blobuploader) - INFO - Done attempting. 18:59:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:59:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:59:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:41 INFO - (blobuploader) - INFO - Done attempting. 18:59:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:59:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:59:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:42 INFO - (blobuploader) - INFO - Done attempting. 18:59:42 INFO - (blobuploader) - INFO - Iteration through files over. 18:59:42 INFO - Return code: 0 18:59:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:59:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:59:42 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0cd251f56746774395949b89be437cf874451ee860588dfba4211d5c5ddc745b00765a9aa22a82e31141cfd7f1850e96d05709e9910ded465e4e8cca610c993a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/466cb3c26a40e6ef9422576b89da269c35304dc4d9c0ba96cce22415ad5d7363180739373d3e4b79b3eb389756fa70ed214cb67224995c6e6c267ef6a69a47ce", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1653f2dbe111a0324d8bb79f102b499125d52b273631c8cc0f352931d965ba70c6d65f4b5df682f5dbca7f97f2f788af135f1d558e7c48e78df726a3f3db945b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/153542d663b82755d0e819a00743db8af0eebead35b9b919c9df591fd224a081fc6bdcc0303fe8c4f621f8bd72c980b4409d2e77c7333404046a46dcb0605f31"} 18:59:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:59:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:59:42 INFO - Contents: 18:59:42 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0cd251f56746774395949b89be437cf874451ee860588dfba4211d5c5ddc745b00765a9aa22a82e31141cfd7f1850e96d05709e9910ded465e4e8cca610c993a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/466cb3c26a40e6ef9422576b89da269c35304dc4d9c0ba96cce22415ad5d7363180739373d3e4b79b3eb389756fa70ed214cb67224995c6e6c267ef6a69a47ce", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1653f2dbe111a0324d8bb79f102b499125d52b273631c8cc0f352931d965ba70c6d65f4b5df682f5dbca7f97f2f788af135f1d558e7c48e78df726a3f3db945b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/153542d663b82755d0e819a00743db8af0eebead35b9b919c9df591fd224a081fc6bdcc0303fe8c4f621f8bd72c980b4409d2e77c7333404046a46dcb0605f31"} 18:59:42 INFO - Running post-run listener: copy_logs_to_upload_dir 18:59:42 INFO - Copying logs to upload dir... 18:59:42 INFO - mkdir: /builds/slave/test/build/upload/logs 18:59:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.155075 ========= master_lag: 9.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-01-11 18:59:50.906555) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 18:59:50.910602) ========= 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=3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0cd251f56746774395949b89be437cf874451ee860588dfba4211d5c5ddc745b00765a9aa22a82e31141cfd7f1850e96d05709e9910ded465e4e8cca610c993a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/466cb3c26a40e6ef9422576b89da269c35304dc4d9c0ba96cce22415ad5d7363180739373d3e4b79b3eb389756fa70ed214cb67224995c6e6c267ef6a69a47ce", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1653f2dbe111a0324d8bb79f102b499125d52b273631c8cc0f352931d965ba70c6d65f4b5df682f5dbca7f97f2f788af135f1d558e7c48e78df726a3f3db945b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/153542d663b82755d0e819a00743db8af0eebead35b9b919c9df591fd224a081fc6bdcc0303fe8c4f621f8bd72c980b4409d2e77c7333404046a46dcb0605f31"} build_url:https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011441 build_url: 'https://queue.taskcluster.net/v1/task/cGzvhvSmRkaL7-FrPINIOA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0cd251f56746774395949b89be437cf874451ee860588dfba4211d5c5ddc745b00765a9aa22a82e31141cfd7f1850e96d05709e9910ded465e4e8cca610c993a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/466cb3c26a40e6ef9422576b89da269c35304dc4d9c0ba96cce22415ad5d7363180739373d3e4b79b3eb389756fa70ed214cb67224995c6e6c267ef6a69a47ce", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1653f2dbe111a0324d8bb79f102b499125d52b273631c8cc0f352931d965ba70c6d65f4b5df682f5dbca7f97f2f788af135f1d558e7c48e78df726a3f3db945b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/153542d663b82755d0e819a00743db8af0eebead35b9b919c9df591fd224a081fc6bdcc0303fe8c4f621f8bd72c980b4409d2e77c7333404046a46dcb0605f31"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 18:59:50.940729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:59:50.941049) ========= 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=3a3620fb28aea1604ab609ef000001d2-1484188565.554325-2146637224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004678 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 18:59:51.075137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 18:59:51.075457) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 18:59:51.075749) ========= ========= Total master_lag: 9.35 =========