builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-031 starttime: 1484302101.65 results: success (0) revision: 248399829d40ff424b3992add92e7c86486049e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.651940) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.652341) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.652669) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.679822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.680088) ========= 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=db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-13 02:08:22-- 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% 46.1M=0s 2017-01-13 02:08:22 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119452 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.816180) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.816475) ========= 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=db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017235 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:21.865979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 02:08:21.866293) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 248399829d40ff424b3992add92e7c86486049e8 --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 248399829d40ff424b3992add92e7c86486049e8 --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=db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-13 02:08:22,259 truncating revision to first 12 chars 2017-01-13 02:08:22,259 Setting DEBUG logging. 2017-01-13 02:08:22,259 attempt 1/10 2017-01-13 02:08:22,259 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/248399829d40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 02:08:23,242 unpacking tar archive at: graphics-248399829d40/testing/mozharness/ program finished with exit code 0 elapsedTime=1.324235 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 02:08:23.206451) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:23.206774) ========= script_repo_revision: 248399829d40ff424b3992add92e7c86486049e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:23.207143) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:23.207412) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 02:08:23.217298) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-13 02:08:23.217587) ========= /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=db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:08:23 INFO - MultiFileLogger online at 20170113 02:08:23 in /builds/slave/test 02:08:23 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 02:08:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:23 INFO - {'append_to_log': False, 02:08:23 INFO - 'base_work_dir': '/builds/slave/test', 02:08:23 INFO - 'blob_upload_branch': 'Graphics', 02:08:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:23 INFO - 'branch': 'Graphics', 02:08:23 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:23 INFO - 'config_files': ('talos/linux_config.py',), 02:08:23 INFO - 'default_actions': ('clobber', 02:08:23 INFO - 'read-buildbot-config', 02:08:23 INFO - 'download-and-extract', 02:08:23 INFO - 'populate-webroot', 02:08:23 INFO - 'create-virtualenv', 02:08:23 INFO - 'install', 02:08:23 INFO - 'run-tests'), 02:08:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:23 INFO - 'download_minidump_stackwalk': True, 02:08:23 INFO - 'download_symbols': 'ondemand', 02:08:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:08:23 INFO - 'tooltool.py': '/tools/tooltool.py', 02:08:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:08:23 INFO - '/tools/misc-python/virtualenv.py')}, 02:08:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:23 INFO - 'installer_path': 'installer.exe', 02:08:23 INFO - 'log_level': 'info', 02:08:23 INFO - 'log_name': 'talos', 02:08:23 INFO - 'log_to_console': True, 02:08:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:08:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:08:23 INFO - 'opt_config_files': (), 02:08:23 INFO - 'pip_index': False, 02:08:23 INFO - 'sps_profile': False, 02:08:23 INFO - 'sps_profile_interval': 0, 02:08:23 INFO - 'suite': 'dromaeojs', 02:08:23 INFO - 'system_bits': '32', 02:08:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:23 INFO - 'title': 'talos-linux64-ix-031', 02:08:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:23 INFO - 'use_talos_json': True, 02:08:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:23 INFO - 'work_dir': 'build'} 02:08:23 INFO - [mozharness: 2017-01-13 10:08:23.705018Z] Running clobber step. 02:08:23 INFO - Running pre-action listener: _resource_record_pre_action 02:08:23 INFO - Running main action method: clobber 02:08:23 INFO - rmtree: /builds/slave/test/build 02:08:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:24 INFO - Running post-action listener: _resource_record_post_action 02:08:24 INFO - [mozharness: 2017-01-13 10:08:24.165522Z] Finished clobber step (success) 02:08:24 INFO - [mozharness: 2017-01-13 10:08:24.165622Z] Running read-buildbot-config step. 02:08:24 INFO - Running pre-action listener: _resource_record_pre_action 02:08:24 INFO - Running main action method: read_buildbot_config 02:08:24 INFO - Using buildbot properties: 02:08:24 INFO - { 02:08:24 INFO - "project": "", 02:08:24 INFO - "product": "firefox", 02:08:24 INFO - "who": "ethlin@mozilla.com", 02:08:24 INFO - "installer_path": "public/build/target.tar.bz2", 02:08:24 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 02:08:24 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 02:08:24 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 02:08:24 INFO - "stage_platform": "linux64", 02:08:24 INFO - "basedir": "/builds/slave/test", 02:08:24 INFO - "buildnumber": 16, 02:08:24 INFO - "platform": "ubuntu64_hw", 02:08:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:08:24 INFO - "slavebuilddir": "test", 02:08:24 INFO - "taskId": "SOY3HrqRSF-KOuRUbtRHog", 02:08:24 INFO - "branch": "graphics", 02:08:24 INFO - "script_repo_revision": "default", 02:08:24 INFO - "revision": "248399829d40ff424b3992add92e7c86486049e8", 02:08:24 INFO - "slavename": "talos-linux64-ix-031", 02:08:24 INFO - "repo_path": "projects/graphics" 02:08:24 INFO - } 02:08:24 INFO - Finding installer, test and symbols from parent task. 02:08:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SOY3HrqRSF-KOuRUbtRHog'}, attempt #1 02:08:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SOY3HrqRSF-KOuRUbtRHog'}, attempt #1 02:08:25 INFO - Task dependencies: I-nqUarhTYGCDjWtbNGYkg 02:08:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg'}, attempt #1 02:08:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2 02:08:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/test_packages.json 02:08:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.crashreporter-symbols.zip 02:08:25 INFO - Running post-action listener: _resource_record_post_action 02:08:25 INFO - [mozharness: 2017-01-13 10:08:25.659368Z] Finished read-buildbot-config step (success) 02:08:25 INFO - [mozharness: 2017-01-13 10:08:25.659585Z] Running download-and-extract step. 02:08:25 INFO - Running pre-action listener: _resource_record_pre_action 02:08:25 INFO - Running main action method: download_and_extract 02:08:25 INFO - mkdir: /builds/slave/test/build/tests 02:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:25 INFO - trying https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.test_packages.json 02:08:25 INFO - Downloading https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:08:26 INFO - Downloaded 896 bytes. 02:08:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:08:26 INFO - Using the following test package requirements: 02:08:26 INFO - {u'common': [u'target.common.tests.zip'], 02:08:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:08:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:08:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:08:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:08:26 INFO - u'mozbase': [u'target.common.tests.zip'], 02:08:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:08:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:08:26 INFO - u'web-platform': [u'target.common.tests.zip', 02:08:26 INFO - u'target.web-platform.tests.zip'], 02:08:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:08:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:08:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.common.tests.zip 02:08:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:08:26 INFO - Fetch https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.common.tests.zip into memory 02:08:27 INFO - Expected file size: 20260791 02:08:27 INFO - Obtained file size: 20260791 02:08:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:08:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.common.tests.zip 02:08:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:08:31 INFO - Fetch https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.common.tests.zip into memory 02:08:32 INFO - Expected file size: 20260791 02:08:32 INFO - Obtained file size: 20260791 02:08:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.talos.tests.zip 02:08:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:08:36 INFO - Fetch https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.talos.tests.zip into memory 02:08:38 INFO - Expected file size: 14403532 02:08:38 INFO - Obtained file size: 14403532 02:08:38 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')]} 02:08:38 INFO - trying https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2 02:08:38 INFO - Downloading https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:08:40 INFO - Downloaded 56131619 bytes. 02:08:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2 02:08:40 INFO - mkdir: /builds/slave/test/properties 02:08:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:08:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:08:40 INFO - Contents: 02:08:40 INFO - build_url:https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2 02:08:40 INFO - Running post-action listener: _resource_record_post_action 02:08:40 INFO - Running post-action listener: set_extra_try_arguments 02:08:40 INFO - [mozharness: 2017-01-13 10:08:40.370525Z] Finished download-and-extract step (success) 02:08:40 INFO - [mozharness: 2017-01-13 10:08:40.370637Z] Running populate-webroot step. 02:08:40 INFO - Running pre-action listener: _resource_record_pre_action 02:08:40 INFO - Running main action method: populate_webroot 02:08:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:08:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:08:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:08:40 INFO - '--tppagecycles', 02:08:40 INFO - '7'], 02:08:40 INFO - 'tests': ['tsvgm']}}, 02:08:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:08:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:08:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:08:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:08:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:08:40 INFO - 'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:08:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:08:40 INFO - 'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['damp', 'tps']}, 02:08:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:08:40 INFO - 'tests': ['damp', 'tps']}, 02:08:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['dromaeo_dom']}, 02:08:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:08:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['basic_compositor_video']}, 02:08:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:08:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['a11yr', 02:08:40 INFO - 'ts_paint', 02:08:40 INFO - 'tpaint', 02:08:40 INFO - 'sessionrestore', 02:08:40 INFO - 'sessionrestore_no_auto_restore', 02:08:40 INFO - 'tabpaint']}, 02:08:40 INFO - 'other-e10s': {'tests': ['a11yr', 02:08:40 INFO - 'ts_paint', 02:08:40 INFO - 'tpaint', 02:08:40 INFO - 'sessionrestore', 02:08:40 INFO - 'sessionrestore_no_auto_restore', 02:08:40 INFO - 'tabpaint']}, 02:08:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:08:40 INFO - 'ts_paint', 02:08:40 INFO - 'tpaint', 02:08:40 INFO - 'sessionrestore', 02:08:40 INFO - 'sessionrestore_no_auto_restore', 02:08:40 INFO - 'tabpaint']}, 02:08:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:08:40 INFO - 'ts_paint', 02:08:40 INFO - 'tpaint', 02:08:40 INFO - 'sessionrestore', 02:08:40 INFO - 'sessionrestore_no_auto_restore', 02:08:40 INFO - 'tabpaint']}, 02:08:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['a11yr', 02:08:40 INFO - 'ts_paint', 02:08:40 INFO - 'tpaint', 02:08:40 INFO - 'sessionrestore', 02:08:40 INFO - 'sessionrestore_no_auto_restore', 02:08:40 INFO - 'tabpaint']}, 02:08:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['a11yr', 02:08:40 INFO - 'ts_paint', 02:08:40 INFO - 'tpaint', 02:08:40 INFO - 'sessionrestore', 02:08:40 INFO - 'sessionrestore_no_auto_restore', 02:08:40 INFO - 'tabpaint']}, 02:08:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['tsvgx', 02:08:40 INFO - 'tsvgr_opacity', 02:08:40 INFO - 'tart', 02:08:40 INFO - 'tscrollx', 02:08:40 INFO - 'cart', 02:08:40 INFO - 'tsvg_static']}, 02:08:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:08:40 INFO - 'tsvgr_opacity', 02:08:40 INFO - 'tart', 02:08:40 INFO - 'tscrollx', 02:08:40 INFO - 'cart', 02:08:40 INFO - 'tsvg_static']}, 02:08:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:08:40 INFO - 'talos_options': ['--disable-e10s'], 02:08:40 INFO - 'tests': ['tp5o']}, 02:08:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:08:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:08:40 INFO - 'talos_options': ['--disable-e10s', 02:08:40 INFO - '--xperf_path', 02:08:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:40 INFO - 'tests': ['tp5n']}, 02:08:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:08:40 INFO - 'talos_options': ['--xperf_path', 02:08:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:40 INFO - 'tests': ['tp5n']}}, 02:08:40 INFO - 'talos.zip': {'path': '', 02:08:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:08:40 INFO - Running post-action listener: _resource_record_post_action 02:08:40 INFO - [mozharness: 2017-01-13 10:08:40.385496Z] Finished populate-webroot step (success) 02:08:40 INFO - [mozharness: 2017-01-13 10:08:40.385591Z] Running create-virtualenv step. 02:08:40 INFO - Running pre-action listener: _resource_record_pre_action 02:08:40 INFO - Running main action method: create_virtualenv 02:08:40 INFO - Creating virtualenv /builds/slave/test/build/venv 02:08:40 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 02:08:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:08:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:08:40 INFO - Using real prefix '/usr' 02:08:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:08:40 INFO - Installing distribute.............................................................................................................................................................................................done. 02:08:42 INFO - Installing pip.................done. 02:08:42 INFO - Return code: 0 02:08:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:08:43 INFO - Reading from file tmpfile_stdout 02:08:43 INFO - Output received: 02:08:43 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) 02:08:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:08:43 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')]} 02:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:43 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')]} 02:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:43 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:43 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 02:08:43 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 02:08:43 INFO - Using env: {'DISPLAY': ':0', 02:08:43 INFO - 'HOME': '/home/cltbld', 02:08:43 INFO - 'LANG': 'en_US.UTF-8', 02:08:43 INFO - 'LANGUAGE': 'en_US:en', 02:08:43 INFO - 'LOGNAME': 'cltbld', 02:08:43 INFO - 'MAIL': '/var/mail/cltbld', 02:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:43 INFO - 'MOZ_NO_REMOTE': '1', 02:08:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:43 INFO - 'NO_EM_RESTART': '1', 02:08:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:43 INFO - 'PWD': '/builds/slave/test', 02:08:43 INFO - 'SHELL': '/bin/bash', 02:08:43 INFO - 'SHLVL': '1', 02:08:43 INFO - 'TERM': 'linux', 02:08:43 INFO - 'TMOUT': '86400', 02:08:43 INFO - 'USER': 'cltbld', 02:08:43 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:43 INFO - '_': '/tools/buildbot/bin/python'} 02:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:43 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 02:08:43 INFO - Cleaning up... 02:08:43 INFO - Return code: 0 02:08:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:08:43 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')]} 02:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:43 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')]} 02:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:43 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:43 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 02:08:43 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 02:08:43 INFO - Using env: {'DISPLAY': ':0', 02:08:43 INFO - 'HOME': '/home/cltbld', 02:08:43 INFO - 'LANG': 'en_US.UTF-8', 02:08:43 INFO - 'LANGUAGE': 'en_US:en', 02:08:43 INFO - 'LOGNAME': 'cltbld', 02:08:43 INFO - 'MAIL': '/var/mail/cltbld', 02:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:43 INFO - 'MOZ_NO_REMOTE': '1', 02:08:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:43 INFO - 'NO_EM_RESTART': '1', 02:08:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:43 INFO - 'PWD': '/builds/slave/test', 02:08:43 INFO - 'SHELL': '/bin/bash', 02:08:43 INFO - 'SHLVL': '1', 02:08:43 INFO - 'TERM': 'linux', 02:08:43 INFO - 'TMOUT': '86400', 02:08:43 INFO - 'USER': 'cltbld', 02:08:43 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:43 INFO - '_': '/tools/buildbot/bin/python'} 02:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:43 INFO - Downloading/unpacking psutil>=3.1.1 02:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:08:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:45 INFO - Installing collected packages: psutil 02:08:45 INFO - Running setup.py install for psutil 02:08:45 INFO - building 'psutil._psutil_linux' extension 02:08:45 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 02:08:45 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 02:08:45 INFO - building 'psutil._psutil_posix' extension 02:08:45 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 02:08:46 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 02:08:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:46 INFO - Successfully installed psutil 02:08:46 INFO - Cleaning up... 02:08:46 INFO - Return code: 0 02:08:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:08:46 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')]} 02:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:46 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')]} 02:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:46 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:46 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 02:08:46 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 02:08:46 INFO - Using env: {'DISPLAY': ':0', 02:08:46 INFO - 'HOME': '/home/cltbld', 02:08:46 INFO - 'LANG': 'en_US.UTF-8', 02:08:46 INFO - 'LANGUAGE': 'en_US:en', 02:08:46 INFO - 'LOGNAME': 'cltbld', 02:08:46 INFO - 'MAIL': '/var/mail/cltbld', 02:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:46 INFO - 'MOZ_NO_REMOTE': '1', 02:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:46 INFO - 'NO_EM_RESTART': '1', 02:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:46 INFO - 'PWD': '/builds/slave/test', 02:08:46 INFO - 'SHELL': '/bin/bash', 02:08:46 INFO - 'SHLVL': '1', 02:08:46 INFO - 'TERM': 'linux', 02:08:46 INFO - 'TMOUT': '86400', 02:08:46 INFO - 'USER': 'cltbld', 02:08:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:46 INFO - '_': '/tools/buildbot/bin/python'} 02:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:08:46 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 02:08:46 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 02:08:46 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 02:08:46 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 02:08:46 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 02:08:46 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 02:08:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:08:48 INFO - Installing collected packages: mozsystemmonitor 02:08:48 INFO - Running setup.py install for mozsystemmonitor 02:08:48 INFO - Successfully installed mozsystemmonitor 02:08:48 INFO - Cleaning up... 02:08:48 INFO - Return code: 0 02:08:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:48 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:48 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 02:08:48 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 02:08:48 INFO - Using env: {'DISPLAY': ':0', 02:08:48 INFO - 'HOME': '/home/cltbld', 02:08:48 INFO - 'LANG': 'en_US.UTF-8', 02:08:48 INFO - 'LANGUAGE': 'en_US:en', 02:08:48 INFO - 'LOGNAME': 'cltbld', 02:08:48 INFO - 'MAIL': '/var/mail/cltbld', 02:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:48 INFO - 'MOZ_NO_REMOTE': '1', 02:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:48 INFO - 'NO_EM_RESTART': '1', 02:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:48 INFO - 'PWD': '/builds/slave/test', 02:08:48 INFO - 'SHELL': '/bin/bash', 02:08:48 INFO - 'SHLVL': '1', 02:08:48 INFO - 'TERM': 'linux', 02:08:48 INFO - 'TMOUT': '86400', 02:08:48 INFO - 'USER': 'cltbld', 02:08:48 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:48 INFO - '_': '/tools/buildbot/bin/python'} 02:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:48 INFO - Downloading/unpacking jsonschema==2.5.1 02:08:48 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 02:08:48 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 02:08:48 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 02:08:48 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 02:08:48 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 02:08:48 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 02:08:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:08:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:08:50 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 02:08:50 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 02:08:50 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 02:08:50 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 02:08:50 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 02:08:50 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 02:08:51 INFO - Downloading functools32-3.2.3-2.tar.gz 02:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:08:51 INFO - warning: no files found matching '*.txt' 02:08:51 INFO - no previously-included directories found matching 'build' 02:08:51 INFO - no previously-included directories found matching 'dist' 02:08:51 INFO - no previously-included directories found matching '.git*' 02:08:51 INFO - Installing collected packages: jsonschema, functools32 02:08:51 INFO - Running setup.py install for functools32 02:08:51 INFO - warning: no files found matching '*.txt' 02:08:51 INFO - no previously-included directories found matching 'build' 02:08:51 INFO - no previously-included directories found matching 'dist' 02:08:51 INFO - no previously-included directories found matching '.git*' 02:08:51 INFO - Successfully installed jsonschema functools32 02:08:51 INFO - Cleaning up... 02:08:51 INFO - Return code: 0 02:08:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:08:51 INFO - Using env: {'DISPLAY': ':0', 02:08:51 INFO - 'HOME': '/home/cltbld', 02:08:51 INFO - 'LANG': 'en_US.UTF-8', 02:08:51 INFO - 'LANGUAGE': 'en_US:en', 02:08:51 INFO - 'LOGNAME': 'cltbld', 02:08:51 INFO - 'MAIL': '/var/mail/cltbld', 02:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:51 INFO - 'MOZ_NO_REMOTE': '1', 02:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:51 INFO - 'NO_EM_RESTART': '1', 02:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:51 INFO - 'PWD': '/builds/slave/test', 02:08:51 INFO - 'SHELL': '/bin/bash', 02:08:51 INFO - 'SHLVL': '1', 02:08:51 INFO - 'TERM': 'linux', 02:08:51 INFO - 'TMOUT': '86400', 02:08:51 INFO - 'USER': 'cltbld', 02:08:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:51 INFO - '_': '/tools/buildbot/bin/python'} 02:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:08:51 INFO - Cleaning up... 02:08:51 INFO - Return code: 0 02:08:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:08:51 INFO - Using env: {'DISPLAY': ':0', 02:08:51 INFO - 'HOME': '/home/cltbld', 02:08:51 INFO - 'LANG': 'en_US.UTF-8', 02:08:51 INFO - 'LANGUAGE': 'en_US:en', 02:08:51 INFO - 'LOGNAME': 'cltbld', 02:08:51 INFO - 'MAIL': '/var/mail/cltbld', 02:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:51 INFO - 'MOZ_NO_REMOTE': '1', 02:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:51 INFO - 'NO_EM_RESTART': '1', 02:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:51 INFO - 'PWD': '/builds/slave/test', 02:08:51 INFO - 'SHELL': '/bin/bash', 02:08:51 INFO - 'SHLVL': '1', 02:08:51 INFO - 'TERM': 'linux', 02:08:51 INFO - 'TMOUT': '86400', 02:08:51 INFO - 'USER': 'cltbld', 02:08:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:51 INFO - '_': '/tools/buildbot/bin/python'} 02:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:51 INFO - Downloading/unpacking blobuploader==1.2.4 02:08:51 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 02:08:51 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 02:08:51 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 02:08:51 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 02:08:51 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 02:08:51 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 02:08:54 INFO - Downloading blobuploader-1.2.4.tar.gz 02:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:08:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:08:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 INFO - Downloading docopt-0.6.1.tar.gz 02:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:08:54 INFO - Installing collected packages: blobuploader, requests, docopt 02:08:54 INFO - Running setup.py install for blobuploader 02:08:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:08:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:08:54 INFO - Running setup.py install for requests 02:08:55 INFO - Running setup.py install for docopt 02:08:55 INFO - Successfully installed blobuploader requests docopt 02:08:55 INFO - Cleaning up... 02:08:55 INFO - Return code: 0 02:08:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:55 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:55 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 02:08:55 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 02:08:55 INFO - Using env: {'DISPLAY': ':0', 02:08:55 INFO - 'HOME': '/home/cltbld', 02:08:55 INFO - 'LANG': 'en_US.UTF-8', 02:08:55 INFO - 'LANGUAGE': 'en_US:en', 02:08:55 INFO - 'LOGNAME': 'cltbld', 02:08:55 INFO - 'MAIL': '/var/mail/cltbld', 02:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:55 INFO - 'MOZ_NO_REMOTE': '1', 02:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:55 INFO - 'NO_EM_RESTART': '1', 02:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:55 INFO - 'PWD': '/builds/slave/test', 02:08:55 INFO - 'SHELL': '/bin/bash', 02:08:55 INFO - 'SHLVL': '1', 02:08:55 INFO - 'TERM': 'linux', 02:08:55 INFO - 'TMOUT': '86400', 02:08:55 INFO - 'USER': 'cltbld', 02:08:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:55 INFO - '_': '/tools/buildbot/bin/python'} 02:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:55 INFO - Running setup.py (path:/tmp/pip-56v5K7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:55 INFO - Running setup.py (path:/tmp/pip-UGp4VV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:55 INFO - Running setup.py (path:/tmp/pip-uRoV_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:55 INFO - Running setup.py (path:/tmp/pip-VORRMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:55 INFO - Running setup.py (path:/tmp/pip-Yb9WIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:56 INFO - Running setup.py (path:/tmp/pip-JBOjYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:56 INFO - Running setup.py (path:/tmp/pip-viyHcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:56 INFO - Running setup.py (path:/tmp/pip-Q1SlR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:56 INFO - Running setup.py (path:/tmp/pip-4yaURn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:56 INFO - Running setup.py (path:/tmp/pip-Sd74a3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:56 INFO - Running setup.py (path:/tmp/pip-__Ro2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:56 INFO - Running setup.py (path:/tmp/pip-OrbWm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:56 INFO - Running setup.py (path:/tmp/pip-VLqahl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:56 INFO - Running setup.py (path:/tmp/pip-8x7Ra4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:56 INFO - Running setup.py (path:/tmp/pip-gZ96DC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:56 INFO - Running setup.py (path:/tmp/pip-sVh7Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:56 INFO - Running setup.py (path:/tmp/pip-eOrIek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:08:56 INFO - Running setup.py install for manifestparser 02:08:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:08:56 INFO - Running setup.py install for mozcrash 02:08:57 INFO - Running setup.py install for mozdebug 02:08:57 INFO - Running setup.py install for mozdevice 02:08:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozfile 02:08:57 INFO - Running setup.py install for mozhttpd 02:08:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozinfo 02:08:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozInstall 02:08:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozleak 02:08:57 INFO - Running setup.py install for mozlog 02:08:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for moznetwork 02:08:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozprocess 02:08:58 INFO - Running setup.py install for mozprofile 02:08:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozrunner 02:08:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozscreenshot 02:08:58 INFO - Running setup.py install for moztest 02:08:58 INFO - Running setup.py install for mozversion 02:08:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:08:58 INFO - Cleaning up... 02:08:58 INFO - Return code: 0 02:08:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:58 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:08:58 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 02:08:58 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 02:08:58 INFO - Using env: {'DISPLAY': ':0', 02:08:58 INFO - 'HOME': '/home/cltbld', 02:08:58 INFO - 'LANG': 'en_US.UTF-8', 02:08:58 INFO - 'LANGUAGE': 'en_US:en', 02:08:58 INFO - 'LOGNAME': 'cltbld', 02:08:58 INFO - 'MAIL': '/var/mail/cltbld', 02:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:58 INFO - 'MOZ_NO_REMOTE': '1', 02:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:58 INFO - 'NO_EM_RESTART': '1', 02:08:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:58 INFO - 'PWD': '/builds/slave/test', 02:08:58 INFO - 'SHELL': '/bin/bash', 02:08:58 INFO - 'SHLVL': '1', 02:08:58 INFO - 'TERM': 'linux', 02:08:58 INFO - 'TMOUT': '86400', 02:08:58 INFO - 'USER': 'cltbld', 02:08:58 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:58 INFO - '_': '/tools/buildbot/bin/python'} 02:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:58 INFO - Running setup.py (path:/tmp/pip-yzGjNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:58 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)) 02:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:58 INFO - Running setup.py (path:/tmp/pip-uBSNMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:59 INFO - Running setup.py (path:/tmp/pip-XWQ5kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:59 INFO - Running setup.py (path:/tmp/pip-FaCkxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:59 INFO - Running setup.py (path:/tmp/pip-bYBFGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:59 INFO - Running setup.py (path:/tmp/pip-i17q6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:59 INFO - Running setup.py (path:/tmp/pip-ySQLMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:59 INFO - Running setup.py (path:/tmp/pip-ywz1hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:59 INFO - Running setup.py (path:/tmp/pip-kvb9N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:59 INFO - Running setup.py (path:/tmp/pip-zYYajv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:59 INFO - Running setup.py (path:/tmp/pip-mAeMAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:59 INFO - Running setup.py (path:/tmp/pip-M0KXvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:59 INFO - Running setup.py (path:/tmp/pip-VTy7kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:59 INFO - Running setup.py (path:/tmp/pip-y8U9na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:59 INFO - Running setup.py (path:/tmp/pip-Dnc3jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:59 INFO - Running setup.py (path:/tmp/pip-7A5n51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:00 INFO - Running setup.py (path:/tmp/pip-968XDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:00 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)) 02:09:00 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)) 02:09:00 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)) 02:09:00 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)) 02:09:00 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)) 02:09:00 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)) 02:09:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09:00 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 02:09:00 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 02:09:00 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 02:09:00 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 02:09:00 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 02:09:00 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 02:09:02 INFO - Downloading blessings-1.6.tar.gz 02:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:09:02 INFO - Installing collected packages: blessings 02:09:02 INFO - Running setup.py install for blessings 02:09:02 INFO - Successfully installed blessings 02:09:02 INFO - Cleaning up... 02:09:02 INFO - Return code: 0 02:09:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:02 INFO - Reading from file tmpfile_stdout 02:09:02 INFO - Current package versions: 02:09:02 INFO - argparse == 1.2.1 02:09:02 INFO - blessings == 1.6 02:09:02 INFO - blobuploader == 1.2.4 02:09:02 INFO - docopt == 0.6.1 02:09:02 INFO - functools32 == 3.2.3-2 02:09:02 INFO - jsonschema == 2.5.1 02:09:02 INFO - manifestparser == 1.1 02:09:02 INFO - mozInstall == 1.12 02:09:02 INFO - mozcrash == 1.0 02:09:02 INFO - mozdebug == 0.1 02:09:02 INFO - mozdevice == 0.49 02:09:02 INFO - mozfile == 1.2 02:09:02 INFO - mozhttpd == 0.7 02:09:02 INFO - mozinfo == 0.9 02:09:02 INFO - mozleak == 0.1 02:09:02 INFO - mozlog == 3.4 02:09:02 INFO - moznetwork == 0.27 02:09:02 INFO - mozprocess == 0.24 02:09:02 INFO - mozprofile == 0.28 02:09:02 INFO - mozrunner == 6.13 02:09:02 INFO - mozscreenshot == 0.1 02:09:02 INFO - mozsystemmonitor == 0.3 02:09:02 INFO - moztest == 0.8 02:09:02 INFO - mozversion == 1.4 02:09:02 INFO - psutil == 3.1.1 02:09:02 INFO - requests == 1.2.3 02:09:02 INFO - wsgiref == 0.1.2 02:09:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:02 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')]} 02:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:02 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')]} 02:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:02 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:09:02 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 02:09:02 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 02:09:02 INFO - Using env: {'DISPLAY': ':0', 02:09:02 INFO - 'HOME': '/home/cltbld', 02:09:02 INFO - 'LANG': 'en_US.UTF-8', 02:09:02 INFO - 'LANGUAGE': 'en_US:en', 02:09:02 INFO - 'LOGNAME': 'cltbld', 02:09:02 INFO - 'MAIL': '/var/mail/cltbld', 02:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:02 INFO - 'MOZ_NO_REMOTE': '1', 02:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:09:02 INFO - 'NO_EM_RESTART': '1', 02:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:02 INFO - 'PWD': '/builds/slave/test', 02:09:02 INFO - 'SHELL': '/bin/bash', 02:09:02 INFO - 'SHLVL': '1', 02:09:02 INFO - 'TERM': 'linux', 02:09:02 INFO - 'TMOUT': '86400', 02:09:02 INFO - 'USER': 'cltbld', 02:09:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:02 INFO - '_': '/tools/buildbot/bin/python'} 02:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 INFO - Cleaning up... 02:09:02 INFO - Return code: 0 02:09:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:09:02 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')]} 02:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:02 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')]} 02:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:02 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 0x1e091f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, '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': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', '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 02:09:02 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 02:09:02 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 02:09:02 INFO - Using env: {'DISPLAY': ':0', 02:09:02 INFO - 'HOME': '/home/cltbld', 02:09:02 INFO - 'LANG': 'en_US.UTF-8', 02:09:02 INFO - 'LANGUAGE': 'en_US:en', 02:09:02 INFO - 'LOGNAME': 'cltbld', 02:09:02 INFO - 'MAIL': '/var/mail/cltbld', 02:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:02 INFO - 'MOZ_NO_REMOTE': '1', 02:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:09:02 INFO - 'NO_EM_RESTART': '1', 02:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:02 INFO - 'PWD': '/builds/slave/test', 02:09:02 INFO - 'SHELL': '/bin/bash', 02:09:02 INFO - 'SHLVL': '1', 02:09:02 INFO - 'TERM': 'linux', 02:09:02 INFO - 'TMOUT': '86400', 02:09:02 INFO - 'USER': 'cltbld', 02:09:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:02 INFO - '_': '/tools/buildbot/bin/python'} 02:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:09:03 INFO - Cleaning up... 02:09:03 INFO - Return code: 0 02:09:03 INFO - Running post-action listener: _resource_record_post_action 02:09:03 INFO - Running post-action listener: _start_resource_monitoring 02:09:03 INFO - Starting resource monitoring. 02:09:03 INFO - [mozharness: 2017-01-13 10:09:03.095399Z] Finished create-virtualenv step (success) 02:09:03 INFO - [mozharness: 2017-01-13 10:09:03.095905Z] Running install step. 02:09:03 INFO - Running pre-action listener: _resource_record_pre_action 02:09:03 INFO - Running main action method: install 02:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:03 INFO - Reading from file tmpfile_stdout 02:09:03 INFO - Detecting whether we're running mozinstall >=1.0... 02:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:09:03 INFO - Reading from file tmpfile_stdout 02:09:03 INFO - Output received: 02:09:03 INFO - Usage: mozinstall [options] installer 02:09:03 INFO - Options: 02:09:03 INFO - -h, --help show this help message and exit 02:09:03 INFO - -d DEST, --destination=DEST 02:09:03 INFO - Directory to install application into. [default: 02:09:03 INFO - "/builds/slave/test"] 02:09:03 INFO - --app=APP Application being installed. [default: firefox] 02:09:03 INFO - mkdir: /builds/slave/test/build/application 02:09:03 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'] 02:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:09:19 INFO - Reading from file tmpfile_stdout 02:09:19 INFO - Output received: 02:09:19 INFO - /builds/slave/test/build/application/firefox/firefox 02:09:19 INFO - Running post-action listener: _resource_record_post_action 02:09:19 INFO - [mozharness: 2017-01-13 10:09:19.544568Z] Finished install step (success) 02:09:19 INFO - [mozharness: 2017-01-13 10:09:19.544845Z] Running run-tests step. 02:09:19 INFO - Running pre-action listener: _resource_record_pre_action 02:09:19 INFO - Running main action method: run_tests 02:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:09:19 INFO - Python 2.7.3 02:09:19 INFO - Return code: 0 02:09:19 INFO - grabbing minidump binary from tooltool 02:09: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')]} 02:09:19 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 0x21783f0>, '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 0x2178cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21791a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:09:19 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 02:09:19 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 02:09:19 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 02:09:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:09:19 INFO - Return code: 0 02:09:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:09:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:09:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:09:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:09:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:09:19 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/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 02:09:19 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/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 02:09:19 INFO - Using env: {'DISPLAY': ':0', 02:09:19 INFO - 'HOME': '/home/cltbld', 02:09:19 INFO - 'LANG': 'en_US.UTF-8', 02:09:19 INFO - 'LANGUAGE': 'en_US:en', 02:09:19 INFO - 'LOGNAME': 'cltbld', 02:09:19 INFO - 'MAIL': '/var/mail/cltbld', 02:09:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:09:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:19 INFO - 'MOZ_NO_REMOTE': '1', 02:09:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:09:19 INFO - 'NO_EM_RESTART': '1', 02:09:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:19 INFO - 'PWD': '/builds/slave/test', 02:09:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:09:19 INFO - 'SHELL': '/bin/bash', 02:09:19 INFO - 'SHLVL': '1', 02:09:19 INFO - 'TERM': 'linux', 02:09:19 INFO - 'TMOUT': '86400', 02:09:19 INFO - 'USER': 'cltbld', 02:09:19 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319', 02:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:19 INFO - '_': '/tools/buildbot/bin/python'} 02:09:19 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/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 02:09:19 INFO - mozversion application_buildid: 20170113093039 02:09:19 INFO - mozversion application_changeset: 248399829d40ff424b3992add92e7c86486049e8 02:09:19 INFO - mozversion application_display_name: Nightly 02:09:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:09:19 INFO - mozversion application_name: Firefox 02:09:19 INFO - mozversion application_remotingname: firefox 02:09:19 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 02:09:19 INFO - mozversion application_vendor: Mozilla 02:09:19 INFO - mozversion application_version: 53.0a1 02:09:19 INFO - mozversion platform_buildid: 20170113093039 02:09:19 INFO - mozversion platform_changeset: 248399829d40ff424b3992add92e7c86486049e8 02:09:19 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 02:09:19 INFO - mozversion platform_version: 53.0a1 02:09:19 INFO - using testdate: 1484302159 02:09:19 INFO - actual date: 1484302159 02:09:19 INFO - starting webserver on 'localhost:44442' 02:09:19 INFO - SUITE-START | Running 2 tests 02:09:19 INFO - TEST-START | dromaeo_css 02:09:19 INFO - operating with platform_type : linux_ 02:09:19 INFO - Initialising browser for dromaeo_css test... 02:09:19 INFO - TEST-INFO | started process 30714 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT0FbJM/profile http://localhost:44442/getInfo.html) 02:09:23 INFO - PROCESS | 30714 | __metrics Screen width/height:1600/1200 02:09:23 INFO - PROCESS | 30714 | colorDepth:24 02:09:23 INFO - PROCESS | 30714 | Browser inner width/height: 1024/697 02:09:23 INFO - PROCESS | 30714 | __metrics 02:09:24 INFO - PROCESS | 30714 | 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" 02:09:24 INFO - PROCESS | 30714 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:09:24 INFO - PROCESS | 30714 | console.error: PushService: 02:09:24 INFO - PROCESS | 30714 | stateChangeProcessEnqueue: Error transitioning state 02:09:24 INFO - PROCESS | 30714 | UnknownError 02:09:25 INFO - TEST-INFO | 30714: exit 0 02:09:25 INFO - Browser initialized. 02:09:25 INFO - Running cycle 1/1 for dromaeo_css test... 02:09:25 INFO - TEST-INFO | started process 30794 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT0FbJM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:09:37 INFO - PROCESS | 30794 | RSS: Main: 152141824 02:09:37 INFO - PROCESS | 30794 | 02:12:42 INFO - PROCESS | 30794 | Cycle 1(1): loaded http://localhost:44442/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44442/tests/dromaeo/cssquery-ext.html) 02:12:43 INFO - PROCESS | 30794 | RSS: Main: 253452288 02:12:43 INFO - PROCESS | 30794 | 02:14:26 INFO - PROCESS | 30794 | ************************* 02:14:26 INFO - PROCESS | 30794 | A coding exception was thrown and uncaught in a Task. 02:14:26 INFO - PROCESS | 30794 | 02:14:26 INFO - PROCESS | 30794 | Full message: ReferenceError: fetch is not defined 02:14:26 INFO - PROCESS | 30794 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 02:14:26 INFO - PROCESS | 30794 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 02:14:26 INFO - PROCESS | 30794 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 02:14:26 INFO - PROCESS | 30794 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:14:26 INFO - PROCESS | 30794 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:14:26 INFO - PROCESS | 30794 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:14:26 INFO - PROCESS | 30794 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 02:14:26 INFO - PROCESS | 30794 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:14:26 INFO - PROCESS | 30794 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:14:26 INFO - PROCESS | 30794 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:14:26 INFO - PROCESS | 30794 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 02:14:26 INFO - PROCESS | 30794 | 02:14:26 INFO - PROCESS | 30794 | ************************* 02:15:46 INFO - PROCESS | 30794 | Cycle 1(1): loaded http://localhost:44442/tests/dromaeo/cssquery-ext.html (next: http://localhost:44442/tests/dromaeo/cssquery-jquery.html) 02:15:46 INFO - PROCESS | 30794 | RSS: Main: 241496064 02:15:46 INFO - PROCESS | 30794 | 02:18:48 INFO - PROCESS | 30794 | Cycle 1(1): loaded http://localhost:44442/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44442/tests/dromaeo/cssquery-mootools.html) 02:18:49 INFO - PROCESS | 30794 | RSS: Main: 218095616 02:18:49 INFO - PROCESS | 30794 | 02:21:51 INFO - PROCESS | 30794 | Cycle 1(1): loaded http://localhost:44442/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44442/tests/dromaeo/cssquery-prototype.html) 02:21:52 INFO - PROCESS | 30794 | RSS: Main: 188305408 02:21:52 INFO - PROCESS | 30794 | 02:24:55 INFO - PROCESS | 30794 | Cycle 1(1): loaded http://localhost:44442/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44442/tests/dromaeo/cssquery-yui.html) 02:24:56 INFO - PROCESS | 30794 | RSS: Main: 185528320 02:24:56 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | Cycle 1(1): loaded http://localhost:44442/tests/dromaeo/cssquery-yui.html (next: http://localhost:44442/tests/dromaeo/cssquery-dojo.html) 02:27:59 INFO - PROCESS | 30794 | RSS: Main: 271507456 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | __start_tp_report 02:27:59 INFO - PROCESS | 30794 | _x_x_mozilla_page_load 02:27:59 INFO - PROCESS | 30794 | _x_x_mozilla_page_load_details 02:27:59 INFO - PROCESS | 30794 | |i|pagename|runs| 02:27:59 INFO - PROCESS | 30794 | |0;dojo.html;2562.3081767922663;2589.805764567658;2603.6485074514935;2616.5421051316025;2618.083670715249;4656.580907718305;4722.598579120779;4772.546608072232;4787.186178349681;4796.928046079312;4715.221988371914;4751.073540659573;4770.689905156171;4776.47458779534;4811.879703007417;4403.273459879116;4430.844920427786;4438;4462.955370446286;4507.0760494098795;4523.932141017871;4533.410656614636;4533.818647254106;4533.886652833673;4555.863324100265;4337;4378.584034516716;4491.775411229425;4506.346579745951;4532.841350552748;4306.784660766949;4344.89137771555;4355.651547876162;4362.454693163355;4407.537208593084;4316.82732690692;4317.913641727179;4326.134773045404;4366.519682834885;4378.868633940987;4620.006698559792;4726.054789042205;4752.144613969484;4757.952420475785;4761.928571071437;4966.900661986776;5001.299818025479;5026.346574945233;5038.2190760432195;5048.974755126237;5106.055379754757;5121.052605268037;5122.078025955327;5124.92312615311;5177.637565370425;4739.478657347689;4742.786574604151;4747.596454301354;4750.453697824794;4827.324174615556;5068.290439338495;5084.923726144111;5126.769295381642;5130.512601302945;5181.222816577543;4989.725565093882;4990.201567749143;4995.450500444948;4997.425296090996;5002.649814512949;5006.524380183877;5025;5037.345145131183;5049.747512624427;5067.037262920034;4989.725565093955;5073.923891141636;5079.111155547765;5081;5085.923711144335;5035.647504674711;5072.340595722532;5079.415867175322;5089.592832573385;5140.408852981955;4259.105587826529;4265.893352666146;4296.828126874952;4351.26028575143;4357.041450880801;3694.5012423323046;3722.8510859565854;3753.530808648919;3764.322421964073;3770.321342158383;4373.016071383977;4376.759278239664;4377.124575084996;4383.912321753547;4422.358757980075;5008.574271186917;5043.0922433961505;5081.212412076134;5082.796688132507;5122.103631864409;4354.107407981356;4393.626541743924;4394.648428125743;4395.780210989502;4454.866354009385;4610.631149508032;4612.907741845144;4615.330777037311;4619.46876109245;4657.278121891112;4719.811207551659;4818.494058123846;4822.397200349957;4822.445418776815;4860.902781944412;5047.066292735855;5053.722045287397;5085.059264036017;5090.211017292326;5190.6107041971245;4805.9038819224115;4812.927806082842;4814.229723244264;4814.350062741485;4849.345338379274;5035.773390197523;5036.697798132123;5038.84883453497;5040.42035165946;5095.719735414439;5005.724685142207;5050.04049230645;5058.924116138334;5092.949070509248;5098.2352647103235;4988.950110498994;4989.451160372429;4989.950100499093;4998.525140111684;5031.597472202142;4932.8766780830765;4937.308776771327;4978.352814134139;4986.900261994812;5015.749212539431;4850.5149485052625;4882.194437917846;4896.706197628154;4921.876953076202;4976.278439626161;5013.298138260573;5018.297438358706;5020.748962551785;5022.67352622093;5069.214271787879;4866.489018653132;4912.312276281395;4914.483979182278;4916.582090522345;4946.975265123651 02:27:59 INFO - PROCESS | 30794 | |1;ext.html;539.7274376439899;566.4929887750462;568.1335962656948;583.224311665485;608.6591508755095;30135;39602.405963910525;41088.38367424491;41168.58831411685;44852.3272150918;40833.8749187622;41294.58705412945;41305.58694413055;41387.79306103465;41442.7927860361;7399.6670149843385;7615.695372185106;7770.33952114071;7774.650140743652;7783.571903545303;7340.816479587999;10061;10361.171106311476;10362.585496580128;10693.83959240612;10041;10118.949405252963;10124.645637402693;10126.848097278547;10135.037171468699;10227.284090113695;10269.948650256702;10279.17766578672;10287.691369258935;10314.587416503331;10068.345557538736;10127.392356458637;10146.898531014669;10149.188064954786;10161.288709790319;38719.612803872165;39070;39109.41335879964;39111.413328799805;39128;3585.354636165489;3697.759645623026;3707.1844194277214;3733.6453036961443;3748.775073495599;29660.555091673643;29802.254943626365;29954.55068173979;29971.850140749375;31024;3477.2523907360037;3526.5415495985612;3529;3537.7877327360443;3562.536870206882;237060;237963;238337.99999999825;238388;240341;54774.72612636911;55137.72431137819;55196.72401637966;55250.44749552533;55264.17103743447;57616;57803;57811;57868;57878.42121578815;12545.184563003375;12919.806202906964;12939.223646581235;12941.741165176643;12953.251311072885;8564.74305770828;8797.692080777142;9183.081691830897;9188.816223675489;9395.34232603725;16056.75914861278;16537;16550;16576.254068566945;16576.6684666306;5800.158976948319;5852.326982396994;5888.941110588925;5973.820785376446;6046.909296360558;831.7795784117213;840.7561807076002;847.3221422861686;853.5604163855619;873.401719821924;27620.861895690392;28159.43681126366;28488.715112848608;28865.278368040967;28961.855190724335;967.2890425537224;972.9659461918799;974.2692980264801;975.6341371985505;984.1416290917022;14997.625059373602;15100.773488397683;15170.393184272727;15196.544103676908;15440.757440937528;322398;322827;324164;324231;324897;41673.37489937653;41693.37459938104;41803.372949405784;41803.7909810449;41809;1207.5471698113208;1241.9813702794465;1249.4814651919532;1251.474380760083;1257.3713143428286;25571.74428255768;26358;27404.177874663794;27470.45059098847;27806.44387112287;42081.36877946895;42681.1463770729;42707;42735.57264427316;42754;212924;263754;264040;264096;264405;32109.678903210668;33145.17137071496;35815.28369432649;36762;37091.25817483689;41851.350067740466;41894.58105418907;42101.57898421099;42407.575924240366;42453.57546424496;34434.655653443144;34644.65355346515;34841;35008;35483.64516354803;33796;34645.48031779475;34957.82521087378;35293.117672058404;35890.4616430759;34334;34951.65048349484;35002;35020.64979350174;35060.649393506756;30214;31393;31580;31805.681943180272;32038.19904502406;24292.878535607208;24519.877400613597;24693.87653061723;25037;25070.37324066913 02:27:59 INFO - PROCESS | 30794 | |2;jquery.html;378.7708436395982;391.25856501928826;399.098038433141;406.0923835228265;419.4819398043416;13626.727465450685;14863.92568037161;17003.14984250787;18576.535586610324;18589.070546472674;12518.311492867888;12942.93528532356;14940;15258.389664413438;15343.923280383611;4995.150824359858;5255.816046438375;5333.573314134879;5538.47384498472;5573.303337082864;5558.805441809517;5591.1054231323;5591.944080559203;5597.972010139943;5599.048161812481;5450.155225939986;5551.9722401387935;5574.10814269717;5587.078132108198;5587.944120558783;5590.273264475612;5607.747651355658;5612.971935140298;5623.297087863996;5625.268715067056;4864.2217245240945;5086.567641750456;5119.718415487147;5192.7403629818355;5195.350581177353;11199.944000280027;11388.430578471127;11392.658220253406;18511;20989.47526311839;2091.633964056284;2135.5975813714026;2240.092762431218;2432.4877352511016;2433.58629033063;8891.199792018684;8921.286297096281;8940.061293564147;9347.672831450867;9441.19749821266;1738.9217485213069;1753.7983131939864;1755.280335062631;1764.382466136842;1785.4643606918003;70573.64713176401;76634.61682691607;77182.45635087267;77220.22779772186;79153.20846791574;10407.375557466576;10568.313059651098;10568.52441640128;10634.255602107933;10711.357318560755;15057;15084;15092.924535377253;15108.471203508045;15115.31981060833;5863.472287494061;6703.296153903867;6921.48088893335;7016.298370162943;7031.683574239171;5428.83713488596;5711.543076553866;5799.5650326225705;5995.46040856325;5995.700214989321;4453.3320001999955;4460.130274596488;4656.068786242765;4700.764961751899;4752.976235118871;1845.2342277954767;1855.3691744806831;1890.4990177602947;1892.6782446984046;1968.1143485431612;848.2874385516196;864.8140649760331;871.3551971541141;883.1013321359129;898.955052247385;16834.915825421038;16930.492085237463;17378.217980190922;17644.382446614563;17923;866.276658989738;867.2584939876441;869.1959937058195;885.6767279942775;898.5866501409295;9188.540572971324;9802.75493112678;9803.950980245196;9814.705558833246;9980.650677226407;496275;502397.1284450916;502860;503669;511046;72627.2737272631;94445.5277723607;99748.50125749325;99821;101293;927.536231884058;931.725141083382;965.6909788867495;967.7580604848788;974.4835237324229;15858;16346;16831.326746929986;16869.831301687318;17026.659466810346;40610.593894061865;40680.79659601683;40960;41208;41443.27010569063;42813;59664.70167649134;61138.69430652818;61315.69342153439;62145;7142.785716428515;7381.188069312264;7950.7217247396065;7957.363410927228;8080.111187769223;10740.409277489813;11785.292882427082;12661.74676506483;12720.427580759073;13028.348582570645;10194.694159175237;10426.791464170523;10763.892361076601;11025.283356581796;11306.773864522827;7424.294692004251;10426.947865260625;10470.476476176313;10473.947630262104;10601.363918164934;9982.900170998197;10305.845412318971;10312.869626842099;10676.145908327471;10914.617988370686;7837.804054898673;7906.881396779168;7930.762077137695;8015.238552337519;8298.336133109458;7713.691452342068;9654.855177172207;9963.551640176354;10190.1338386238;10466.16270698327 02:27:59 INFO - PROCESS | 30794 | |3;mootools.html;1322.4710115953617;1339.9330033498322;1340.4638144742103;1344.1061693973509;1357.9253141077236;7012.719491220351;7376.520526165796;7380.630968451583;7381.409487241021;7401.666924988375;6374.872502549946;6558.081868538408;6973.9302606973915;7120;7152.463565232602;2167.707359506466;2188.113813905371;2189.8029177374033;2190.244365693833;2204.7795220477983;2065.9070341834577;2160.9891950540227;2181.5309708412688;2182.8690278583335;2184.9344519664437;1857.3313607101434;1935.5548223908438;2109.725735654363;2173.445771328309;2178.4989452425953;2182.2471247419617;2186.6719992001167;2204.889755512234;2206.5145667953107;2214.413180507167;2173.054721196285;2198.967015494753;2200.504886400563;2202.394341556069;2202.9118835246572;7358.484906056578;7406.111266647983;7412.666430010609;7412.999245101896;7416.184219735825;4223.535411104776;4372.978135109304;4427.048184640318;4431.047324825146;4434.955650443487;2733.1117386849155;2983.4331477019296;2984.0451055662197;2995.1613548206537;2999.2501874531367;1048.4338457233084;1068.3216157739814;1079.838024296354;1081.7674200047027;1127.520803658442;9694.17599504036;9711.465869377182;9728.708138755848;9729.075737804897;9771.853422198672;4093.733907296076;4280.850170244026;4332.8768903237815;4335.2196604610845;4351.564843515623;7785.844283114306;7844.37245020397;7850.371970242367;7851.489653172525;7858.921410785933;4441.311596702516;4442.644588432982;4443.2224360736745;4444.644428445717;4448.888777780581;3922.254771593445;4014.518257809023;4048.5344185419053;4112;4113.362428823537;3186.1078897908624;3296.983515082409;3308;3313.2379552703014;3314.502824576333;1022.7238645565805;1028.4292217819182;1033.4316126130748;1036.9688909332733;1041.4740556019162;675.9695813688396;705.6119134476018;719.3669570777682;734.3831181807308;735.334522257358;4834.299026641117;4947.505249475023;5027.296178535008;5096.643234973516;5257.973710131425;847.9109693482095;918.237862574062;927.5686805635412;933.3419938943028;967.327707243459;3132.042358853677;3413.7951722896705;3495.807730574843;3505.6844883960566;3612.5664920209742;8230.876536851953;8574.099719529484;8578.4424012439;8579.957100214458;8590.742277731679;8519.446235994641;8530.232279094911;8543.401961862734;8549.615267312985;8557.315414766803;6756.189257289157;6778.084958530634;6789.592624442549;6791.762288319885;6827.795166145023;4726.1492931272705;4802.471728109836;4916.7541622918;4941.456439791692;4976.253561965734;7757.26306000929;7906.051273847175;8022.598870056357;8109.878351824609;8146.75559733209;8048.597570121354;8316.792080198044;8387.454815436977;8428.704995325133;8447.915520844714;5863.824085277449;5965.791197308074;5969.611975221596;5974.1934838796205;5980.611260268069;6517.511186660925;6902.102726645504;6967.059446974594;6971.825704357431;6976.372126508639;6156.78451254204;6191.349908259748;6224.253089629274;6276.089966954857;6462.192225971754;6066.332703402534;6079.240094988127;6114.938850611615;6136.907946380718;6143.324234334309;5372.973135134299;5660.830175094754;5717.7712891485535;5718.228039214653;5720.3421606515785;4529.550543825944;4562.133194692998;4617.515160908057;4646.070785842778;4694.460137084279;4287.7641729705165;4367.956320436882;4476.940607320669;4545.227311357011;4687.835925742583 02:27:59 INFO - PROCESS | 30794 | |4;prototype.html;337.9543761592186;349.3990336621012;351.74674234551117;355.7847502261134;359.3746880428056;4750.239961606144;4886.462489126193;4954.207326827708;4987.675801072933;5014.997000599881;4760.428748550179;4844.1764899967;4864.246041863508;4878.121938051158;4924.507549245073;3947.407888816672;3953.9209215815667;3961.2869683456966;3968.2658708138943;3971.1462035662325;3758.9060273493246;3787.5039359453;3970.841166353357;3975.86084487043;3985.163115745697;3865.690744740414;3885.7759805661267;3891.7470364426363;3900.219956008795;3923.15652134791;3892.5134358205223;3902;3902.239063382656;3914.297280682898;3919.7844118573466;3543.4861945018088;3545.6986156176754;3545.716342692604;3548.3967725486727;3567.8929632111076;4690.771883355413;4797.280407938802;4813.724363043796;4820.156472617278;4902.475225258994;4496.2581174106235;4497.797599108048;4510.143072816188;4524.68327217095;4527.7283362998;5146.150885103953;5158.3552055993005;5162.767675454614;5162.948370516285;5174.456682048406;3458.4985177149424;3618.095476130967;3658.615845336228;3894.3768996960634;3894.493715816954;5298.655587386807;5305.31030965972;5363.651362661414;5377.650452720561;5383.573353061442;3623.7463377563317;3628.74598778083;3648.4892115103908;3651.2332410193626;3696.464012718141;3630.4917311575873;3752.2120354725266;3753.3619284721663;3753.60587138352;3757.4363845423404;4228.498882896589;4233.386159006926;4271.743695378287;4271.864067966017;4294.871153865389;5064.620153488429;5089.3638295213095;5090.949090509048;5112.335396398519;5115.2071428928575;1194.8230992472409;1216.4404373987888;1222.45600707686;1245.701031752373;1263.3935710858655;820.9096999330069;825.628467189767;840.8024114333127;846.7248144352986;866.0776273268948;4308.310670292738;4339.4792624884585;4350.2387082260475;4363.629091527256;4393.362962370438;5120.718360490208;5137.486251374908;5159.535641792257;5161.509656582693;5163.870903227375;4185.958140418617;4372.562743725602;4401.977990110093;4402.251617225015;4417;2700.6226824319347;2706.9729302707115;2708.6885008223912;2718.4834881372476;2722.7821774258014;4787.234042553175;4805.567498925114;4823.5658790709;4840.661153719277;4848.636352273594;4917.729524876095;5121.308623335876;5135.3324067870935;5154.664946778447;5162.819301324435;4759.547842954914;4765.663246445718;4773;4775.638942901256;4780.856574302777;5060.1903695408555;5060.595152387727;5089.618278629192;5095.566876815364;5129.487051294841;4955.886614110352;5170.353705786777;5216;5218.765155568084;5236.371635403776;4946.1591529439365;4963.106640804545;4968;4969.776360063734;4975.353204083446;3968.226195891889;4014.8594799181888;4016.7389119707127;4023.7183397161916;4034.8184331704556;5155.381354237516;5216.40011398694;5295.576353891603;5318.521333080042;5346.8395948121615;5124.231365295235;5124.333836601218;5128.820491282787;5147.768350424165;5150.40770311419;4911.68074075184;5114.670185751806;5127.46161653036;5132.409772876167;5141.691498510101;4840.201366774443;4973.52751488608;5039.974800125976;5061.645684802176;5103.948960510347;5062.82280120194;5067.290579318972;5098.668586541863;5117.309163262912;5120.8222108914415;4969.279454479152;5016.448190699093;5024.371953505812;5036;5049.57078648319 02:27:59 INFO - PROCESS | 30794 | |5;yui.html;668.7726173101145;679.9354061364169;724.5978481942519;727.5598263050855;751.5866273549547;13320.333983300832;13956.651083722913;13957.441702331895;14011.789823152649;14051.718965620681;13232.338383080856;13355.265460399674;13392.732145357111;13394.933025334883;13400.262985535792;640.7308930249283;658.3021996683507;778.4784194589638;796.7888509544975;808.789714674186;816.6529225079347;819.0703551469096;819.9754007379759;820.3888103363003;820.5076953827715;854.444611002847;855.6235256487125;856.6359297298654;856.9271611912994;857.0657982798748;679.7892653277476;696.9930300696979;699.2692636195188;699.3461113858558;857.2627540315324;616.9105479705418;635.8378613453586;638.3425072175667;640.0974625777632;640.1549954060641;13495.730085398336;13572.45710171592;13593.59219223415;13608.455661773518;13611.183329000292;11756.05951523876;11928.701782455508;11996.460159292852;12213;12224.816627750592;10084.848727269096;11016.724581885437;11106.111511079094;11127.944360278228;11224.943875280653;4116.75299482032;4142.937855932163;4159.563245859172;4194.517630472505;4247.086876321606;60460.39539604592;61836;63517;63797;64269.357306426806;10056.547455364525;10112.74718132038;10219.693409197736;10268.794624107475;10449.425281609585;18011.909940450212;18149.183286752126;18176.45470635883;18177.182026808827;18182.63634727298;6186.792396762936;6348.8412789679705;6461.547691661633;6488.545801793924;6489.740410383626;3371.76397652162;3374.088995971074;3391.881284155092;3485.616582176009;3492.6681965213265;2645.4444566641223;2677.7322267773066;2704.2698471413;2712.294803351143;2719.048333083405;487.18396685552267;493.8074151081081;498.837877689745;499.97750101244804;500.30457663847284;543.3805461773582;549.3380476525776;558.3774091887516;569.64966545575;571.4371344225934;7863.803404914808;8129.79675508117;8354.665813367397;8517.403781735218;8605;536.6779932040822;556.093567485005;556.7049463784201;565.6803905793214;574.5059249045818;3737.5514938207043;3745.8127093645207;3765.755225910362;3770.3967365221433;3867.9226415471535;46668.299975499714;50647.24029139566;52567.474325257026;64688.353116469174;65959.34040659532;52093.739531302104;52227.21659175115;52879.20681189785;53348;53667.559892813115;47382.76308618504;47837.52162478331;49106;49870.75064624654;50017.249741254636;7943.165967573553;8673.869891951499;8699.260562852229;8810.647574096964;8941.239994600532;33543;33753.32493350168;34244;34614.82692586521;35517.64482355143;40150.397744034446;40165.59834401619;40893.79553102215;41174.1765164701;41989.58010419979;10626.52180651857;10714.196435267542;10769.892301076889;11044.282121662067;11265.324080555192;12115.878841211475;12251;12517.436715348013;12717.55488557896;13462.663433414244;11206.943965280121;11256.493457794582;11512.94243528777;11571.479283432434;11843.763124737628;11245.325280483197;11440.485178166837;11479.311241325548;11975.940120299343;12187.268763874195;11321.150913681671;11529.135314851252;11552.191346605658;12119.81820272679;12182.6954326139;9156.267498599962;9280.350375473652;9461.574229159567;9512.1439070484;9701.175400091071;9469.763255918657;9630.32587718853;9695.806083878424;9708.417494950325;9713.320067595201 02:27:59 INFO - PROCESS | 30794 | __end_tp_report 02:27:59 INFO - PROCESS | 30794 | __start_cc_report 02:27:59 INFO - PROCESS | 30794 | _x_x_mozilla_cycle_collect,1091 02:27:59 INFO - PROCESS | 30794 | __end_cc_report 02:27:59 INFO - PROCESS | 30794 | __startTimestamp1484303279707__endTimestamp 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | ------- Summary: start ------- 02:27:59 INFO - PROCESS | 30794 | Number of tests: 6 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | [#0] dojo.html Cycles:165 Average:4707.26 Median:4827.32 stddev:493.93 (10.2%) stddev-sans-first:465.91 02:27:59 INFO - PROCESS | 30794 | Values: 2562.3 2589.8 2603.6 2616.5 2618.1 4656.6 4722.6 4772.5 4787.2 4796.9 4715.2 4751.1 4770.7 4776.5 4811.9 4403.3 4430.8 4438.0 4463.0 4507.1 4523.9 4533.4 4533.8 4533.9 4555.9 4337.0 4378.6 4491.8 4506.3 4532.8 4306.8 4344.9 4355.7 4362.5 4407.5 4316.8 4317.9 4326.1 4366.5 4378.9 4620.0 4726.1 4752.1 4758.0 4761.9 4966.9 5001.3 5026.3 5038.2 5049.0 5106.1 5121.1 5122.1 5124.9 5177.6 4739.5 4742.8 4747.6 4750.5 4827.3 5068.3 5084.9 5126.8 5130.5 5181.2 4989.7 4990.2 4995.5 4997.4 5002.6 5006.5 5025.0 5037.3 5049.7 5067.0 4989.7 5073.9 5079.1 5081.0 5085.9 5035.6 5072.3 5079.4 5089.6 5140.4 4259.1 4265.9 4296.8 4351.3 4357.0 3694.5 3722.9 3753.5 3764.3 3770.3 4373.0 4376.8 4377.1 4383.9 4422.4 5008.6 5043.1 5081.2 5082.8 5122.1 4354.1 4393.6 4394.6 4395.8 4454.9 4610.6 4612.9 4615.3 4619.5 4657.3 4719.8 4818.5 4822.4 4822.4 4860.9 5047.1 5053.7 5085.1 5090.2 5190.6 4805.9 4812.9 4814.2 4814.4 4849.3 5035.8 5036.7 5038.8 5040.4 5095.7 5005.7 5050.0 5058.9 5092.9 5098.2 4989.0 4989.5 4990.0 4998.5 5031.6 4932.9 4937.3 4978.4 4986.9 5015.7 4850.5 4882.2 4896.7 4921.9 4976.3 5013.3 5018.3 5020.7 5022.7 5069.2 4866.5 4912.3 4914.5 4916.6 4947.0 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | [#1] ext.html Cycles:180 Average:43735.64 Median:34979.91 stddev:71756.09 (205.1%) stddev-sans-first:71884.09 02:27:59 INFO - PROCESS | 30794 | Values: 539.7 566.5 568.1 583.2 608.7 30135.0 39602.4 41088.4 41168.6 44852.3 40833.9 41294.6 41305.6 41387.8 41442.8 7399.7 7615.7 7770.3 7774.7 7783.6 7340.8 10061.0 10361.2 10362.6 10693.8 10041.0 10118.9 10124.6 10126.8 10135.0 10227.3 10269.9 10279.2 10287.7 10314.6 10068.3 10127.4 10146.9 10149.2 10161.3 38719.6 39070.0 39109.4 39111.4 39128.0 3585.4 3697.8 3707.2 3733.6 3748.8 29660.6 29802.3 29954.6 29971.9 31024.0 3477.3 3526.5 3529.0 3537.8 3562.5 237060.0 237963.0 238338.0 238388.0 240341.0 54774.7 55137.7 55196.7 55250.4 55264.2 57616.0 57803.0 57811.0 57868.0 57878.4 12545.2 12919.8 12939.2 12941.7 12953.3 8564.7 8797.7 9183.1 9188.8 9395.3 16056.8 16537.0 16550.0 16576.3 16576.7 5800.2 5852.3 5888.9 5973.8 6046.9 831.8 840.8 847.3 853.6 873.4 27620.9 28159.4 28488.7 28865.3 28961.9 967.3 973.0 974.3 975.6 984.1 14997.6 15100.8 15170.4 15196.5 15440.8 322398.0 322827.0 324164.0 324231.0 324897.0 41673.4 41693.4 41803.4 41803.8 41809.0 1207.5 1242.0 1249.5 1251.5 1257.4 25571.7 26358.0 27404.2 27470.5 27806.4 42081.4 42681.1 42707.0 42735.6 42754.0 212924.0 263754.0 264040.0 264096.0 264405.0 32109.7 33145.2 35815.3 36762.0 37091.3 41851.4 41894.6 42101.6 42407.6 42453.6 34434.7 34644.7 34841.0 35008.0 35483.6 33796.0 34645.5 34957.8 35293.1 35890.5 34334.0 34951.7 35002.0 35020.6 35060.6 30214.0 31393.0 31580.0 31805.7 32038.2 24292.9 24519.9 24693.9 25037.0 25070.4 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | [#2] jquery.html Cycles:180 Average:28252.33 Median:502628.56 stddev:83065.89 (16.5%) stddev-sans-first:83272.55 02:27:59 INFO - PROCESS | 30794 | Values: 378.8 391.3 399.1 406.1 419.5 13626.7 14863.9 17003.1 18576.5 18589.1 12518.3 12942.9 14940.0 15258.4 15343.9 4995.2 5255.8 5333.6 5538.5 5573.3 5558.8 5591.1 5591.9 5598.0 5599.0 5450.2 5552.0 5574.1 5587.1 5587.9 5590.3 5607.7 5613.0 5623.3 5625.3 4864.2 5086.6 5119.7 5192.7 5195.4 11199.9 11388.4 11392.7 18511.0 20989.5 2091.6 2135.6 2240.1 2432.5 2433.6 8891.2 8921.3 8940.1 9347.7 9441.2 1738.9 1753.8 1755.3 1764.4 1785.5 70573.6 76634.6 77182.5 77220.2 79153.2 10407.4 10568.3 10568.5 10634.3 10711.4 15057.0 15084.0 15092.9 15108.5 15115.3 5863.5 6703.3 6921.5 7016.3 7031.7 5428.8 5711.5 5799.6 5995.5 5995.7 4453.3 4460.1 4656.1 4700.8 4753.0 1845.2 1855.4 1890.5 1892.7 1968.1 848.3 864.8 871.4 883.1 899.0 16834.9 16930.5 17378.2 17644.4 17923.0 866.3 867.3 869.2 885.7 898.6 9188.5 9802.8 9804.0 9814.7 9980.7 496275.0 502397.1 502860.0 503669.0 511046.0 72627.3 94445.5 99748.5 99821.0 101293.0 927.5 931.7 965.7 967.8 974.5 15858.0 16346.0 16831.3 16869.8 17026.7 40610.6 40680.8 40960.0 41208.0 41443.3 42813.0 59664.7 61138.7 61315.7 62145.0 7142.8 7381.2 7950.7 7957.4 8080.1 10740.4 11785.3 12661.7 12720.4 13028.3 10194.7 10426.8 10763.9 11025.3 11306.8 7424.3 10426.9 10470.5 10473.9 10601.4 9982.9 10305.8 10312.9 10676.1 10914.6 7837.8 7906.9 7930.8 8015.2 8298.3 7713.7 9654.9 9963.6 10190.1 10466.2 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | [#3] mootools.html Cycles:180 Average:4770.99 Median:4961.88 stddev:2541.21 (51.2%) stddev-sans-first:2535.12 02:27:59 INFO - PROCESS | 30794 | Values: 1322.5 1339.9 1340.5 1344.1 1357.9 7012.7 7376.5 7380.6 7381.4 7401.7 6374.9 6558.1 6973.9 7120.0 7152.5 2167.7 2188.1 2189.8 2190.2 2204.8 2065.9 2161.0 2181.5 2182.9 2184.9 1857.3 1935.6 2109.7 2173.4 2178.5 2182.2 2186.7 2204.9 2206.5 2214.4 2173.1 2199.0 2200.5 2202.4 2202.9 7358.5 7406.1 7412.7 7413.0 7416.2 4223.5 4373.0 4427.0 4431.0 4435.0 2733.1 2983.4 2984.0 2995.2 2999.3 1048.4 1068.3 1079.8 1081.8 1127.5 9694.2 9711.5 9728.7 9729.1 9771.9 4093.7 4280.9 4332.9 4335.2 4351.6 7785.8 7844.4 7850.4 7851.5 7858.9 4441.3 4442.6 4443.2 4444.6 4448.9 3922.3 4014.5 4048.5 4112.0 4113.4 3186.1 3297.0 3308.0 3313.2 3314.5 1022.7 1028.4 1033.4 1037.0 1041.5 676.0 705.6 719.4 734.4 735.3 4834.3 4947.5 5027.3 5096.6 5258.0 847.9 918.2 927.6 933.3 967.3 3132.0 3413.8 3495.8 3505.7 3612.6 8230.9 8574.1 8578.4 8580.0 8590.7 8519.4 8530.2 8543.4 8549.6 8557.3 6756.2 6778.1 6789.6 6791.8 6827.8 4726.1 4802.5 4916.8 4941.5 4976.3 7757.3 7906.1 8022.6 8109.9 8146.8 8048.6 8316.8 8387.5 8428.7 8447.9 5863.8 5965.8 5969.6 5974.2 5980.6 6517.5 6902.1 6967.1 6971.8 6976.4 6156.8 6191.3 6224.3 6276.1 6462.2 6066.3 6079.2 6114.9 6136.9 6143.3 5373.0 5660.8 5717.8 5718.2 5720.3 4529.6 4562.1 4617.5 4646.1 4694.5 4287.8 4368.0 4476.9 4545.2 4687.8 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | [#4] prototype.html Cycles:180 Average:4242.88 Median:4809.65 stddev:1215.27 (25.3%) stddev-sans-first:1182.81 02:27:59 INFO - PROCESS | 30794 | Values: 338.0 349.4 351.7 355.8 359.4 4750.2 4886.5 4954.2 4987.7 5015.0 4760.4 4844.2 4864.2 4878.1 4924.5 3947.4 3953.9 3961.3 3968.3 3971.1 3758.9 3787.5 3970.8 3975.9 3985.2 3865.7 3885.8 3891.7 3900.2 3923.2 3892.5 3902.0 3902.2 3914.3 3919.8 3543.5 3545.7 3545.7 3548.4 3567.9 4690.8 4797.3 4813.7 4820.2 4902.5 4496.3 4497.8 4510.1 4524.7 4527.7 5146.2 5158.4 5162.8 5162.9 5174.5 3458.5 3618.1 3658.6 3894.4 3894.5 5298.7 5305.3 5363.7 5377.7 5383.6 3623.7 3628.7 3648.5 3651.2 3696.5 3630.5 3752.2 3753.4 3753.6 3757.4 4228.5 4233.4 4271.7 4271.9 4294.9 5064.6 5089.4 5090.9 5112.3 5115.2 1194.8 1216.4 1222.5 1245.7 1263.4 820.9 825.6 840.8 846.7 866.1 4308.3 4339.5 4350.2 4363.6 4393.4 5120.7 5137.5 5159.5 5161.5 5163.9 4186.0 4372.6 4402.0 4402.3 4417.0 2700.6 2707.0 2708.7 2718.5 2722.8 4787.2 4805.6 4823.6 4840.7 4848.6 4917.7 5121.3 5135.3 5154.7 5162.8 4759.5 4765.7 4773.0 4775.6 4780.9 5060.2 5060.6 5089.6 5095.6 5129.5 4955.9 5170.4 5216.0 5218.8 5236.4 4946.2 4963.1 4968.0 4969.8 4975.4 3968.2 4014.9 4016.7 4023.7 4034.8 5155.4 5216.4 5295.6 5318.5 5346.8 5124.2 5124.3 5128.8 5147.8 5150.4 4911.7 5114.7 5127.5 5132.4 5141.7 4840.2 4973.5 5040.0 5061.6 5103.9 5062.8 5067.3 5098.7 5117.3 5120.8 4969.3 5016.4 5024.4 5036.0 5049.6 02:27:59 INFO - PROCESS | 30794 | 02:27:59 INFO - PROCESS | 30794 | [#5] yui.html Cycles:180 Average:14132.67 Median:47025.53 stddev:17001.28 (36.2%) stddev-sans-first:17018.91 02:27:59 INFO - PROCESS | 30794 | Values: 668.8 679.9 724.6 727.6 751.6 13320.3 13956.7 13957.4 14011.8 14051.7 13232.3 13355.3 13392.7 13394.9 13400.3 640.7 658.3 778.5 796.8 808.8 816.7 819.1 820.0 820.4 820.5 854.4 855.6 856.6 856.9 857.1 679.8 697.0 699.3 699.3 857.3 616.9 635.8 638.3 640.1 640.2 13495.7 13572.5 13593.6 13608.5 13611.2 11756.1 11928.7 11996.5 12213.0 12224.8 10084.8 11016.7 11106.1 11127.9 11224.9 4116.8 4142.9 4159.6 4194.5 4247.1 60460.4 61836.0 63517.0 63797.0 64269.4 10056.5 10112.7 10219.7 10268.8 10449.4 18011.9 18149.2 18176.5 18177.2 18182.6 6186.8 6348.8 6461.5 6488.5 6489.7 3371.8 3374.1 3391.9 3485.6 3492.7 2645.4 2677.7 2704.3 2712.3 2719.0 487.2 493.8 498.8 500.0 500.3 543.4 549.3 558.4 569.6 571.4 7863.8 8129.8 8354.7 8517.4 8605.0 536.7 556.1 556.7 565.7 574.5 3737.6 3745.8 3765.8 3770.4 3867.9 46668.3 50647.2 52567.5 64688.4 65959.3 52093.7 52227.2 52879.2 53348.0 53667.6 47382.8 47837.5 49106.0 49870.8 50017.2 7943.2 8673.9 8699.3 8810.6 8941.2 33543.0 33753.3 34244.0 34614.8 35517.6 40150.4 40165.6 40893.8 41174.2 41989.6 10626.5 10714.2 10769.9 11044.3 11265.3 12115.9 12251.0 12517.4 12717.6 13462.7 11206.9 11256.5 11512.9 11571.5 11843.8 11245.3 11440.5 11479.3 11975.9 12187.3 11321.2 11529.1 11552.2 12119.8 12182.7 9156.3 9280.4 9461.6 9512.1 9701.2 9469.8 9630.3 9695.8 9708.4 9713.3 02:27:59 INFO - PROCESS | 30794 | -------- Summary: end -------- 02:27:59 INFO - PROCESS | 30794 | 02:28:00 INFO - TEST-INFO | 30794: exit 0 02:28:00 INFO - TEST-OK | dromaeo_css | took 1120674ms 02:28:00 INFO - TEST-START | kraken 02:28:00 INFO - operating with platform_type : linux_ 02:28:00 INFO - Initialising browser for kraken test... 02:28:00 INFO - TEST-INFO | started process 31093 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjMWvSG/profile http://localhost:44442/getInfo.html) 02:28:04 INFO - PROCESS | 31093 | __metrics Screen width/height:1600/1200 02:28:04 INFO - PROCESS | 31093 | colorDepth:24 02:28:04 INFO - PROCESS | 31093 | Browser inner width/height: 1024/697 02:28:04 INFO - PROCESS | 31093 | __metrics 02:28:05 INFO - PROCESS | 31093 | 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" 02:28:05 INFO - PROCESS | 31093 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:28:05 INFO - PROCESS | 31093 | console.error: PushService: 02:28:05 INFO - PROCESS | 31093 | stateChangeProcessEnqueue: Error transitioning state 02:28:05 INFO - PROCESS | 31093 | UnknownError 02:28:06 INFO - TEST-INFO | 31093: exit 0 02:28:06 INFO - Browser initialized. 02:28:06 INFO - Running cycle 1/1 for kraken test... 02:28:06 INFO - TEST-INFO | started process 31176 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjMWvSG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:18 INFO - PROCESS | 31176 | RSS: Main: 152834048 02:28:18 INFO - PROCESS | 31176 | 02:28:20 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44442/tests/kraken/driver.html?testName=audio-beat-detection) 02:28:21 INFO - PROCESS | 31176 | RSS: Main: 206442496 02:28:21 INFO - PROCESS | 31176 | 02:28:24 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44442/tests/kraken/driver.html?testName=audio-dft) 02:28:25 INFO - PROCESS | 31176 | RSS: Main: 185982976 02:28:25 INFO - PROCESS | 31176 | 02:28:28 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44442/tests/kraken/driver.html?testName=audio-fft) 02:28:28 INFO - PROCESS | 31176 | RSS: Main: 268722176 02:28:28 INFO - PROCESS | 31176 | 02:28:31 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44442/tests/kraken/driver.html?testName=audio-oscillator) 02:28:31 INFO - PROCESS | 31176 | RSS: Main: 179748864 02:28:31 INFO - PROCESS | 31176 | 02:28:33 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44442/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:28:34 INFO - PROCESS | 31176 | RSS: Main: 183029760 02:28:34 INFO - PROCESS | 31176 | 02:28:37 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44442/tests/kraken/driver.html?testName=imaging-darkroom) 02:28:38 INFO - PROCESS | 31176 | RSS: Main: 222334976 02:28:38 INFO - PROCESS | 31176 | 02:28:41 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44442/tests/kraken/driver.html?testName=imaging-desaturate) 02:28:42 INFO - PROCESS | 31176 | RSS: Main: 221831168 02:28:42 INFO - PROCESS | 31176 | 02:28:45 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44442/tests/kraken/driver.html?testName=json-parse-financial) 02:28:45 INFO - PROCESS | 31176 | RSS: Main: 220745728 02:28:45 INFO - PROCESS | 31176 | 02:28:47 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44442/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:28:48 INFO - PROCESS | 31176 | RSS: Main: 183382016 02:28:48 INFO - PROCESS | 31176 | 02:28:50 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:28:50 INFO - PROCESS | 31176 | RSS: Main: 190312448 02:28:50 INFO - PROCESS | 31176 | 02:28:52 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:28:53 INFO - PROCESS | 31176 | RSS: Main: 199270400 02:28:53 INFO - PROCESS | 31176 | 02:28:56 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:28:57 INFO - PROCESS | 31176 | RSS: Main: 203063296 02:28:57 INFO - PROCESS | 31176 | 02:28:59 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:29:00 INFO - PROCESS | 31176 | RSS: Main: 203902976 02:29:00 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | Cycle 1(1): loaded http://localhost:44442/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44442/tests/kraken/driver.html?testName=ai-astar) 02:29:02 INFO - PROCESS | 31176 | RSS: Main: 255332352 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | __start_tp_report 02:29:02 INFO - PROCESS | 31176 | _x_x_mozilla_page_load 02:29:02 INFO - PROCESS | 31176 | _x_x_mozilla_page_load_details 02:29:02 INFO - PROCESS | 31176 | |i|pagename|runs| 02:29:02 INFO - PROCESS | 31176 | |0;ai-astar;105;117;114;115;116;115;115;114;115;115 02:29:02 INFO - PROCESS | 31176 | |1;audio-beat-detection;156;130;124;154;129;130;124;124;149;125 02:29:02 INFO - PROCESS | 31176 | |2;audio-dft;161;161;161;161;162;162;163;162;160;161 02:29:02 INFO - PROCESS | 31176 | |3;audio-fft;75;76;77;107;77;76;76;75;91;75 02:29:02 INFO - PROCESS | 31176 | |4;audio-oscillator;84;85;104;85;85;85;99;84;85;121 02:29:02 INFO - PROCESS | 31176 | |5;imaging-gaussian-blur;117;116;117;117;116;117;116;117;117;117 02:29:02 INFO - PROCESS | 31176 | |6;imaging-darkroom;149;149;150;149;148;149;149;148;149;148 02:29:02 INFO - PROCESS | 31176 | |7;imaging-desaturate;95;95;95;96;96;95;96;95;95;95 02:29:02 INFO - PROCESS | 31176 | |8;json-parse-financial;62;61;60;61;61;62;62;63;62;62 02:29:02 INFO - PROCESS | 31176 | |9;json-stringify-tinderbox;68;72;68;67;68;67;67;67;67;68 02:29:02 INFO - PROCESS | 31176 | |10;stanford-crypto-aes;84;85;85;86;84;84;85;85;86;91 02:29:02 INFO - PROCESS | 31176 | |11;stanford-crypto-ccm;144;142;134;136;148;132;133;140;209;141 02:29:02 INFO - PROCESS | 31176 | |12;stanford-crypto-pbkdf2;175;172;172;173;174;173;173;175;172;171 02:29:02 INFO - PROCESS | 31176 | |13;stanford-crypto-sha256-iterative;67;67;67;66;68;67;66;67;70;65 02:29:02 INFO - PROCESS | 31176 | __end_tp_report 02:29:02 INFO - PROCESS | 31176 | __start_cc_report 02:29:02 INFO - PROCESS | 31176 | _x_x_mozilla_cycle_collect,1819 02:29:02 INFO - PROCESS | 31176 | __end_cc_report 02:29:02 INFO - PROCESS | 31176 | __startTimestamp1484303342595__endTimestamp 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | ------- Summary: start ------- 02:29:02 INFO - PROCESS | 31176 | Number of tests: 14 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.31 (2.9%) stddev-sans-first:0.93 02:29:02 INFO - PROCESS | 31176 | Values: 105.0 117.0 114.0 115.0 116.0 115.0 115.0 114.0 115.0 115.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:130.00 stddev:13.10 (10.1%) stddev-sans-first:11.35 02:29:02 INFO - PROCESS | 31176 | Values: 156.0 130.0 124.0 154.0 129.0 130.0 124.0 124.0 149.0 125.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.88 02:29:02 INFO - PROCESS | 31176 | Values: 161.0 161.0 161.0 161.0 162.0 162.0 163.0 162.0 160.0 161.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#3] audio-fft Cycles:10 Average:80.50 Median:76.00 stddev:10.48 (13.8%) stddev-sans-first:10.93 02:29:02 INFO - PROCESS | 31176 | Values: 75.0 76.0 77.0 107.0 77.0 76.0 76.0 75.0 91.0 75.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:85.00 stddev:12.50 (14.7%) stddev-sans-first:12.94 02:29:02 INFO - PROCESS | 31176 | Values: 84.0 85.0 104.0 85.0 85.0 85.0 99.0 84.0 85.0 121.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 02:29:02 INFO - PROCESS | 31176 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 02:29:02 INFO - PROCESS | 31176 | Values: 149.0 149.0 150.0 149.0 148.0 149.0 149.0 148.0 149.0 148.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 02:29:02 INFO - PROCESS | 31176 | Values: 95.0 95.0 95.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:62.00 stddev:0.84 (1.4%) stddev-sans-first:0.88 02:29:02 INFO - PROCESS | 31176 | Values: 62.0 61.0 60.0 61.0 61.0 62.0 62.0 63.0 62.0 62.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:1.52 (2.2%) stddev-sans-first:1.62 02:29:02 INFO - PROCESS | 31176 | Values: 68.0 72.0 68.0 67.0 68.0 67.0 67.0 67.0 67.0 68.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.00 stddev:2.07 (2.4%) stddev-sans-first:2.12 02:29:02 INFO - PROCESS | 31176 | Values: 84.0 85.0 85.0 86.0 84.0 84.0 85.0 85.0 86.0 91.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:141.50 stddev:22.76 (16.1%) stddev-sans-first:24.13 02:29:02 INFO - PROCESS | 31176 | Values: 144.0 142.0 134.0 136.0 148.0 132.0 133.0 140.0 209.0 141.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:173.00 stddev:1.33 (0.8%) stddev-sans-first:1.20 02:29:02 INFO - PROCESS | 31176 | Values: 175.0 172.0 172.0 173.0 174.0 173.0 173.0 175.0 172.0 171.0 02:29:02 INFO - PROCESS | 31176 | 02:29:02 INFO - PROCESS | 31176 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.33 (2.0%) stddev-sans-first:1.41 02:29:02 INFO - PROCESS | 31176 | Values: 67.0 67.0 67.0 66.0 68.0 67.0 66.0 67.0 70.0 65.0 02:29:02 INFO - PROCESS | 31176 | -------- Summary: end -------- 02:29:02 INFO - PROCESS | 31176 | 02:29:03 INFO - TEST-INFO | 31176: exit 0 02:29:03 INFO - TEST-OK | kraken | took 63305ms 02:29:03 INFO - SUITE-END | took 1183s 02:29:04 INFO - Completed test suite (00:19:44) 02:29:04 INFO - javascript benchmark 02:29:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2562.3081767922663, 2589.805764567658, 2603.6485074514935, 2616.5421051316025, 2618.083670715249, 4656.580907718305, 4722.598579120779, 4772.546608072232, 4787.186178349681, 4796.928046079312, 4715.221988371914, 4751.073540659573, 4770.689905156171, 4776.47458779534, 4811.879703007417, 4403.273459879116, 4430.844920427786, 4438.0, 4462.955370446286, 4507.0760494098795, 4523.932141017871, 4533.410656614636, 4533.818647254106, 4533.886652833673, 4555.863324100265, 4337.0, 4378.584034516716, 4491.775411229425, 4506.346579745951, 4532.841350552748, 4306.784660766949, 4344.89137771555, 4355.651547876162, 4362.454693163355, 4407.537208593084, 4316.82732690692, 4317.913641727179, 4326.134773045404, 4366.519682834885, 4378.868633940987, 4620.006698559792, 4726.054789042205, 4752.144613969484, 4757.952420475785, 4761.928571071437, 4966.900661986776, 5001.299818025479, 5026.346574945233, 5038.2190760432195, 5048.974755126237, 5106.055379754757, 5121.052605268037, 5122.078025955327, 5124.92312615311, 5177.637565370425, 4739.478657347689, 4742.786574604151, 4747.596454301354, 4750.453697824794, 4827.324174615556, 5068.290439338495, 5084.923726144111, 5126.769295381642, 5130.512601302945, 5181.222816577543, 4989.725565093882, 4990.201567749143, 4995.450500444948, 4997.425296090996, 5002.649814512949, 5006.524380183877, 5025.0, 5037.345145131183, 5049.747512624427, 5067.037262920034, 4989.725565093955, 5073.923891141636, 5079.111155547765, 5081.0, 5085.923711144335, 5035.647504674711, 5072.340595722532, 5079.415867175322, 5089.592832573385, 5140.408852981955, 4259.105587826529, 4265.893352666146, 4296.828126874952, 4351.26028575143, 4357.041450880801, 3694.5012423323046, 3722.8510859565854, 3753.530808648919, 3764.322421964073, 3770.321342158383, 4373.016071383977, 4376.759278239664, 4377.124575084996, 4383.912321753547, 4422.358757980075, 5008.574271186917, 5043.0922433961505, 5081.212412076134, 5082.796688132507, 5122.103631864409, 4354.107407981356, 4393.626541743924, 4394.648428125743, 4395.780210989502, 4454.866354009385, 4610.631149508032, 4612.907741845144, 4615.330777037311, 4619.46876109245, 4657.278121891112, 4719.811207551659, 4818.494058123846, 4822.397200349957, 4822.445418776815, 4860.902781944412, 5047.066292735855, 5053.722045287397, 5085.059264036017, 5090.211017292326, 5190.6107041971245, 4805.9038819224115, 4812.927806082842, 4814.229723244264, 4814.350062741485, 4849.345338379274, 5035.773390197523, 5036.697798132123, 5038.84883453497, 5040.42035165946, 5095.719735414439, 5005.724685142207, 5050.04049230645, 5058.924116138334, 5092.949070509248, 5098.2352647103235, 4988.950110498994, 4989.451160372429, 4989.950100499093, 4998.525140111684, 5031.597472202142, 4932.8766780830765, 4937.308776771327, 4978.352814134139, 4986.900261994812, 5015.749212539431, 4850.5149485052625, 4882.194437917846, 4896.706197628154, 4921.876953076202, 4976.278439626161, 5013.298138260573, 5018.297438358706, 5020.748962551785, 5022.67352622093, 5069.214271787879, 4866.489018653132, 4912.312276281395, 4914.483979182278, 4916.582090522345, 4946.975265123651], "value": 4674.8629583345655, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.7274376439899, 566.4929887750462, 568.1335962656948, 583.224311665485, 608.6591508755095, 30135.0, 39602.405963910525, 41088.38367424491, 41168.58831411685, 44852.3272150918, 40833.8749187622, 41294.58705412945, 41305.58694413055, 41387.79306103465, 41442.7927860361, 7399.6670149843385, 7615.695372185106, 7770.33952114071, 7774.650140743652, 7783.571903545303, 7340.816479587999, 10061.0, 10361.171106311476, 10362.585496580128, 10693.83959240612, 10041.0, 10118.949405252963, 10124.645637402693, 10126.848097278547, 10135.037171468699, 10227.284090113695, 10269.948650256702, 10279.17766578672, 10287.691369258935, 10314.587416503331, 10068.345557538736, 10127.392356458637, 10146.898531014669, 10149.188064954786, 10161.288709790319, 38719.612803872165, 39070.0, 39109.41335879964, 39111.413328799805, 39128.0, 3585.354636165489, 3697.759645623026, 3707.1844194277214, 3733.6453036961443, 3748.775073495599, 29660.555091673643, 29802.254943626365, 29954.55068173979, 29971.850140749375, 31024.0, 3477.2523907360037, 3526.5415495985612, 3529.0, 3537.7877327360443, 3562.536870206882, 237060.0, 237963.0, 238337.99999999825, 238388.0, 240341.0, 54774.72612636911, 55137.72431137819, 55196.72401637966, 55250.44749552533, 55264.17103743447, 57616.0, 57803.0, 57811.0, 57868.0, 57878.42121578815, 12545.184563003375, 12919.806202906964, 12939.223646581235, 12941.741165176643, 12953.251311072885, 8564.74305770828, 8797.692080777142, 9183.081691830897, 9188.816223675489, 9395.34232603725, 16056.75914861278, 16537.0, 16550.0, 16576.254068566945, 16576.6684666306, 5800.158976948319, 5852.326982396994, 5888.941110588925, 5973.820785376446, 6046.909296360558, 831.7795784117213, 840.7561807076002, 847.3221422861686, 853.5604163855619, 873.401719821924, 27620.861895690392, 28159.43681126366, 28488.715112848608, 28865.278368040967, 28961.855190724335, 967.2890425537224, 972.9659461918799, 974.2692980264801, 975.6341371985505, 984.1416290917022, 14997.625059373602, 15100.773488397683, 15170.393184272727, 15196.544103676908, 15440.757440937528, 322398.0, 322827.0, 324164.0, 324231.0, 324897.0, 41673.37489937653, 41693.37459938104, 41803.372949405784, 41803.7909810449, 41809.0, 1207.5471698113208, 1241.9813702794465, 1249.4814651919532, 1251.474380760083, 1257.3713143428286, 25571.74428255768, 26358.0, 27404.177874663794, 27470.45059098847, 27806.44387112287, 42081.36877946895, 42681.1463770729, 42707.0, 42735.57264427316, 42754.0, 212924.0, 263754.0, 264040.0, 264096.0, 264405.0, 32109.678903210668, 33145.17137071496, 35815.28369432649, 36762.0, 37091.25817483689, 41851.350067740466, 41894.58105418907, 42101.57898421099, 42407.575924240366, 42453.57546424496, 34434.655653443144, 34644.65355346515, 34841.0, 35008.0, 35483.64516354803, 33796.0, 34645.48031779475, 34957.82521087378, 35293.117672058404, 35890.4616430759, 34334.0, 34951.65048349484, 35002.0, 35020.64979350174, 35060.649393506756, 30214.0, 31393.0, 31580.0, 31805.681943180272, 32038.19904502406, 24292.878535607208, 24519.877400613597, 24693.87653061723, 25037.0, 25070.37324066913], "value": 17685.561736595355, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.7708436395982, 391.25856501928826, 399.098038433141, 406.0923835228265, 419.4819398043416, 13626.727465450685, 14863.92568037161, 17003.14984250787, 18576.535586610324, 18589.070546472674, 12518.311492867888, 12942.93528532356, 14940.0, 15258.389664413438, 15343.923280383611, 4995.150824359858, 5255.816046438375, 5333.573314134879, 5538.47384498472, 5573.303337082864, 5558.805441809517, 5591.1054231323, 5591.944080559203, 5597.972010139943, 5599.048161812481, 5450.155225939986, 5551.9722401387935, 5574.10814269717, 5587.078132108198, 5587.944120558783, 5590.273264475612, 5607.747651355658, 5612.971935140298, 5623.297087863996, 5625.268715067056, 4864.2217245240945, 5086.567641750456, 5119.718415487147, 5192.7403629818355, 5195.350581177353, 11199.944000280027, 11388.430578471127, 11392.658220253406, 18511.0, 20989.47526311839, 2091.633964056284, 2135.5975813714026, 2240.092762431218, 2432.4877352511016, 2433.58629033063, 8891.199792018684, 8921.286297096281, 8940.061293564147, 9347.672831450867, 9441.19749821266, 1738.9217485213069, 1753.7983131939864, 1755.280335062631, 1764.382466136842, 1785.4643606918003, 70573.64713176401, 76634.61682691607, 77182.45635087267, 77220.22779772186, 79153.20846791574, 10407.375557466576, 10568.313059651098, 10568.52441640128, 10634.255602107933, 10711.357318560755, 15057.0, 15084.0, 15092.924535377253, 15108.471203508045, 15115.31981060833, 5863.472287494061, 6703.296153903867, 6921.48088893335, 7016.298370162943, 7031.683574239171, 5428.83713488596, 5711.543076553866, 5799.5650326225705, 5995.46040856325, 5995.700214989321, 4453.3320001999955, 4460.130274596488, 4656.068786242765, 4700.764961751899, 4752.976235118871, 1845.2342277954767, 1855.3691744806831, 1890.4990177602947, 1892.6782446984046, 1968.1143485431612, 848.2874385516196, 864.8140649760331, 871.3551971541141, 883.1013321359129, 898.955052247385, 16834.915825421038, 16930.492085237463, 17378.217980190922, 17644.382446614563, 17923.0, 866.276658989738, 867.2584939876441, 869.1959937058195, 885.6767279942775, 898.5866501409295, 9188.540572971324, 9802.75493112678, 9803.950980245196, 9814.705558833246, 9980.650677226407, 496275.0, 502397.1284450916, 502860.0, 503669.0, 511046.0, 72627.2737272631, 94445.5277723607, 99748.50125749325, 99821.0, 101293.0, 927.536231884058, 931.725141083382, 965.6909788867495, 967.7580604848788, 974.4835237324229, 15858.0, 16346.0, 16831.326746929986, 16869.831301687318, 17026.659466810346, 40610.593894061865, 40680.79659601683, 40960.0, 41208.0, 41443.27010569063, 42813.0, 59664.70167649134, 61138.69430652818, 61315.69342153439, 62145.0, 7142.785716428515, 7381.188069312264, 7950.7217247396065, 7957.363410927228, 8080.111187769223, 10740.409277489813, 11785.292882427082, 12661.74676506483, 12720.427580759073, 13028.348582570645, 10194.694159175237, 10426.791464170523, 10763.892361076601, 11025.283356581796, 11306.773864522827, 7424.294692004251, 10426.947865260625, 10470.476476176313, 10473.947630262104, 10601.363918164934, 9982.900170998197, 10305.845412318971, 10312.869626842099, 10676.145908327471, 10914.617988370686, 7837.804054898673, 7906.881396779168, 7930.762077137695, 8015.238552337519, 8298.336133109458, 7713.691452342068, 9654.855177172207, 9963.551640176354, 10190.1338386238, 10466.16270698327], "value": 8385.47538088002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.4710115953617, 1339.9330033498322, 1340.4638144742103, 1344.1061693973509, 1357.9253141077236, 7012.719491220351, 7376.520526165796, 7380.630968451583, 7381.409487241021, 7401.666924988375, 6374.872502549946, 6558.081868538408, 6973.9302606973915, 7120.0, 7152.463565232602, 2167.707359506466, 2188.113813905371, 2189.8029177374033, 2190.244365693833, 2204.7795220477983, 2065.9070341834577, 2160.9891950540227, 2181.5309708412688, 2182.8690278583335, 2184.9344519664437, 1857.3313607101434, 1935.5548223908438, 2109.725735654363, 2173.445771328309, 2178.4989452425953, 2182.2471247419617, 2186.6719992001167, 2204.889755512234, 2206.5145667953107, 2214.413180507167, 2173.054721196285, 2198.967015494753, 2200.504886400563, 2202.394341556069, 2202.9118835246572, 7358.484906056578, 7406.111266647983, 7412.666430010609, 7412.999245101896, 7416.184219735825, 4223.535411104776, 4372.978135109304, 4427.048184640318, 4431.047324825146, 4434.955650443487, 2733.1117386849155, 2983.4331477019296, 2984.0451055662197, 2995.1613548206537, 2999.2501874531367, 1048.4338457233084, 1068.3216157739814, 1079.838024296354, 1081.7674200047027, 1127.520803658442, 9694.17599504036, 9711.465869377182, 9728.708138755848, 9729.075737804897, 9771.853422198672, 4093.733907296076, 4280.850170244026, 4332.8768903237815, 4335.2196604610845, 4351.564843515623, 7785.844283114306, 7844.37245020397, 7850.371970242367, 7851.489653172525, 7858.921410785933, 4441.311596702516, 4442.644588432982, 4443.2224360736745, 4444.644428445717, 4448.888777780581, 3922.254771593445, 4014.518257809023, 4048.5344185419053, 4112.0, 4113.362428823537, 3186.1078897908624, 3296.983515082409, 3308.0, 3313.2379552703014, 3314.502824576333, 1022.7238645565805, 1028.4292217819182, 1033.4316126130748, 1036.9688909332733, 1041.4740556019162, 675.9695813688396, 705.6119134476018, 719.3669570777682, 734.3831181807308, 735.334522257358, 4834.299026641117, 4947.505249475023, 5027.296178535008, 5096.643234973516, 5257.973710131425, 847.9109693482095, 918.237862574062, 927.5686805635412, 933.3419938943028, 967.327707243459, 3132.042358853677, 3413.7951722896705, 3495.807730574843, 3505.6844883960566, 3612.5664920209742, 8230.876536851953, 8574.099719529484, 8578.4424012439, 8579.957100214458, 8590.742277731679, 8519.446235994641, 8530.232279094911, 8543.401961862734, 8549.615267312985, 8557.315414766803, 6756.189257289157, 6778.084958530634, 6789.592624442549, 6791.762288319885, 6827.795166145023, 4726.1492931272705, 4802.471728109836, 4916.7541622918, 4941.456439791692, 4976.253561965734, 7757.26306000929, 7906.051273847175, 8022.598870056357, 8109.878351824609, 8146.75559733209, 8048.597570121354, 8316.792080198044, 8387.454815436977, 8428.704995325133, 8447.915520844714, 5863.824085277449, 5965.791197308074, 5969.611975221596, 5974.1934838796205, 5980.611260268069, 6517.511186660925, 6902.102726645504, 6967.059446974594, 6971.825704357431, 6976.372126508639, 6156.78451254204, 6191.349908259748, 6224.253089629274, 6276.089966954857, 6462.192225971754, 6066.332703402534, 6079.240094988127, 6114.938850611615, 6136.907946380718, 6143.324234334309, 5372.973135134299, 5660.830175094754, 5717.7712891485535, 5718.228039214653, 5720.3421606515785, 4529.550543825944, 4562.133194692998, 4617.515160908057, 4646.070785842778, 4694.460137084279, 4287.7641729705165, 4367.956320436882, 4476.940607320669, 4545.227311357011, 4687.835925742583], "value": 3911.745167043424, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9543761592186, 349.3990336621012, 351.74674234551117, 355.7847502261134, 359.3746880428056, 4750.239961606144, 4886.462489126193, 4954.207326827708, 4987.675801072933, 5014.997000599881, 4760.428748550179, 4844.1764899967, 4864.246041863508, 4878.121938051158, 4924.507549245073, 3947.407888816672, 3953.9209215815667, 3961.2869683456966, 3968.2658708138943, 3971.1462035662325, 3758.9060273493246, 3787.5039359453, 3970.841166353357, 3975.86084487043, 3985.163115745697, 3865.690744740414, 3885.7759805661267, 3891.7470364426363, 3900.219956008795, 3923.15652134791, 3892.5134358205223, 3902.0, 3902.239063382656, 3914.297280682898, 3919.7844118573466, 3543.4861945018088, 3545.6986156176754, 3545.716342692604, 3548.3967725486727, 3567.8929632111076, 4690.771883355413, 4797.280407938802, 4813.724363043796, 4820.156472617278, 4902.475225258994, 4496.2581174106235, 4497.797599108048, 4510.143072816188, 4524.68327217095, 4527.7283362998, 5146.150885103953, 5158.3552055993005, 5162.767675454614, 5162.948370516285, 5174.456682048406, 3458.4985177149424, 3618.095476130967, 3658.615845336228, 3894.3768996960634, 3894.493715816954, 5298.655587386807, 5305.31030965972, 5363.651362661414, 5377.650452720561, 5383.573353061442, 3623.7463377563317, 3628.74598778083, 3648.4892115103908, 3651.2332410193626, 3696.464012718141, 3630.4917311575873, 3752.2120354725266, 3753.3619284721663, 3753.60587138352, 3757.4363845423404, 4228.498882896589, 4233.386159006926, 4271.743695378287, 4271.864067966017, 4294.871153865389, 5064.620153488429, 5089.3638295213095, 5090.949090509048, 5112.335396398519, 5115.2071428928575, 1194.8230992472409, 1216.4404373987888, 1222.45600707686, 1245.701031752373, 1263.3935710858655, 820.9096999330069, 825.628467189767, 840.8024114333127, 846.7248144352986, 866.0776273268948, 4308.310670292738, 4339.4792624884585, 4350.2387082260475, 4363.629091527256, 4393.362962370438, 5120.718360490208, 5137.486251374908, 5159.535641792257, 5161.509656582693, 5163.870903227375, 4185.958140418617, 4372.562743725602, 4401.977990110093, 4402.251617225015, 4417.0, 2700.6226824319347, 2706.9729302707115, 2708.6885008223912, 2718.4834881372476, 2722.7821774258014, 4787.234042553175, 4805.567498925114, 4823.5658790709, 4840.661153719277, 4848.636352273594, 4917.729524876095, 5121.308623335876, 5135.3324067870935, 5154.664946778447, 5162.819301324435, 4759.547842954914, 4765.663246445718, 4773.0, 4775.638942901256, 4780.856574302777, 5060.1903695408555, 5060.595152387727, 5089.618278629192, 5095.566876815364, 5129.487051294841, 4955.886614110352, 5170.353705786777, 5216.0, 5218.765155568084, 5236.371635403776, 4946.1591529439365, 4963.106640804545, 4968.0, 4969.776360063734, 4975.353204083446, 3968.226195891889, 4014.8594799181888, 4016.7389119707127, 4023.7183397161916, 4034.8184331704556, 5155.381354237516, 5216.40011398694, 5295.576353891603, 5318.521333080042, 5346.8395948121615, 5124.231365295235, 5124.333836601218, 5128.820491282787, 5147.768350424165, 5150.40770311419, 4911.68074075184, 5114.670185751806, 5127.46161653036, 5132.409772876167, 5141.691498510101, 4840.201366774443, 4973.52751488608, 5039.974800125976, 5061.645684802176, 5103.948960510347, 5062.82280120194, 5067.290579318972, 5098.668586541863, 5117.309163262912, 5120.8222108914415, 4969.279454479152, 5016.448190699093, 5024.371953505812, 5036.0, 5049.57078648319], "value": 3863.182087585614, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.7726173101145, 679.9354061364169, 724.5978481942519, 727.5598263050855, 751.5866273549547, 13320.333983300832, 13956.651083722913, 13957.441702331895, 14011.789823152649, 14051.718965620681, 13232.338383080856, 13355.265460399674, 13392.732145357111, 13394.933025334883, 13400.262985535792, 640.7308930249283, 658.3021996683507, 778.4784194589638, 796.7888509544975, 808.789714674186, 816.6529225079347, 819.0703551469096, 819.9754007379759, 820.3888103363003, 820.5076953827715, 854.444611002847, 855.6235256487125, 856.6359297298654, 856.9271611912994, 857.0657982798748, 679.7892653277476, 696.9930300696979, 699.2692636195188, 699.3461113858558, 857.2627540315324, 616.9105479705418, 635.8378613453586, 638.3425072175667, 640.0974625777632, 640.1549954060641, 13495.730085398336, 13572.45710171592, 13593.59219223415, 13608.455661773518, 13611.183329000292, 11756.05951523876, 11928.701782455508, 11996.460159292852, 12213.0, 12224.816627750592, 10084.848727269096, 11016.724581885437, 11106.111511079094, 11127.944360278228, 11224.943875280653, 4116.75299482032, 4142.937855932163, 4159.563245859172, 4194.517630472505, 4247.086876321606, 60460.39539604592, 61836.0, 63517.0, 63797.0, 64269.357306426806, 10056.547455364525, 10112.74718132038, 10219.693409197736, 10268.794624107475, 10449.425281609585, 18011.909940450212, 18149.183286752126, 18176.45470635883, 18177.182026808827, 18182.63634727298, 6186.792396762936, 6348.8412789679705, 6461.547691661633, 6488.545801793924, 6489.740410383626, 3371.76397652162, 3374.088995971074, 3391.881284155092, 3485.616582176009, 3492.6681965213265, 2645.4444566641223, 2677.7322267773066, 2704.2698471413, 2712.294803351143, 2719.048333083405, 487.18396685552267, 493.8074151081081, 498.837877689745, 499.97750101244804, 500.30457663847284, 543.3805461773582, 549.3380476525776, 558.3774091887516, 569.64966545575, 571.4371344225934, 7863.803404914808, 8129.79675508117, 8354.665813367397, 8517.403781735218, 8605.0, 536.6779932040822, 556.093567485005, 556.7049463784201, 565.6803905793214, 574.5059249045818, 3737.5514938207043, 3745.8127093645207, 3765.755225910362, 3770.3967365221433, 3867.9226415471535, 46668.299975499714, 50647.24029139566, 52567.474325257026, 64688.353116469174, 65959.34040659532, 52093.739531302104, 52227.21659175115, 52879.20681189785, 53348.0, 53667.559892813115, 47382.76308618504, 47837.52162478331, 49106.0, 49870.75064624654, 50017.249741254636, 7943.165967573553, 8673.869891951499, 8699.260562852229, 8810.647574096964, 8941.239994600532, 33543.0, 33753.32493350168, 34244.0, 34614.82692586521, 35517.64482355143, 40150.397744034446, 40165.59834401619, 40893.79553102215, 41174.1765164701, 41989.58010419979, 10626.52180651857, 10714.196435267542, 10769.892301076889, 11044.282121662067, 11265.324080555192, 12115.878841211475, 12251.0, 12517.436715348013, 12717.55488557896, 13462.663433414244, 11206.943965280121, 11256.493457794582, 11512.94243528777, 11571.479283432434, 11843.763124737628, 11245.325280483197, 11440.485178166837, 11479.311241325548, 11975.940120299343, 12187.268763874195, 11321.150913681671, 11529.135314851252, 11552.191346605658, 12119.81820272679, 12182.6954326139, 9156.267498599962, 9280.350375473652, 9461.574229159567, 9512.1439070484, 9701.175400091071, 9469.763255918657, 9630.32587718853, 9695.806083878424, 9708.417494950325, 9713.320067595201], "value": 6161.383880560187, "unit": "score"}], "name": "dromaeo_css", "value": 6333.739496444357, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 114.0, 115.0, 116.0, 115.0, 115.0, 114.0, 115.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 130.0, 124.0, 154.0, 129.0, 130.0, 124.0, 124.0, 149.0, 125.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 163.0, 162.0, 160.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 107.0, 77.0, 76.0, 76.0, 75.0, 91.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 104.0, 85.0, 85.0, 85.0, 99.0, 84.0, 85.0, 121.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 149.0, 148.0, 149.0, 149.0, 148.0, 149.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 61.0, 61.0, 62.0, 62.0, 63.0, 62.0, 62.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 68.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 68.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 86.0, 84.0, 84.0, 85.0, 85.0, 86.0, 91.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 142.0, 134.0, 136.0, 148.0, 132.0, 133.0, 140.0, 209.0, 141.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 172.0, 173.0, 174.0, 173.0, 173.0, 175.0, 172.0, 171.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 68.0, 67.0, 66.0, 67.0, 70.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1543.9, "alertThreshold": 2.0}]} 02:29:04 INFO - Return code: 0 02:29:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:29:04 INFO - # TBPL SUCCESS # 02:29:04 INFO - Running post-action listener: _resource_record_post_action 02:29:04 INFO - [mozharness: 2017-01-13 10:29:04.370807Z] Finished run-tests step (success) 02:29:04 INFO - Running post-run listener: _resource_record_post_run 02:29:04 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382656512; Read time: 20; Write time: 244536 02:29:04 INFO - TinderboxPrint: CPU usage
13.1% 02:29:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 02:29:04 INFO - TinderboxPrint: I/O write bytes / time
382,656,512 / 244,536 02:29:04 INFO - TinderboxPrint: CPU idle
8,330.8 (86.9%) 02:29:04 INFO - TinderboxPrint: CPU user
1,211.3 (12.6%) 02:29:04 INFO - TinderboxPrint: Swap in / out
0 / 0 02:29:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100282368; Read time: 0; Write time: 94096 02:29:04 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 282374144; Read time: 0; Write time: 150440 02:29:04 INFO - Running post-run listener: _upload_blobber_files 02:29:04 INFO - Blob upload gear active. 02:29:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:29:04 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 02:29:04 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'] 02:29:04 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 02:29:05 INFO - (blobuploader) - INFO - Open directory for files ... 02:29:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:29:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:08 INFO - (blobuploader) - INFO - Done attempting. 02:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:29:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:09 INFO - (blobuploader) - INFO - Done attempting. 02:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:10 INFO - (blobuploader) - INFO - Done attempting. 02:29:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:29:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:11 INFO - (blobuploader) - INFO - Done attempting. 02:29:11 INFO - (blobuploader) - INFO - Iteration through files over. 02:29:11 INFO - Return code: 0 02:29:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:29:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:29:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/592c8a41624197968c57e5c742b88edc1629423943903133ab8934e16fb96ba323148117015def7c7d46eb7a202e3e3a8ea6c0da746b76e82ec19b593acd1cad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5920c479798021794d9f5c33956c1e5dd9f08afc69694335affd3dac38d3867a8bb44d147e2be352d801867cd01bc86f3b71b442b982a81346099428de68c5fb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b061498b2a0ec37f3262378cce353626462be45cbbc131f1771d6e75865224d0dceac560833cf754dfc7927cd3f18584a0caf71240662af86a112211cdea129d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b0c9656fe0bec380ae1cdb0b50de9e9ab5cf63a7ca55fc34f0e95fe572baaa3b8d7db48b4e71ba05ce0e6877a496efb50a6f0fc47268e4f3df46fd984690f4b1"} 02:29:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:29:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:29:11 INFO - Contents: 02:29:11 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/592c8a41624197968c57e5c742b88edc1629423943903133ab8934e16fb96ba323148117015def7c7d46eb7a202e3e3a8ea6c0da746b76e82ec19b593acd1cad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5920c479798021794d9f5c33956c1e5dd9f08afc69694335affd3dac38d3867a8bb44d147e2be352d801867cd01bc86f3b71b442b982a81346099428de68c5fb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b061498b2a0ec37f3262378cce353626462be45cbbc131f1771d6e75865224d0dceac560833cf754dfc7927cd3f18584a0caf71240662af86a112211cdea129d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b0c9656fe0bec380ae1cdb0b50de9e9ab5cf63a7ca55fc34f0e95fe572baaa3b8d7db48b4e71ba05ce0e6877a496efb50a6f0fc47268e4f3df46fd984690f4b1"} 02:29:11 INFO - Running post-run listener: copy_logs_to_upload_dir 02:29:11 INFO - Copying logs to upload dir... 02:29:11 INFO - mkdir: /builds/slave/test/build/upload/logs 02:29:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.938957 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-01-13 02:29:11.177807) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 02:29:11.180972) ========= 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=db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319 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/592c8a41624197968c57e5c742b88edc1629423943903133ab8934e16fb96ba323148117015def7c7d46eb7a202e3e3a8ea6c0da746b76e82ec19b593acd1cad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5920c479798021794d9f5c33956c1e5dd9f08afc69694335affd3dac38d3867a8bb44d147e2be352d801867cd01bc86f3b71b442b982a81346099428de68c5fb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b061498b2a0ec37f3262378cce353626462be45cbbc131f1771d6e75865224d0dceac560833cf754dfc7927cd3f18584a0caf71240662af86a112211cdea129d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b0c9656fe0bec380ae1cdb0b50de9e9ab5cf63a7ca55fc34f0e95fe572baaa3b8d7db48b4e71ba05ce0e6877a496efb50a6f0fc47268e4f3df46fd984690f4b1"} build_url:https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011801 build_url: 'https://queue.taskcluster.net/v1/task/I-nqUarhTYGCDjWtbNGYkg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/592c8a41624197968c57e5c742b88edc1629423943903133ab8934e16fb96ba323148117015def7c7d46eb7a202e3e3a8ea6c0da746b76e82ec19b593acd1cad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5920c479798021794d9f5c33956c1e5dd9f08afc69694335affd3dac38d3867a8bb44d147e2be352d801867cd01bc86f3b71b442b982a81346099428de68c5fb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b061498b2a0ec37f3262378cce353626462be45cbbc131f1771d6e75865224d0dceac560833cf754dfc7927cd3f18584a0caf71240662af86a112211cdea129d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b0c9656fe0bec380ae1cdb0b50de9e9ab5cf63a7ca55fc34f0e95fe572baaa3b8d7db48b4e71ba05ce0e6877a496efb50a6f0fc47268e4f3df46fd984690f4b1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 02:29:11.206574) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 02:29:11.206884) ========= 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=db0483c52d987ecb441a263b000001ad-1484301605.999490-1584767319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 02:29:11.292609) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 02:29:11.292944) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 02:29:11.293243) ========= ========= Total master_lag: 0.18 =========