builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1483748826.3 results: success (0) revision: 1a815865294fbf234f166c075986f587b2b72a89 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.297557) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.297984) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.298256) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.331667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.331923) ========= 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=4300f881736b422476174874000001bc-1483748825.981598-1286120602 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-06 16:27:06-- 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.4M=0s 2017-01-06 16:27:06 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113275 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.458686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.459004) ========= 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=4300f881736b422476174874000001bc-1483748825.981598-1286120602 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018795 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.507873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:06.508163) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 1a815865294fbf234f166c075986f587b2b72a89 --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 1a815865294fbf234f166c075986f587b2b72a89 --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=4300f881736b422476174874000001bc-1483748825.981598-1286120602 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-06 16:27:06,832 truncating revision to first 12 chars 2017-01-06 16:27:06,832 Setting DEBUG logging. 2017-01-06 16:27:06,832 attempt 1/10 2017-01-06 16:27:06,832 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/1a815865294f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 16:27:07,362 unpacking tar archive at: graphics-1a815865294f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.893958 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:07.415031) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:07.415360) ========= script_repo_revision: 1a815865294fbf234f166c075986f587b2b72a89 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:07.415770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:07.416079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 16:27:07.424116) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-01-06 16:27:07.424400) ========= /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=4300f881736b422476174874000001bc-1483748825.981598-1286120602 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:27:07 INFO - MultiFileLogger online at 20170106 16:27:07 in /builds/slave/test 16:27:07 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 16:27:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:27:07 INFO - {'append_to_log': False, 16:27:07 INFO - 'base_work_dir': '/builds/slave/test', 16:27:07 INFO - 'blob_upload_branch': 'Graphics', 16:27:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:27:07 INFO - 'branch': 'Graphics', 16:27:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:27:07 INFO - 'config_files': ('talos/linux_config.py',), 16:27:07 INFO - 'default_actions': ('clobber', 16:27:07 INFO - 'read-buildbot-config', 16:27:07 INFO - 'download-and-extract', 16:27:07 INFO - 'populate-webroot', 16:27:07 INFO - 'create-virtualenv', 16:27:07 INFO - 'install', 16:27:07 INFO - 'run-tests'), 16:27:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:27:07 INFO - 'download_minidump_stackwalk': True, 16:27:07 INFO - 'download_symbols': 'ondemand', 16:27:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:27:07 INFO - 'tooltool.py': '/tools/tooltool.py', 16:27:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:27:07 INFO - '/tools/misc-python/virtualenv.py')}, 16:27:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:27:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:27:07 INFO - 'installer_path': 'installer.exe', 16:27:07 INFO - 'log_level': 'info', 16:27:07 INFO - 'log_name': 'talos', 16:27:07 INFO - 'log_to_console': True, 16:27:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:27:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:27:07 INFO - 'opt_config_files': (), 16:27:07 INFO - 'pip_index': False, 16:27:07 INFO - 'sps_profile': False, 16:27:07 INFO - 'sps_profile_interval': 0, 16:27:07 INFO - 'suite': 'dromaeojs', 16:27:07 INFO - 'system_bits': '32', 16:27:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:27:07 INFO - 'title': 'talos-linux64-ix-045', 16:27:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:27:07 INFO - 'use_talos_json': True, 16:27:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:27:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:27:07 INFO - 'work_dir': 'build'} 16:27:07 INFO - [mozharness: 2017-01-07 00:27:07.845541Z] Running clobber step. 16:27:07 INFO - Running pre-action listener: _resource_record_pre_action 16:27:07 INFO - Running main action method: clobber 16:27:07 INFO - rmtree: /builds/slave/test/build 16:27:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:27:08 INFO - Running post-action listener: _resource_record_post_action 16:27:08 INFO - [mozharness: 2017-01-07 00:27:08.304986Z] Finished clobber step (success) 16:27:08 INFO - [mozharness: 2017-01-07 00:27:08.305088Z] Running read-buildbot-config step. 16:27:08 INFO - Running pre-action listener: _resource_record_pre_action 16:27:08 INFO - Running main action method: read_buildbot_config 16:27:08 INFO - Using buildbot properties: 16:27:08 INFO - { 16:27:08 INFO - "project": "", 16:27:08 INFO - "product": "firefox", 16:27:08 INFO - "who": "kgupta@mozilla.com", 16:27:08 INFO - "installer_path": "public/build/target.tar.bz2", 16:27:08 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 16:27:08 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 16:27:08 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 16:27:08 INFO - "stage_platform": "linux64", 16:27:08 INFO - "basedir": "/builds/slave/test", 16:27:08 INFO - "buildnumber": 6, 16:27:08 INFO - "platform": "ubuntu64_hw", 16:27:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:27:08 INFO - "slavebuilddir": "test", 16:27:08 INFO - "taskId": "VSho0kp5Q1arTYIY7X5WeQ", 16:27:08 INFO - "branch": "graphics", 16:27:08 INFO - "script_repo_revision": "default", 16:27:08 INFO - "revision": "1a815865294fbf234f166c075986f587b2b72a89", 16:27:08 INFO - "slavename": "talos-linux64-ix-045", 16:27:08 INFO - "repo_path": "projects/graphics" 16:27:08 INFO - } 16:27:08 INFO - Finding installer, test and symbols from parent task. 16:27:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VSho0kp5Q1arTYIY7X5WeQ'}, attempt #1 16:27:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VSho0kp5Q1arTYIY7X5WeQ'}, attempt #1 16:27:09 INFO - Task dependencies: UO8-Lu3cQSq6E94FANkudQ 16:27:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ'}, attempt #1 16:27:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2 16:27:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/test_packages.json 16:27:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.crashreporter-symbols.zip 16:27:09 INFO - Running post-action listener: _resource_record_post_action 16:27:09 INFO - [mozharness: 2017-01-07 00:27:09.735670Z] Finished read-buildbot-config step (success) 16:27:09 INFO - [mozharness: 2017-01-07 00:27:09.735887Z] Running download-and-extract step. 16:27:09 INFO - Running pre-action listener: _resource_record_pre_action 16:27:09 INFO - Running main action method: download_and_extract 16:27:09 INFO - mkdir: /builds/slave/test/build/tests 16:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:09 INFO - trying https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.test_packages.json 16:27:09 INFO - Downloading https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:27:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:27:10 INFO - Downloaded 896 bytes. 16:27:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:27:10 INFO - Using the following test package requirements: 16:27:10 INFO - {u'common': [u'target.common.tests.zip'], 16:27:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:27:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:27:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:27:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:27:10 INFO - u'mozbase': [u'target.common.tests.zip'], 16:27:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:27:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:27:10 INFO - u'web-platform': [u'target.common.tests.zip', 16:27:10 INFO - u'target.web-platform.tests.zip'], 16:27:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:27:10 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:27:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.common.tests.zip 16:27:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:27:10 INFO - Fetch https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.common.tests.zip into memory 16:27:12 INFO - Expected file size: 20246889 16:27:12 INFO - Obtained file size: 20246889 16:27:16 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:27:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.common.tests.zip 16:27:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:27:16 INFO - Fetch https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.common.tests.zip into memory 16:27:17 INFO - Expected file size: 20246889 16:27:17 INFO - Obtained file size: 20246889 16:27:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.talos.tests.zip 16:27:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:27:22 INFO - Fetch https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.talos.tests.zip into memory 16:27:23 INFO - Expected file size: 14403730 16:27:23 INFO - Obtained file size: 14403730 16:27:24 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')]} 16:27:24 INFO - trying https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2 16:27:24 INFO - Downloading https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:27:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:27:26 INFO - Downloaded 55877633 bytes. 16:27:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2 16:27:26 INFO - mkdir: /builds/slave/test/properties 16:27:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:26 INFO - Contents: 16:27:26 INFO - build_url:https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2 16:27:26 INFO - Running post-action listener: _resource_record_post_action 16:27:26 INFO - Running post-action listener: set_extra_try_arguments 16:27:26 INFO - [mozharness: 2017-01-07 00:27:26.006466Z] Finished download-and-extract step (success) 16:27:26 INFO - [mozharness: 2017-01-07 00:27:26.006563Z] Running populate-webroot step. 16:27:26 INFO - Running pre-action listener: _resource_record_pre_action 16:27:26 INFO - Running main action method: populate_webroot 16:27:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:26 INFO - '--tppagecycles', 16:27:26 INFO - '7'], 16:27:26 INFO - 'tests': ['tsvgm']}}, 16:27:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:27:26 INFO - 'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:27:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:27:26 INFO - 'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['damp', 'tps']}, 16:27:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:27:26 INFO - 'tests': ['damp', 'tps']}, 16:27:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['dromaeo_dom']}, 16:27:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['basic_compositor_video']}, 16:27:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['a11yr', 16:27:26 INFO - 'ts_paint', 16:27:26 INFO - 'tpaint', 16:27:26 INFO - 'sessionrestore', 16:27:26 INFO - 'sessionrestore_no_auto_restore', 16:27:26 INFO - 'tabpaint']}, 16:27:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:26 INFO - 'ts_paint', 16:27:26 INFO - 'tpaint', 16:27:26 INFO - 'sessionrestore', 16:27:26 INFO - 'sessionrestore_no_auto_restore', 16:27:26 INFO - 'tabpaint']}, 16:27:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:26 INFO - 'ts_paint', 16:27:26 INFO - 'tpaint', 16:27:26 INFO - 'sessionrestore', 16:27:26 INFO - 'sessionrestore_no_auto_restore', 16:27:26 INFO - 'tabpaint']}, 16:27:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:26 INFO - 'ts_paint', 16:27:26 INFO - 'tpaint', 16:27:26 INFO - 'sessionrestore', 16:27:26 INFO - 'sessionrestore_no_auto_restore', 16:27:26 INFO - 'tabpaint']}, 16:27:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['a11yr', 16:27:26 INFO - 'ts_paint', 16:27:26 INFO - 'tpaint', 16:27:26 INFO - 'sessionrestore', 16:27:26 INFO - 'sessionrestore_no_auto_restore', 16:27:26 INFO - 'tabpaint']}, 16:27:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['a11yr', 16:27:26 INFO - 'ts_paint', 16:27:26 INFO - 'tpaint', 16:27:26 INFO - 'sessionrestore', 16:27:26 INFO - 'sessionrestore_no_auto_restore', 16:27:26 INFO - 'tabpaint']}, 16:27:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['tsvgx', 16:27:26 INFO - 'tsvgr_opacity', 16:27:26 INFO - 'tart', 16:27:26 INFO - 'tscrollx', 16:27:26 INFO - 'cart', 16:27:26 INFO - 'tsvg_static']}, 16:27:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:26 INFO - 'tsvgr_opacity', 16:27:26 INFO - 'tart', 16:27:26 INFO - 'tscrollx', 16:27:26 INFO - 'cart', 16:27:26 INFO - 'tsvg_static']}, 16:27:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:27:26 INFO - 'talos_options': ['--disable-e10s'], 16:27:26 INFO - 'tests': ['tp5o']}, 16:27:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:27:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:27:26 INFO - 'talos_options': ['--disable-e10s', 16:27:26 INFO - '--xperf_path', 16:27:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:26 INFO - 'tests': ['tp5n']}, 16:27:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:27:26 INFO - 'talos_options': ['--xperf_path', 16:27:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:26 INFO - 'tests': ['tp5n']}}, 16:27:26 INFO - 'talos.zip': {'path': '', 16:27:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:26 INFO - Running post-action listener: _resource_record_post_action 16:27:26 INFO - [mozharness: 2017-01-07 00:27:26.019907Z] Finished populate-webroot step (success) 16:27:26 INFO - [mozharness: 2017-01-07 00:27:26.020001Z] Running create-virtualenv step. 16:27:26 INFO - Running pre-action listener: _resource_record_pre_action 16:27:26 INFO - Running main action method: create_virtualenv 16:27:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:26 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 16:27:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:26 INFO - Using real prefix '/usr' 16:27:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:26 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:28 INFO - Installing pip.................done. 16:27:28 INFO - Return code: 0 16:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:27:28 INFO - Reading from file tmpfile_stdout 16:27:28 INFO - Output received: 16:27:28 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) 16:27:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:28 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:28 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 16:27:28 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 16:27:28 INFO - Using env: {'DISPLAY': ':0', 16:27:28 INFO - 'HOME': '/home/cltbld', 16:27:28 INFO - 'LANG': 'en_US.UTF-8', 16:27:28 INFO - 'LANGUAGE': 'en_US:en', 16:27:28 INFO - 'LOGNAME': 'cltbld', 16:27:28 INFO - 'MAIL': '/var/mail/cltbld', 16:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:28 INFO - 'MOZ_NO_REMOTE': '1', 16:27:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:28 INFO - 'NO_EM_RESTART': '1', 16:27:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:28 INFO - 'PWD': '/builds/slave/test', 16:27:28 INFO - 'SHELL': '/bin/bash', 16:27:28 INFO - 'SHLVL': '1', 16:27:28 INFO - 'TERM': 'linux', 16:27:28 INFO - 'TMOUT': '86400', 16:27:28 INFO - 'USER': 'cltbld', 16:27:28 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:28 INFO - '_': '/tools/buildbot/bin/python'} 16:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:29 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 16:27:29 INFO - Cleaning up... 16:27:29 INFO - Return code: 0 16:27:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:29 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')]} 16:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:29 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')]} 16:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:29 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:29 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 16:27:29 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 16:27:29 INFO - Using env: {'DISPLAY': ':0', 16:27:29 INFO - 'HOME': '/home/cltbld', 16:27:29 INFO - 'LANG': 'en_US.UTF-8', 16:27:29 INFO - 'LANGUAGE': 'en_US:en', 16:27:29 INFO - 'LOGNAME': 'cltbld', 16:27:29 INFO - 'MAIL': '/var/mail/cltbld', 16:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:29 INFO - 'MOZ_NO_REMOTE': '1', 16:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:29 INFO - 'NO_EM_RESTART': '1', 16:27:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:29 INFO - 'PWD': '/builds/slave/test', 16:27:29 INFO - 'SHELL': '/bin/bash', 16:27:29 INFO - 'SHLVL': '1', 16:27:29 INFO - 'TERM': 'linux', 16:27:29 INFO - 'TMOUT': '86400', 16:27:29 INFO - 'USER': 'cltbld', 16:27:29 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:29 INFO - '_': '/tools/buildbot/bin/python'} 16:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:29 INFO - Downloading/unpacking psutil>=3.1.1 16:27:29 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 16:27:29 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 16:27:29 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 16:27:29 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 16:27:29 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 16:27:29 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 16:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:31 INFO - Installing collected packages: psutil 16:27:31 INFO - Running setup.py install for psutil 16:27:31 INFO - building 'psutil._psutil_linux' extension 16:27:31 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 16:27:31 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 16:27:31 INFO - building 'psutil._psutil_posix' extension 16:27:31 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 16:27:31 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 16:27:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:31 INFO - Successfully installed psutil 16:27:31 INFO - Cleaning up... 16:27:31 INFO - Return code: 0 16:27:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:31 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')]} 16:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:31 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')]} 16:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:31 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:31 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 16:27:31 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 16:27:31 INFO - Using env: {'DISPLAY': ':0', 16:27:31 INFO - 'HOME': '/home/cltbld', 16:27:31 INFO - 'LANG': 'en_US.UTF-8', 16:27:31 INFO - 'LANGUAGE': 'en_US:en', 16:27:31 INFO - 'LOGNAME': 'cltbld', 16:27:31 INFO - 'MAIL': '/var/mail/cltbld', 16:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:31 INFO - 'MOZ_NO_REMOTE': '1', 16:27:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:31 INFO - 'NO_EM_RESTART': '1', 16:27:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:31 INFO - 'PWD': '/builds/slave/test', 16:27:31 INFO - 'SHELL': '/bin/bash', 16:27:31 INFO - 'SHLVL': '1', 16:27:31 INFO - 'TERM': 'linux', 16:27:31 INFO - 'TMOUT': '86400', 16:27:31 INFO - 'USER': 'cltbld', 16:27:31 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:31 INFO - '_': '/tools/buildbot/bin/python'} 16:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:34 INFO - Installing collected packages: mozsystemmonitor 16:27:34 INFO - Running setup.py install for mozsystemmonitor 16:27:34 INFO - Successfully installed mozsystemmonitor 16:27:34 INFO - Cleaning up... 16:27:34 INFO - Return code: 0 16:27:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:34 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')]} 16:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:34 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')]} 16:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:34 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:34 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 16:27:34 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 16:27:34 INFO - Using env: {'DISPLAY': ':0', 16:27:34 INFO - 'HOME': '/home/cltbld', 16:27:34 INFO - 'LANG': 'en_US.UTF-8', 16:27:34 INFO - 'LANGUAGE': 'en_US:en', 16:27:34 INFO - 'LOGNAME': 'cltbld', 16:27:34 INFO - 'MAIL': '/var/mail/cltbld', 16:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:34 INFO - 'MOZ_NO_REMOTE': '1', 16:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:34 INFO - 'NO_EM_RESTART': '1', 16:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:34 INFO - 'PWD': '/builds/slave/test', 16:27:34 INFO - 'SHELL': '/bin/bash', 16:27:34 INFO - 'SHLVL': '1', 16:27:34 INFO - 'TERM': 'linux', 16:27:34 INFO - 'TMOUT': '86400', 16:27:34 INFO - 'USER': 'cltbld', 16:27:34 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:34 INFO - '_': '/tools/buildbot/bin/python'} 16:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:34 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:34 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 16:27:34 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 16:27:34 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 16:27:34 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 16:27:34 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 16:27:34 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 16:27:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:36 INFO - warning: no files found matching '*.txt' 16:27:36 INFO - no previously-included directories found matching 'build' 16:27:36 INFO - no previously-included directories found matching 'dist' 16:27:36 INFO - no previously-included directories found matching '.git*' 16:27:36 INFO - Installing collected packages: jsonschema, functools32 16:27:36 INFO - Running setup.py install for functools32 16:27:36 INFO - warning: no files found matching '*.txt' 16:27:36 INFO - no previously-included directories found matching 'build' 16:27:36 INFO - no previously-included directories found matching 'dist' 16:27:36 INFO - no previously-included directories found matching '.git*' 16:27:36 INFO - Successfully installed jsonschema functools32 16:27:36 INFO - Cleaning up... 16:27:37 INFO - Return code: 0 16:27:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:27:37 INFO - Using env: {'DISPLAY': ':0', 16:27:37 INFO - 'HOME': '/home/cltbld', 16:27:37 INFO - 'LANG': 'en_US.UTF-8', 16:27:37 INFO - 'LANGUAGE': 'en_US:en', 16:27:37 INFO - 'LOGNAME': 'cltbld', 16:27:37 INFO - 'MAIL': '/var/mail/cltbld', 16:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:37 INFO - 'MOZ_NO_REMOTE': '1', 16:27:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:37 INFO - 'NO_EM_RESTART': '1', 16:27:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:37 INFO - 'PWD': '/builds/slave/test', 16:27:37 INFO - 'SHELL': '/bin/bash', 16:27:37 INFO - 'SHLVL': '1', 16:27:37 INFO - 'TERM': 'linux', 16:27:37 INFO - 'TMOUT': '86400', 16:27:37 INFO - 'USER': 'cltbld', 16:27:37 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:37 INFO - '_': '/tools/buildbot/bin/python'} 16:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:37 INFO - Cleaning up... 16:27:37 INFO - Return code: 0 16:27:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:27:37 INFO - Using env: {'DISPLAY': ':0', 16:27:37 INFO - 'HOME': '/home/cltbld', 16:27:37 INFO - 'LANG': 'en_US.UTF-8', 16:27:37 INFO - 'LANGUAGE': 'en_US:en', 16:27:37 INFO - 'LOGNAME': 'cltbld', 16:27:37 INFO - 'MAIL': '/var/mail/cltbld', 16:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:37 INFO - 'MOZ_NO_REMOTE': '1', 16:27:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:37 INFO - 'NO_EM_RESTART': '1', 16:27:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:37 INFO - 'PWD': '/builds/slave/test', 16:27:37 INFO - 'SHELL': '/bin/bash', 16:27:37 INFO - 'SHLVL': '1', 16:27:37 INFO - 'TERM': 'linux', 16:27:37 INFO - 'TMOUT': '86400', 16:27:37 INFO - 'USER': 'cltbld', 16:27:37 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:37 INFO - '_': '/tools/buildbot/bin/python'} 16:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:37 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:37 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 16:27:37 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 16:27:37 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 16:27:37 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 16:27:37 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 16:27:37 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 16:27:39 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:27:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:40 INFO - Downloading docopt-0.6.1.tar.gz 16:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:40 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:40 INFO - Running setup.py install for blobuploader 16:27:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:27:40 INFO - Running setup.py install for requests 16:27:40 INFO - Running setup.py install for docopt 16:27:40 INFO - Successfully installed blobuploader requests docopt 16:27:40 INFO - Cleaning up... 16:27:40 INFO - Return code: 0 16:27:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:40 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 16:27:40 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 16:27:40 INFO - Using env: {'DISPLAY': ':0', 16:27:40 INFO - 'HOME': '/home/cltbld', 16:27:40 INFO - 'LANG': 'en_US.UTF-8', 16:27:40 INFO - 'LANGUAGE': 'en_US:en', 16:27:40 INFO - 'LOGNAME': 'cltbld', 16:27:40 INFO - 'MAIL': '/var/mail/cltbld', 16:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:40 INFO - 'MOZ_NO_REMOTE': '1', 16:27:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:40 INFO - 'NO_EM_RESTART': '1', 16:27:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:40 INFO - 'PWD': '/builds/slave/test', 16:27:40 INFO - 'SHELL': '/bin/bash', 16:27:40 INFO - 'SHLVL': '1', 16:27:40 INFO - 'TERM': 'linux', 16:27:40 INFO - 'TMOUT': '86400', 16:27:40 INFO - 'USER': 'cltbld', 16:27:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:40 INFO - '_': '/tools/buildbot/bin/python'} 16:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:41 INFO - Running setup.py (path:/tmp/pip-0rvv2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:41 INFO - Running setup.py (path:/tmp/pip-omcw4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:41 INFO - Running setup.py (path:/tmp/pip-RfAgm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:41 INFO - Running setup.py (path:/tmp/pip-nR3wsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:41 INFO - Running setup.py (path:/tmp/pip-TZVosm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:41 INFO - Running setup.py (path:/tmp/pip-wlrf9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:41 INFO - Running setup.py (path:/tmp/pip-KGjdih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:41 INFO - Running setup.py (path:/tmp/pip-uFLmLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:41 INFO - Running setup.py (path:/tmp/pip-rsoPO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:41 INFO - Running setup.py (path:/tmp/pip-jywCFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:41 INFO - Running setup.py (path:/tmp/pip-weMQkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:41 INFO - Running setup.py (path:/tmp/pip-1ldnXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:41 INFO - Running setup.py (path:/tmp/pip-Xp4zx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:42 INFO - Running setup.py (path:/tmp/pip-nYWUM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:42 INFO - Running setup.py (path:/tmp/pip-ED98TD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:42 INFO - Running setup.py (path:/tmp/pip-R2YucT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:42 INFO - Running setup.py (path:/tmp/pip-ImRihd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:42 INFO - Running setup.py install for manifestparser 16:27:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:27:42 INFO - Running setup.py install for mozcrash 16:27:42 INFO - Running setup.py install for mozdebug 16:27:42 INFO - Running setup.py install for mozdevice 16:27:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:27:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:27:42 INFO - Running setup.py install for mozfile 16:27:42 INFO - Running setup.py install for mozhttpd 16:27:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:27:42 INFO - Running setup.py install for mozinfo 16:27:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Running setup.py install for mozInstall 16:27:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Running setup.py install for mozleak 16:27:43 INFO - Running setup.py install for mozlog 16:27:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Running setup.py install for moznetwork 16:27:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Running setup.py install for mozprocess 16:27:43 INFO - Running setup.py install for mozprofile 16:27:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Running setup.py install for mozrunner 16:27:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:27:43 INFO - Running setup.py install for mozscreenshot 16:27:43 INFO - Running setup.py install for moztest 16:27:44 INFO - Running setup.py install for mozversion 16:27:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:27:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:27:44 INFO - Cleaning up... 16:27:44 INFO - Return code: 0 16:27:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:44 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 16:27:44 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 16:27:44 INFO - Using env: {'DISPLAY': ':0', 16:27:44 INFO - 'HOME': '/home/cltbld', 16:27:44 INFO - 'LANG': 'en_US.UTF-8', 16:27:44 INFO - 'LANGUAGE': 'en_US:en', 16:27:44 INFO - 'LOGNAME': 'cltbld', 16:27:44 INFO - 'MAIL': '/var/mail/cltbld', 16:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:44 INFO - 'MOZ_NO_REMOTE': '1', 16:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:44 INFO - 'NO_EM_RESTART': '1', 16:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:44 INFO - 'PWD': '/builds/slave/test', 16:27:44 INFO - 'SHELL': '/bin/bash', 16:27:44 INFO - 'SHLVL': '1', 16:27:44 INFO - 'TERM': 'linux', 16:27:44 INFO - 'TMOUT': '86400', 16:27:44 INFO - 'USER': 'cltbld', 16:27:44 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:44 INFO - '_': '/tools/buildbot/bin/python'} 16:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:44 INFO - Running setup.py (path:/tmp/pip-KIz4OK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:44 INFO - Running setup.py (path:/tmp/pip-g3lq60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:44 INFO - Running setup.py (path:/tmp/pip-hYuaer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:44 INFO - Running setup.py (path:/tmp/pip-pE9LGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:44 INFO - Running setup.py (path:/tmp/pip-8N2ir8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:44 INFO - Running setup.py (path:/tmp/pip-jvDs8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:44 INFO - Running setup.py (path:/tmp/pip-hbjN5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:44 INFO - Running setup.py (path:/tmp/pip-cNFsys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:44 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)) 16:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:44 INFO - Running setup.py (path:/tmp/pip-Woms7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:45 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:45 INFO - Running setup.py (path:/tmp/pip-6XYv_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:45 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:45 INFO - Running setup.py (path:/tmp/pip-2n4mdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:45 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:45 INFO - Running setup.py (path:/tmp/pip-X6rQO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:45 INFO - Running setup.py (path:/tmp/pip-jioA7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:45 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:45 INFO - Running setup.py (path:/tmp/pip-K9kuHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:45 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:45 INFO - Running setup.py (path:/tmp/pip-Fu1ivw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:45 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:45 INFO - Running setup.py (path:/tmp/pip-WwdwET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:45 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)) 16:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:45 INFO - Running setup.py (path:/tmp/pip-5LTcvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:47 INFO - Downloading blessings-1.6.tar.gz 16:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:27:47 INFO - Installing collected packages: blessings 16:27:47 INFO - Running setup.py install for blessings 16:27:47 INFO - Successfully installed blessings 16:27:47 INFO - Cleaning up... 16:27:47 INFO - Return code: 0 16:27:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:27:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:48 INFO - Reading from file tmpfile_stdout 16:27:48 INFO - Current package versions: 16:27:48 INFO - argparse == 1.2.1 16:27:48 INFO - blessings == 1.6 16:27:48 INFO - blobuploader == 1.2.4 16:27:48 INFO - docopt == 0.6.1 16:27:48 INFO - functools32 == 3.2.3-2 16:27:48 INFO - jsonschema == 2.5.1 16:27:48 INFO - manifestparser == 1.1 16:27:48 INFO - mozInstall == 1.12 16:27:48 INFO - mozcrash == 1.0 16:27:48 INFO - mozdebug == 0.1 16:27:48 INFO - mozdevice == 0.49 16:27:48 INFO - mozfile == 1.2 16:27:48 INFO - mozhttpd == 0.7 16:27:48 INFO - mozinfo == 0.9 16:27:48 INFO - mozleak == 0.1 16:27:48 INFO - mozlog == 3.4 16:27:48 INFO - moznetwork == 0.27 16:27:48 INFO - mozprocess == 0.23 16:27:48 INFO - mozprofile == 0.28 16:27:48 INFO - mozrunner == 6.13 16:27:48 INFO - mozscreenshot == 0.1 16:27:48 INFO - mozsystemmonitor == 0.3 16:27:48 INFO - moztest == 0.8 16:27:48 INFO - mozversion == 1.4 16:27:48 INFO - psutil == 3.1.1 16:27:48 INFO - requests == 1.2.3 16:27:48 INFO - wsgiref == 0.1.2 16:27:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:48 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27:48 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 16:27:48 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 16:27:48 INFO - Using env: {'DISPLAY': ':0', 16:27:48 INFO - 'HOME': '/home/cltbld', 16:27:48 INFO - 'LANG': 'en_US.UTF-8', 16:27:48 INFO - 'LANGUAGE': 'en_US:en', 16:27:48 INFO - 'LOGNAME': 'cltbld', 16:27:48 INFO - 'MAIL': '/var/mail/cltbld', 16:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:48 INFO - 'MOZ_NO_REMOTE': '1', 16:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:48 INFO - 'NO_EM_RESTART': '1', 16:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:48 INFO - 'PWD': '/builds/slave/test', 16:27:48 INFO - 'SHELL': '/bin/bash', 16:27:48 INFO - 'SHLVL': '1', 16:27:48 INFO - 'TERM': 'linux', 16:27:48 INFO - 'TMOUT': '86400', 16:27:48 INFO - 'USER': 'cltbld', 16:27:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:48 INFO - '_': '/tools/buildbot/bin/python'} 16:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 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)) 16:27:48 INFO - Cleaning up... 16:27:48 INFO - Return code: 0 16:27:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2072a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2217c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, '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': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', '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 16:27: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'] in /builds/slave/test/build 16:27: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 16:27:48 INFO - Using env: {'DISPLAY': ':0', 16:27:48 INFO - 'HOME': '/home/cltbld', 16:27:48 INFO - 'LANG': 'en_US.UTF-8', 16:27:48 INFO - 'LANGUAGE': 'en_US:en', 16:27:48 INFO - 'LOGNAME': 'cltbld', 16:27:48 INFO - 'MAIL': '/var/mail/cltbld', 16:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:48 INFO - 'MOZ_NO_REMOTE': '1', 16:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:48 INFO - 'NO_EM_RESTART': '1', 16:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:48 INFO - 'PWD': '/builds/slave/test', 16:27:48 INFO - 'SHELL': '/bin/bash', 16:27:48 INFO - 'SHLVL': '1', 16:27:48 INFO - 'TERM': 'linux', 16:27:48 INFO - 'TMOUT': '86400', 16:27:48 INFO - 'USER': 'cltbld', 16:27:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:48 INFO - '_': '/tools/buildbot/bin/python'} 16:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:27:48 INFO - Cleaning up... 16:27:48 INFO - Return code: 0 16:27:48 INFO - Running post-action listener: _resource_record_post_action 16:27:48 INFO - Running post-action listener: _start_resource_monitoring 16:27:48 INFO - Starting resource monitoring. 16:27:48 INFO - [mozharness: 2017-01-07 00:27:48.601305Z] Finished create-virtualenv step (success) 16:27:48 INFO - [mozharness: 2017-01-07 00:27:48.601804Z] Running install step. 16:27:48 INFO - Running pre-action listener: _resource_record_pre_action 16:27:48 INFO - Running main action method: install 16:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:48 INFO - Reading from file tmpfile_stdout 16:27:48 INFO - Detecting whether we're running mozinstall >=1.0... 16:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:27:48 INFO - Reading from file tmpfile_stdout 16:27:48 INFO - Output received: 16:27:48 INFO - Usage: mozinstall [options] installer 16:27:48 INFO - Options: 16:27:48 INFO - -h, --help show this help message and exit 16:27:48 INFO - -d DEST, --destination=DEST 16:27:48 INFO - Directory to install application into. [default: 16:27:48 INFO - "/builds/slave/test"] 16:27:48 INFO - --app=APP Application being installed. [default: firefox] 16:27:48 INFO - mkdir: /builds/slave/test/build/application 16:27:48 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'] 16:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:28:05 INFO - Reading from file tmpfile_stdout 16:28:05 INFO - Output received: 16:28:05 INFO - /builds/slave/test/build/application/firefox/firefox 16:28:05 INFO - Running post-action listener: _resource_record_post_action 16:28:05 INFO - [mozharness: 2017-01-07 00:28:05.596248Z] Finished install step (success) 16:28:05 INFO - [mozharness: 2017-01-07 00:28:05.596535Z] Running run-tests step. 16:28:05 INFO - Running pre-action listener: _resource_record_pre_action 16:28:05 INFO - Running main action method: run_tests 16:28:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:05 INFO - Python 2.7.3 16:28:05 INFO - Return code: 0 16:28:05 INFO - grabbing minidump binary from tooltool 16:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:28:05 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 0x22153f0>, '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 0x2215cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22161a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:05 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 16:28:05 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 16:28:05 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 16:28:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:05 INFO - Return code: 0 16:28:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:28:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:28:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:05 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/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 16:28:05 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/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 16:28:05 INFO - Using env: {'DISPLAY': ':0', 16:28:05 INFO - 'HOME': '/home/cltbld', 16:28:05 INFO - 'LANG': 'en_US.UTF-8', 16:28:05 INFO - 'LANGUAGE': 'en_US:en', 16:28:05 INFO - 'LOGNAME': 'cltbld', 16:28:05 INFO - 'MAIL': '/var/mail/cltbld', 16:28:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:05 INFO - 'MOZ_NO_REMOTE': '1', 16:28:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:05 INFO - 'NO_EM_RESTART': '1', 16:28:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:05 INFO - 'PWD': '/builds/slave/test', 16:28:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:05 INFO - 'SHELL': '/bin/bash', 16:28:05 INFO - 'SHLVL': '1', 16:28:05 INFO - 'TERM': 'linux', 16:28:05 INFO - 'TMOUT': '86400', 16:28:05 INFO - 'USER': 'cltbld', 16:28:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1483748825.981598-1286120602', 16:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:05 INFO - '_': '/tools/buildbot/bin/python'} 16:28:05 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/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 16:28:05 INFO - mozversion application_buildid: 20170106190555 16:28:05 INFO - mozversion application_changeset: 1a815865294fbf234f166c075986f587b2b72a89 16:28:05 INFO - mozversion application_display_name: Nightly 16:28:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:05 INFO - mozversion application_name: Firefox 16:28:05 INFO - mozversion application_remotingname: firefox 16:28:05 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 16:28:05 INFO - mozversion application_vendor: Mozilla 16:28:05 INFO - mozversion application_version: 53.0a1 16:28:05 INFO - mozversion platform_buildid: 20170106190555 16:28:05 INFO - mozversion platform_changeset: 1a815865294fbf234f166c075986f587b2b72a89 16:28:05 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 16:28:05 INFO - mozversion platform_version: 53.0a1 16:28:05 INFO - using testdate: 1483748885 16:28:05 INFO - actual date: 1483748885 16:28:05 INFO - starting webserver on 'localhost:50163' 16:28:05 INFO - SUITE-START | Running 2 tests 16:28:05 INFO - TEST-START | dromaeo_css 16:28:05 INFO - operating with platform_type : linux_ 16:28:05 INFO - Initialising browser for dromaeo_css test... 16:28:05 INFO - TEST-INFO | started process 7355 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqxNbVW/profile http://localhost:50163/getInfo.html) 16:28:06 INFO - PROCESS | 7355 | 1483748886402 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 16:28:10 INFO - PROCESS | 7355 | __metrics Screen width/height:1600/1200 16:28:10 INFO - PROCESS | 7355 | colorDepth:24 16:28:10 INFO - PROCESS | 7355 | Browser inner width/height: 1024/697 16:28:10 INFO - PROCESS | 7355 | __metrics 16:28:10 INFO - PROCESS | 7355 | 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" 16:28:10 INFO - PROCESS | 7355 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:11 INFO - PROCESS | 7355 | console.error: PushService: 16:28:11 INFO - PROCESS | 7355 | stateChangeProcessEnqueue: Error transitioning state 16:28:11 INFO - PROCESS | 7355 | UnknownError 16:28:11 INFO - TEST-INFO | 7355: exit 0 16:28:11 INFO - Browser initialized. 16:28:11 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:11 INFO - TEST-INFO | started process 7438 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqxNbVW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:23 INFO - PROCESS | 7438 | RSS: Main: 151822336 16:28:23 INFO - PROCESS | 7438 | 16:31:28 INFO - PROCESS | 7438 | Cycle 1(1): loaded http://localhost:50163/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50163/tests/dromaeo/cssquery-ext.html) 16:31:29 INFO - PROCESS | 7438 | RSS: Main: 283664384 16:31:29 INFO - PROCESS | 7438 | 16:33:12 INFO - PROCESS | 7438 | ************************* 16:33:12 INFO - PROCESS | 7438 | A coding exception was thrown and uncaught in a Task. 16:33:12 INFO - PROCESS | 7438 | 16:33:12 INFO - PROCESS | 7438 | Full message: ReferenceError: fetch is not defined 16:33:12 INFO - PROCESS | 7438 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 16:33:12 INFO - PROCESS | 7438 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 16:33:12 INFO - PROCESS | 7438 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 16:33:12 INFO - PROCESS | 7438 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:33:12 INFO - PROCESS | 7438 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:33:12 INFO - PROCESS | 7438 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:33:12 INFO - PROCESS | 7438 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 16:33:12 INFO - PROCESS | 7438 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:33:12 INFO - PROCESS | 7438 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:33:12 INFO - PROCESS | 7438 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:33:12 INFO - PROCESS | 7438 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 16:33:12 INFO - PROCESS | 7438 | 16:33:12 INFO - PROCESS | 7438 | ************************* 16:34:32 INFO - PROCESS | 7438 | Cycle 1(1): loaded http://localhost:50163/tests/dromaeo/cssquery-ext.html (next: http://localhost:50163/tests/dromaeo/cssquery-jquery.html) 16:34:33 INFO - PROCESS | 7438 | RSS: Main: 239030272 16:34:33 INFO - PROCESS | 7438 | 16:37:35 INFO - PROCESS | 7438 | Cycle 1(1): loaded http://localhost:50163/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50163/tests/dromaeo/cssquery-mootools.html) 16:37:35 INFO - PROCESS | 7438 | RSS: Main: 183853056 16:37:35 INFO - PROCESS | 7438 | 16:40:38 INFO - PROCESS | 7438 | Cycle 1(1): loaded http://localhost:50163/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50163/tests/dromaeo/cssquery-prototype.html) 16:40:38 INFO - PROCESS | 7438 | RSS: Main: 199806976 16:40:38 INFO - PROCESS | 7438 | 16:43:41 INFO - PROCESS | 7438 | Cycle 1(1): loaded http://localhost:50163/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50163/tests/dromaeo/cssquery-yui.html) 16:43:42 INFO - PROCESS | 7438 | RSS: Main: 191102976 16:43:42 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | Cycle 1(1): loaded http://localhost:50163/tests/dromaeo/cssquery-yui.html (next: http://localhost:50163/tests/dromaeo/cssquery-dojo.html) 16:46:45 INFO - PROCESS | 7438 | RSS: Main: 277229568 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | __start_tp_report 16:46:45 INFO - PROCESS | 7438 | _x_x_mozilla_page_load 16:46:45 INFO - PROCESS | 7438 | _x_x_mozilla_page_load_details 16:46:45 INFO - PROCESS | 7438 | |i|pagename|runs| 16:46:45 INFO - PROCESS | 7438 | |0;dojo.html;2567.820252582319;2575.832570882894;2610.6997695265045;2612.8301660392085;2617.7120516743144;4619.214733495305;4649.418822647169;4726.314684370765;4760.143174228642;4773.236282194849;4694.671373003883;4709.952900470994;4725.173094708421;4746.976265118678;4757.881052973668;4225.746455212682;4266.679999000072;4304.677149213806;4339.566043395651;4420.182266280733;4286.228478873817;4363.3454981752675;4391.802368893407;4414.602685758281;4466.665000124987;4225.408442818008;4255.5957184067465;4364.978175109104;4370.453693288339;4440.7335559866515;4271.8718438446895;4277.550857160016;4312.116016216668;4313.4176886120285;4391.341298805173;4306.2248795217165;4306.310990241546;4310.676699247538;4312.137572485515;4338.891527711832;4669.066186762661;4694.2723877799;4696.60078893291;4699.765011749433;4735.195016847144;4938.604911607063;4965.726885021322;4968.652194346397;4978.77595508203;5009.874753131201;5039.09296326661;5053.646244762868;5054.620903432257;5056.873578160538;5106.08090543702;4737.786799594027;4740.194166991585;4746.311784791255;4776.832810851603;4784.2823576463115;5025.924611130836;5066.189409694432;5082.720450375265;5115.130427827278;5145.871353216199;4923.532264434873;4924.1875090610365;4925.088858561177;4968.056069346812;4975.0547395994645;4934.087193869145;4948.950510494849;5005.74971251436;5005.74971251436;5056.974715126475;4922.58158056562;4998.450170481244;5003.674761140514;5004.699718016931;5013.874653133628;4946.727929963887;4988.476209997971;4989.7006179628615;5034.546890779848;5039.420466646383;4323.913521729548;4326.069894972596;4329.393884856123;4359.324304732771;4366.454193225847;3716.405375140019;3740.8690695825517;3775.320442320354;3788.905277368088;3804.2962052020466;4379.4525684289465;4397.966477877696;4406.008648054162;4428.114377124523;4458.7324760514475;5132.743362831843;5134.358205224347;5136.43499215086;5147.536721695065;5190.039842629126;4305.870823875226;4426.468823741172;4430.224710675619;4432.069265454226;4468.128714900563;4533.161365147458;4536.138133754642;4556.612687921497;4610.585047345755;4639.350490931272;4769.594584460359;4770.332153498583;4777.856664300077;4784.282357646381;4826.32431459603;5097.592192624507;5101.540861322499;5107.948920510896;5108.463611320906;5153.819616313411;4617.676762626584;4646.488886222445;4694.2723877797625;4716.1510928033285;4742.407199100113;4968.950310496849;4975.452700203047;4976.303317535476;4979.900401992012;5020.322256495472;5081.898362032664;5083.339165908408;5093.847184584469;5095.796168153232;5125.231215317583;4762.666613337033;4783.449903261169;4823.614110871192;4875.0493653737185;4882.829100981448;4861.732604706775;4892.339534162985;4894.2658601210105;4896.6572339935865;4900.044491324157;4815.109204797123;4817.9036419272115;4831.033793241436;4841.951580484291;4844.4186697595105;4949.480304568113;4952.957823399392;4958.628102892226;4963.156263435152;5000.699958002531;4804.855854324376;4810.7113573185725;4813.229883218668;4828.444728856227;4844.297576851266 16:46:45 INFO - PROCESS | 7438 | |1;ext.html;532.837484567207;556.9108942569188;564.3369041376382;569.9399117641186;576.4120596991066;29481.852590737042;38572.61427385725;40769.59230407695;40824.59175408245;44775.77612111943;41472;41634.791826040826;41852.79073604628;41944;41974.79012604932;7378.299061589168;7644.923550764491;7812.53124812513;7813.296803287703;7817.2964433201005;7345.88981165283;9808.558614862348;10044.347117437379;10048;10280.485975701222;9995.250356223276;10021.59913603455;10034.498275086253;10047.397156170617;10070.194384449263;9824.90175098247;9889.851652225187;9896.10935015852;9897.505124743733;9960.501974901226;9709.90290097097;9769.462679552622;9772.071653192936;9775.51122443882;9778.706638800848;39226.21547569061;39296.80351598252;39587.8020609898;39611.80194099011;39620.801895990335;3557.768745031565;3628.365036118695;3631.055925459373;3654.28741395428;3661;28479.145625631263;28876;29181.56227656587;29279.70720292791;29409.558856616954;3477.600075991268;3478.878239261639;3665.816709164531;3684.244729830378;3699.8335074921692;235909;236653;236713;237068;237643;55060.72469637626;55980.44019559752;56244.718776405854;56274.43725562692;56306;58485;58737.706311468166;59197.40802591919;59271;59512;14410.207438590769;15100.320485578175;15102.924485377504;15113.47102851417;15121.546353609408;9726.2705297103;10087;10391.53238104287;11397.544098235978;11446.713832154097;17187.484375468757;17783.46649600514;17797.7330340045;17819.910900445673;17859.285628574973;6688.230853451915;6748.662566871637;6770.830729231809;6904.965475172692;6926.134233220848;823.3043078598574;827.0902007791382;829.4940086547201;830.2818062376001;849.5667209722963;27478.86260568724;28000.299992499942;28045.298867528476;28387.57418638722;28622.713772862422;974.8155990471611;978.3738407419257;986.6004268271362;997.805427941559;1048.7220886465093;14523.201223932574;15187.316570754343;15255.152863748339;15336.386544538316;15390.3074361654;336759;337193;337308;337445;338648;52521.737391312796;52564.73717631387;52583.73708131435;52753;52756.73621631867;1218.993905030487;1255.1026016398318;1257.911946163778;1258.1444617659897;1260.5188554688173;25457.36356591026;26467.205983820517;27192.72807271902;27749.306267342672;27814;42643.57356426396;44075;44080;44101.943689009604;44121.77939110412;274950;276616;276913;277930;278081.8287725726;34762.82618586891;35691.82154089213;36464.81767591145;37141.81429092837;37228.441573376964;36698.81650591837;37009.25981480409;37129.6287037137;37328.25343493169;37343.6265637351;32916.83541582373;33247;33364.83317583396;33931.83034084814;34059.318813624086;32993.67006329906;33031.33937321192;33324;33579.49630755492;33846.323073538886;31956.763026587916;33261;33416.832915835264;33670.15824604405;33744.662553375136;25748.485030299664;26069;29598.852005739835;31347.216319592193;31957.68042319547;23128.4217894554;23151.652725208798;23287.41781455477;23348.76651233534;23528.529429411657 16:46:45 INFO - PROCESS | 7438 | |2;jquery.html;361.6473937910539;371.6320842366055;382.0715660943906;383.7448097015484;400.94787677601926;17563.209655565497;17651.73522397165;18049.54876128096;18154.636907261847;18641.62716745664;10914.563417463312;11312.773744525104;11325.150613703985;11331.320120792758;11344.546218151285;4031.6774658027434;4639.6984196027315;5266.631335806495;5297.152455607104;5311.176767601009;5189.169732842746;5272.103742363788;5275.630705850592;5275.973620131894;5276.050310944029;4358.956410435902;4742.620590352763;4764.166270902596;4773.689710168828;5237.423883372826;4752.144613969484;4753.096911586805;4773.6897101688455;4788.114198873185;4791.233402655575;4599.908001839978;4653.185692503798;4658.650601204923;4692.272697731857;4707.246840505537;12629.936850315782;12663.746725065539;12745.936270318682;12763.680907977281;19992.300269490497;2182.297807709989;2192.6162921488676;2232.8548644338066;2285.06312411912;2302.781235782598;9089.545522723838;9611.90388096117;9647.179989700884;9851.556679949419;9853.50732463374;1715.4596302164778;1740.8346207110305;1757.7275522294065;1776.4670598820303;1808.312841120379;63367;66342.99999999952;76829;78694;78971;10309.896901031043;10319.84520232197;10437.68686939393;10699.62551310716;10751.51618177184;11949.10381721357;12413.565525206574;12438.81341779874;12983.285919274342;13475.663108422368;7023.683934222972;7093.751718689821;7217.133943926762;7319.194888562254;7336.633168341562;6819.454443644596;6837.213720422215;6950;7063.964680176669;7066.964665176642;4121.752694838259;4211.936820947688;4244.745315281031;4273.401723758676;4307.160103779733;1785.6071664234107;1810.4749622609295;1864.1052294898525;1899.8575106866763;1931.8551108666907;952.9475878826593;966.0918736387773;972.154225823538;981.2738573455591;983.593461350276;16562.668820966894;20087.598248034956;20113.59772804565;20214.393568192976;20241.696374554394;825.4881973176668;857.7769779857282;864.1617630898019;884.5798245833306;890.4301247201796;9342.392744471723;9849.621053052579;9855.211583073336;9915.55380007887;9920.206383489303;513890;532804;533237;536918;548356;91929;92412.53793730988;92824;93575;94758.5262073699;949.126803340928;949.8670186174079;975.020104794691;975.1662328708993;979.020979020979;14853.968822791803;15032.774508382165;15405.69188616199;15659;15789.763153552476;37177.4423383644;38019.619803802714;38632;39616;40648.187036259704;54067.45932540624;55660;56044.43955560555;56765.71617141888;56930.430695692514;5260.342457192851;7785.221477852169;7953;8096.149904259968;8199.344052475906;11671.474783634587;12045.457954391899;12186.146969712036;12601.369931503572;12639.115261931944;10091.69724908254;10723.839142412715;10758.246922715334;10764.569417223223;10823.160062789188;10286.897131028594;11091.5563377464;11152.21934464612;11192.552297908318;11210.719732006764;10794.56821727122;10940.070094042096;10975.560977560808;11130.109591232844;11579.710507237387;6865.931340686529;6868.313168683092;6951.617661028489;7156.892646610409;8000.59997000136;8334.70828521023;10665;10695.94652026735;10722.410267435365;10873.45632718345 16:46:45 INFO - PROCESS | 7438 | |3;mootools.html;1328.4486937920763;1328.8604696506866;1333.6665833541615;1333.873282038206;1343.3417625363568;6725.159355080615;7101.041359416484;7101.289871012896;7102.396296314807;7108.289171082889;6204.503639708823;6411.903821442682;6641.568298060634;6838.487113466485;7005.474589405802;2091.111277706972;2128.5849259394427;2129.574085182962;2129.606022885763;2131.8720876747366;2105.494681276498;2133.4453042208984;2133.893305334735;2138.8182004529635;2140.807327340539;1859.82331678491;1922.865399422041;2010.8190262876337;2093.633614117531;2111.556573119639;2113.3342996955907;2115.6932244824416;2117.163720330461;2117.5976564452703;2118.0362934864675;2069.968950465744;2084.3434318189875;2086.6974288728047;2088.5405210853587;2088.895555222233;6893.93106068943;6904.723811047552;6957.9652101739675;6985.89521157183;6987.231404545496;4251.91496170078;4399.824007039714;4401.955980440187;4402.119576084796;4408.074304396081;2643.814932954694;2757.8483183424905;2771.5565509518583;2775.9444811103867;2819.4361127774528;1046.3042077018774;1078.1967434261446;1107.2304748200004;1119.7424592343725;1154.5381847261078;9035.548222588845;9120.452772833585;9138.223251023672;9159.633614655406;9228.354015218938;3757.2109856929806;3937.9803100984313;3983.48214732083;4013.5986401359633;4028.9049694159457;7966;7977.800554986172;7989.0812556555575;8001.959990200128;8010.519368837889;4327.956720432819;4372.912541749147;4395.626371758436;4406.317020861771;4423.889402764892;3552.502649629054;3677.9632203678157;3878.0692633767694;4007;4012.1574469361176;3387.2378714788974;3417.624061353249;3474.583050034012;3502.5796904371637;3535.505029295901;1148.3339662995443;1186.7567148734488;1186.9228500147462;1187.857457105153;1188.6909403555137;799.4244144216156;820.913804050578;825.4551995682821;832.2343444031505;834.0741776627938;4725.64557658177;4755.524447555217;4763.332533781094;4825.565699087099;4873.366462359871;520.9476856749345;865.5628907401848;882.1311008656468;908.2416182487565;915.624593916491;3223.403670320998;3271.1822044488877;3278.5246139309666;3279.8032118072992;3385.3398587275246;7885.645145968445;8205.466644668077;8213.79465513355;8221.218984196492;8221.342292616577;8122.634481448349;8133.877991830127;8137.95931020353;8141.633626486822;8147.266745992889;6618.4705223582;6725.764598239038;6733.696983635748;6734.023566582818;6735.090762746966;4571.748553829571;4638.768061596974;4676.532346765296;4738.099761045391;4758.976205118952;7398.741044063432;7579.090509138939;7641.541507509567;7666.348360389206;7762;7703.268189521987;7772.92227077722;7820;7823.335016523659;7852.60736963161;5506.256655351636;5653.660780353192;6122.387761223842;6284.3087260402235;6297.559170857996;6189.5048396129105;6411.71147298382;6416.743330266924;6601.867962640817;6604.042413849868;5916.852078697895;5996.070609055603;6058.5758996871555;6084.269887613515;6245.96877015612;5902.1441890926435;5947.434993675594;5971.611845230046;5976;5976.8804623908145;5318.8936221276135;5344.706041167773;5352.4915133062295;5375.462453754593;5406.162044883049;4254.617084462413;4259.850905218303;4314.331278651814;4370.934435983527;4442.133783912106;4096.549379568305;4104.4664193654635;4139.172165566959;4220.240356735817;4293.248681480791 16:46:45 INFO - PROCESS | 7438 | |4;prototype.html;341.31736526946105;356.4830995057167;358.27449414934756;360.8394264552272;363.4602615116551;4891.241857512083;4904.852854414365;4923.310736496894;4959.429665588454;4985.501449855013;4586.8394606188795;4710.929336059962;4711.764411779406;4714.5285471452835;4849.151398505265;3770.868019619314;3831.808409579524;3836.2327534493074;3849.903752406184;3917.471141395918;3890.610938906115;3894.9805250973704;3899.532056153266;3902.824372903212;3913.7651740895494;3775.697944164474;3857.498525191735;3864.613538646141;3875.9418608720753;3878.3794592865147;3834.8112085253383;3837.9232415351535;3877.673395962428;3882.7476214046137;3897.9220415591813;3481.442969124928;3481.9825900870333;3484.8257587120797;3499.965000349989;3502.807345595991;4730.976345118287;4747.501512341189;4753.786079626425;4754.096721622881;4774.618030557582;4318.740875547477;4455.309427038814;4469.486009108961;4478.58924438804;4479.3281007848755;5039.546440820343;5048.697078175322;5054.469280725544;5062.265971434158;5105.157648987913;3828.5451528419303;3833.21419109082;3835.17543728097;3841.673457756094;3849.287881741887;5078.187490001619;5191.766370513336;5252.2142471559855;5303.337082864642;5305.469453054702;3904.882853514399;3905.1994341159993;3925.450436938831;3955.584663610337;3956.3274243378696;4015.9397609035886;4016.819243134066;4019.2162528307285;4019.2765302245293;4020.618041286073;4350.0854427532995;4472.932906006413;4497.6851620386915;4505.301678239878;4516.683832131719;5108.335916330928;5110.233464980282;5124.974375128175;5139.331886854685;5160.148575485078;1357.8254809589635;1362.1486570893192;1367.7264547090422;1368.6928982821382;1378.5244090788663;926.8285367207088;941.2470023980788;961.5000199896155;978.4422878959067;979.7060881735451;4288.506821715543;4300.419443375167;4300.8709738707885;4313.482382114166;4343.326784348368;5016.974915125474;5018.322526458881;5046.798128074909;5056.57019153376;5057.443681195065;4104.322786740214;4304;4307.504636966726;4322.827086916488;4326.178026175016;2696.177665811946;2698.311930457721;2703.2566044337573;2709.4962295925952;2713.782897368206;4689.62483001359;4689.85930422081;4750.358701575243;4757.14371413149;4762.214234651313;4910.607151427877;5028.547430731252;5072.873178170502;5143;5144.485551444825;4676.532346765296;4685.484596694361;4695.624350051988;4698.389209402756;4709.7174169549935;4995.900081998267;5009.096543313518;5040.57155141817;5051.671641343448;5066.468020857904;4782.856514304576;4909.95090049095;4947.381577302837;4953.851384458472;4958.84290333706;4633.953660463352;4690.835820746258;4699.530046995274;4700;4718.386609740712;3839.2513459876172;3849.82675779585;3880.96119038806;3892.3188442023097;3903.2974064668633;4783.545563171493;4978.726170060681;5045.133300678773;5054.721990290569;5073.518015788494;4828.275758636232;4836.733979631013;4851.563359297539;4880.41435027799;4885.267209918541;4872.4883887192755;4873.468613124201;4878.219484882402;4881.487443818348;4888.462269150319;4600.677952543289;4817.435098027505;4824.396950381202;4827.686200397104;4840.080384726891;4869.09921664479;4870.196417591058;4900.852974410774;4901.750053736417;4904.411470623548;4801;4810.089895613243;4819.060283244734;4825.565699087099;4847.927281090716 16:46:45 INFO - PROCESS | 7438 | |5;yui.html;612.8793926742068;618.2580902916501;718.6658203935169;742.5173637135862;748.2143749063483;12612.873871261285;13161.21032738034;13341.93329033356;13411.59765207043;13752.862471375283;11044.558217671303;11175.21773475855;11192.10463162947;11198.776024479506;11222.382768947726;667.7929841749049;812.2567850416862;825.0800357601262;826.7809030606894;827.1769589258666;689.1764341611776;792.1603100713262;811.2860682599334;822.9053658829251;823.8269963307713;881.0836729800999;881.8456770065245;884.2616415293238;884.460479107747;884.5488800711619;704.1655638068853;880.3001613717046;880.6917578847384;882.2192359761625;882.699882040103;526.1555203897766;609.893268677984;625.8216408323606;629.7418058595999;631.7788773929088;13509.797353039712;13527.661808454768;13549;13551.186928784304;13556.728865422636;12097.395130243453;12129.21160124598;12307.569235076819;12551.748965020739;12641.115121941468;11637.47631356591;11705.48707178242;11720.589779357682;11812.763744725058;11950.940245298718;4006.939895901564;4039.838406463738;4041.7777022263763;4075.4498142750645;4122.835086596532;56559.71720141373;56636.71681641607;57035;57877.42122578762;58163.709181454244;9747.268954828416;10041.949790251148;10075.412092673303;10118.898811011795;10262.332948358333;18898.338558150397;18999.81000190008;19151.329703460313;19324.903375483314;19385.612287754167;6511.38141876521;6540.6729663516635;6541.2150541934325;6639.568428052257;6738.427233685094;3322.103032181346;3348.983255083709;3412.590489141319;3428.177237463041;3447.0348302475345;2657.0833062593374;2702.7837772978114;2717.6059471376934;2738.2469820799042;2740.6163137160815;460.34400978605356;461.5961034095167;463.72408416991834;464.68401486988495;468.6742713813867;582.4874110782484;583.4020129367433;592.2744637818638;598.3806760003375;599.9268622935627;7566.508176968479;7679.961600191963;8222.917770822334;8235.629396677165;8370.12113728062;544.5616278895448;561.4383137011779;574.5977815529146;601.1823919469518;605.4429924469499;4014.9397759033636;4034.536028356718;4073.429719839225;4112.321466957978;4141.967509476001;50930.490695093315;51669.7416512915;52028.47971520312;64373.67813160998;66300;52888.735556321975;52936.735316323175;53215.46784532182;53833;55382.44617553773;49436.50563494319;50239.49760502421;50963;53122.2031669533;53733.73133134309;8781.385303028726;9316.394434361744;9323.813523729623;9332.486713230566;9342.392744471588;34565;34707;34957.30085398329;35057.298854023284;36193.095172620895;40086.997825054605;42116.36825447682;42780;42874.35688464738;44865;10046.196304295785;10223.591056357662;10265.332753371007;10573.154147668321;10711.089557387397;11752.23610465317;11874.8816299679;11887.76224475523;11911.225770324902;12468.438920248429;11109.513872021518;11194.107426602803;11372.772544549229;11451.312921224753;11485.138614603771;10961.451927403758;11092.445377731243;11194.720131996764;11294.096472282363;11815.82276265874;10539.57841686324;10594.417307048187;10753.89246107529;10898;11144.665660030212;9139.830614335164;9175.908240917772;9257;9307.674231401868;9423.434593924387;9176.6788162414;9421.95289023578;9502.382345147544;9566.186874115778;9596.088371604686 16:46:45 INFO - PROCESS | 7438 | __end_tp_report 16:46:45 INFO - PROCESS | 7438 | __start_cc_report 16:46:45 INFO - PROCESS | 7438 | _x_x_mozilla_cycle_collect,1385 16:46:45 INFO - PROCESS | 7438 | __end_cc_report 16:46:45 INFO - PROCESS | 7438 | __startTimestamp1483750005703__endTimestamp 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | ------- Summary: start ------- 16:46:45 INFO - PROCESS | 7438 | Number of tests: 6 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | [#0] dojo.html Cycles:165 Average:4661.63 Median:4777.86 stddev:485.49 (10.2%) stddev-sans-first:458.35 16:46:45 INFO - PROCESS | 7438 | Values: 2567.8 2575.8 2610.7 2612.8 2617.7 4619.2 4649.4 4726.3 4760.1 4773.2 4694.7 4710.0 4725.2 4747.0 4757.9 4225.7 4266.7 4304.7 4339.6 4420.2 4286.2 4363.3 4391.8 4414.6 4466.7 4225.4 4255.6 4365.0 4370.5 4440.7 4271.9 4277.6 4312.1 4313.4 4391.3 4306.2 4306.3 4310.7 4312.1 4338.9 4669.1 4694.3 4696.6 4699.8 4735.2 4938.6 4965.7 4968.7 4978.8 5009.9 5039.1 5053.6 5054.6 5056.9 5106.1 4737.8 4740.2 4746.3 4776.8 4784.3 5025.9 5066.2 5082.7 5115.1 5145.9 4923.5 4924.2 4925.1 4968.1 4975.1 4934.1 4949.0 5005.7 5005.7 5057.0 4922.6 4998.5 5003.7 5004.7 5013.9 4946.7 4988.5 4989.7 5034.5 5039.4 4323.9 4326.1 4329.4 4359.3 4366.5 3716.4 3740.9 3775.3 3788.9 3804.3 4379.5 4398.0 4406.0 4428.1 4458.7 5132.7 5134.4 5136.4 5147.5 5190.0 4305.9 4426.5 4430.2 4432.1 4468.1 4533.2 4536.1 4556.6 4610.6 4639.4 4769.6 4770.3 4777.9 4784.3 4826.3 5097.6 5101.5 5107.9 5108.5 5153.8 4617.7 4646.5 4694.3 4716.2 4742.4 4969.0 4975.5 4976.3 4979.9 5020.3 5081.9 5083.3 5093.8 5095.8 5125.2 4762.7 4783.4 4823.6 4875.0 4882.8 4861.7 4892.3 4894.3 4896.7 4900.0 4815.1 4817.9 4831.0 4842.0 4844.4 4949.5 4953.0 4958.6 4963.2 5000.7 4804.9 4810.7 4813.2 4828.4 4844.3 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | [#1] ext.html Cycles:180 Average:44874.16 Median:3518.32 stddev:75017.54 (2132.2%) stddev-sans-first:75154.11 16:46:45 INFO - PROCESS | 7438 | Values: 532.8 556.9 564.3 569.9 576.4 29481.9 38572.6 40769.6 40824.6 44775.8 41472.0 41634.8 41852.8 41944.0 41974.8 7378.3 7644.9 7812.5 7813.3 7817.3 7345.9 9808.6 10044.3 10048.0 10280.5 9995.3 10021.6 10034.5 10047.4 10070.2 9824.9 9889.9 9896.1 9897.5 9960.5 9709.9 9769.5 9772.1 9775.5 9778.7 39226.2 39296.8 39587.8 39611.8 39620.8 3557.8 3628.4 3631.1 3654.3 3661.0 28479.1 28876.0 29181.6 29279.7 29409.6 3477.6 3478.9 3665.8 3684.2 3699.8 235909.0 236653.0 236713.0 237068.0 237643.0 55060.7 55980.4 56244.7 56274.4 56306.0 58485.0 58737.7 59197.4 59271.0 59512.0 14410.2 15100.3 15102.9 15113.5 15121.5 9726.3 10087.0 10391.5 11397.5 11446.7 17187.5 17783.5 17797.7 17819.9 17859.3 6688.2 6748.7 6770.8 6905.0 6926.1 823.3 827.1 829.5 830.3 849.6 27478.9 28000.3 28045.3 28387.6 28622.7 974.8 978.4 986.6 997.8 1048.7 14523.2 15187.3 15255.2 15336.4 15390.3 336759.0 337193.0 337308.0 337445.0 338648.0 52521.7 52564.7 52583.7 52753.0 52756.7 1219.0 1255.1 1257.9 1258.1 1260.5 25457.4 26467.2 27192.7 27749.3 27814.0 42643.6 44075.0 44080.0 44101.9 44121.8 274950.0 276616.0 276913.0 277930.0 278081.8 34762.8 35691.8 36464.8 37141.8 37228.4 36698.8 37009.3 37129.6 37328.3 37343.6 32916.8 33247.0 33364.8 33931.8 34059.3 32993.7 33031.3 33324.0 33579.5 33846.3 31956.8 33261.0 33416.8 33670.2 33744.7 25748.5 26069.0 29598.9 31347.2 31957.7 23128.4 23151.7 23287.4 23348.8 23528.5 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | [#2] jquery.html Cycles:180 Average:28779.34 Median:4479.43 stddev:87771.67 (1959.4%) stddev-sans-first:87991.95 16:46:45 INFO - PROCESS | 7438 | Values: 361.6 371.6 382.1 383.7 400.9 17563.2 17651.7 18049.5 18154.6 18641.6 10914.6 11312.8 11325.2 11331.3 11344.5 4031.7 4639.7 5266.6 5297.2 5311.2 5189.2 5272.1 5275.6 5276.0 5276.1 4359.0 4742.6 4764.2 4773.7 5237.4 4752.1 4753.1 4773.7 4788.1 4791.2 4599.9 4653.2 4658.7 4692.3 4707.2 12629.9 12663.7 12745.9 12763.7 19992.3 2182.3 2192.6 2232.9 2285.1 2302.8 9089.5 9611.9 9647.2 9851.6 9853.5 1715.5 1740.8 1757.7 1776.5 1808.3 63367.0 66343.0 76829.0 78694.0 78971.0 10309.9 10319.8 10437.7 10699.6 10751.5 11949.1 12413.6 12438.8 12983.3 13475.7 7023.7 7093.8 7217.1 7319.2 7336.6 6819.5 6837.2 6950.0 7064.0 7067.0 4121.8 4211.9 4244.7 4273.4 4307.2 1785.6 1810.5 1864.1 1899.9 1931.9 952.9 966.1 972.2 981.3 983.6 16562.7 20087.6 20113.6 20214.4 20241.7 825.5 857.8 864.2 884.6 890.4 9342.4 9849.6 9855.2 9915.6 9920.2 513890.0 532804.0 533237.0 536918.0 548356.0 91929.0 92412.5 92824.0 93575.0 94758.5 949.1 949.9 975.0 975.2 979.0 14854.0 15032.8 15405.7 15659.0 15789.8 37177.4 38019.6 38632.0 39616.0 40648.2 54067.5 55660.0 56044.4 56765.7 56930.4 5260.3 7785.2 7953.0 8096.1 8199.3 11671.5 12045.5 12186.1 12601.4 12639.1 10091.7 10723.8 10758.2 10764.6 10823.2 10286.9 11091.6 11152.2 11192.6 11210.7 10794.6 10940.1 10975.6 11130.1 11579.7 6865.9 6868.3 6951.6 7156.9 8000.6 8334.7 10665.0 10695.9 10722.4 10873.5 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | [#3] mootools.html Cycles:180 Average:4600.46 Median:4757.25 stddev:2416.93 (50.8%) stddev-sans-first:2411.20 16:46:45 INFO - PROCESS | 7438 | Values: 1328.4 1328.9 1333.7 1333.9 1343.3 6725.2 7101.0 7101.3 7102.4 7108.3 6204.5 6411.9 6641.6 6838.5 7005.5 2091.1 2128.6 2129.6 2129.6 2131.9 2105.5 2133.4 2133.9 2138.8 2140.8 1859.8 1922.9 2010.8 2093.6 2111.6 2113.3 2115.7 2117.2 2117.6 2118.0 2070.0 2084.3 2086.7 2088.5 2088.9 6893.9 6904.7 6958.0 6985.9 6987.2 4251.9 4399.8 4402.0 4402.1 4408.1 2643.8 2757.8 2771.6 2775.9 2819.4 1046.3 1078.2 1107.2 1119.7 1154.5 9035.5 9120.5 9138.2 9159.6 9228.4 3757.2 3938.0 3983.5 4013.6 4028.9 7966.0 7977.8 7989.1 8002.0 8010.5 4328.0 4372.9 4395.6 4406.3 4423.9 3552.5 3678.0 3878.1 4007.0 4012.2 3387.2 3417.6 3474.6 3502.6 3535.5 1148.3 1186.8 1186.9 1187.9 1188.7 799.4 820.9 825.5 832.2 834.1 4725.6 4755.5 4763.3 4825.6 4873.4 520.9 865.6 882.1 908.2 915.6 3223.4 3271.2 3278.5 3279.8 3385.3 7885.6 8205.5 8213.8 8221.2 8221.3 8122.6 8133.9 8138.0 8141.6 8147.3 6618.5 6725.8 6733.7 6734.0 6735.1 4571.7 4638.8 4676.5 4738.1 4759.0 7398.7 7579.1 7641.5 7666.3 7762.0 7703.3 7772.9 7820.0 7823.3 7852.6 5506.3 5653.7 6122.4 6284.3 6297.6 6189.5 6411.7 6416.7 6601.9 6604.0 5916.9 5996.1 6058.6 6084.3 6246.0 5902.1 5947.4 5971.6 5976.0 5976.9 5318.9 5344.7 5352.5 5375.5 5406.2 4254.6 4259.9 4314.3 4370.9 4442.1 4096.5 4104.5 4139.2 4220.2 4293.2 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | [#4] prototype.html Cycles:180 Average:4185.22 Median:4699.77 stddev:1145.14 (24.4%) stddev-sans-first:1111.41 16:46:45 INFO - PROCESS | 7438 | Values: 341.3 356.5 358.3 360.8 363.5 4891.2 4904.9 4923.3 4959.4 4985.5 4586.8 4710.9 4711.8 4714.5 4849.2 3770.9 3831.8 3836.2 3849.9 3917.5 3890.6 3895.0 3899.5 3902.8 3913.8 3775.7 3857.5 3864.6 3875.9 3878.4 3834.8 3837.9 3877.7 3882.7 3897.9 3481.4 3482.0 3484.8 3500.0 3502.8 4731.0 4747.5 4753.8 4754.1 4774.6 4318.7 4455.3 4469.5 4478.6 4479.3 5039.5 5048.7 5054.5 5062.3 5105.2 3828.5 3833.2 3835.2 3841.7 3849.3 5078.2 5191.8 5252.2 5303.3 5305.5 3904.9 3905.2 3925.5 3955.6 3956.3 4015.9 4016.8 4019.2 4019.3 4020.6 4350.1 4472.9 4497.7 4505.3 4516.7 5108.3 5110.2 5125.0 5139.3 5160.1 1357.8 1362.1 1367.7 1368.7 1378.5 926.8 941.2 961.5 978.4 979.7 4288.5 4300.4 4300.9 4313.5 4343.3 5017.0 5018.3 5046.8 5056.6 5057.4 4104.3 4304.0 4307.5 4322.8 4326.2 2696.2 2698.3 2703.3 2709.5 2713.8 4689.6 4689.9 4750.4 4757.1 4762.2 4910.6 5028.5 5072.9 5143.0 5144.5 4676.5 4685.5 4695.6 4698.4 4709.7 4995.9 5009.1 5040.6 5051.7 5066.5 4782.9 4910.0 4947.4 4953.9 4958.8 4634.0 4690.8 4699.5 4700.0 4718.4 3839.3 3849.8 3881.0 3892.3 3903.3 4783.5 4978.7 5045.1 5054.7 5073.5 4828.3 4836.7 4851.6 4880.4 4885.3 4872.5 4873.5 4878.2 4881.5 4888.5 4600.7 4817.4 4824.4 4827.7 4840.1 4869.1 4870.2 4900.9 4901.8 4904.4 4801.0 4810.1 4819.1 4825.6 4847.9 16:46:45 INFO - PROCESS | 7438 | 16:46:45 INFO - PROCESS | 7438 | [#5] yui.html Cycles:180 Average:14086.52 Median:462.66 stddev:16966.61 (3667.2%) stddev-sans-first:16984.03 16:46:45 INFO - PROCESS | 7438 | Values: 612.9 618.3 718.7 742.5 748.2 12612.9 13161.2 13341.9 13411.6 13752.9 11044.6 11175.2 11192.1 11198.8 11222.4 667.8 812.3 825.1 826.8 827.2 689.2 792.2 811.3 822.9 823.8 881.1 881.8 884.3 884.5 884.5 704.2 880.3 880.7 882.2 882.7 526.2 609.9 625.8 629.7 631.8 13509.8 13527.7 13549.0 13551.2 13556.7 12097.4 12129.2 12307.6 12551.7 12641.1 11637.5 11705.5 11720.6 11812.8 11950.9 4006.9 4039.8 4041.8 4075.4 4122.8 56559.7 56636.7 57035.0 57877.4 58163.7 9747.3 10041.9 10075.4 10118.9 10262.3 18898.3 18999.8 19151.3 19324.9 19385.6 6511.4 6540.7 6541.2 6639.6 6738.4 3322.1 3349.0 3412.6 3428.2 3447.0 2657.1 2702.8 2717.6 2738.2 2740.6 460.3 461.6 463.7 464.7 468.7 582.5 583.4 592.3 598.4 599.9 7566.5 7680.0 8222.9 8235.6 8370.1 544.6 561.4 574.6 601.2 605.4 4014.9 4034.5 4073.4 4112.3 4142.0 50930.5 51669.7 52028.5 64373.7 66300.0 52888.7 52936.7 53215.5 53833.0 55382.4 49436.5 50239.5 50963.0 53122.2 53733.7 8781.4 9316.4 9323.8 9332.5 9342.4 34565.0 34707.0 34957.3 35057.3 36193.1 40087.0 42116.4 42780.0 42874.4 44865.0 10046.2 10223.6 10265.3 10573.2 10711.1 11752.2 11874.9 11887.8 11911.2 12468.4 11109.5 11194.1 11372.8 11451.3 11485.1 10961.5 11092.4 11194.7 11294.1 11815.8 10539.6 10594.4 10753.9 10898.0 11144.7 9139.8 9175.9 9257.0 9307.7 9423.4 9176.7 9422.0 9502.4 9566.2 9596.1 16:46:45 INFO - PROCESS | 7438 | -------- Summary: end -------- 16:46:45 INFO - PROCESS | 7438 | 16:46:46 INFO - TEST-INFO | 7438: exit 0 16:46:46 INFO - TEST-OK | dromaeo_css | took 1120685ms 16:46:46 INFO - TEST-START | kraken 16:46:46 INFO - operating with platform_type : linux_ 16:46:46 INFO - Initialising browser for kraken test... 16:46:46 INFO - TEST-INFO | started process 7739 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYiAhSD/profile http://localhost:50163/getInfo.html) 16:46:47 INFO - PROCESS | 7739 | 1483750007097 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 16:46:50 INFO - PROCESS | 7739 | __metrics Screen width/height:1600/1200 16:46:50 INFO - PROCESS | 7739 | colorDepth:24 16:46:50 INFO - PROCESS | 7739 | Browser inner width/height: 1024/697 16:46:50 INFO - PROCESS | 7739 | __metrics 16:46:51 INFO - PROCESS | 7739 | 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" 16:46:51 INFO - PROCESS | 7739 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:46:51 INFO - PROCESS | 7739 | console.error: PushService: 16:46:51 INFO - PROCESS | 7739 | stateChangeProcessEnqueue: Error transitioning state 16:46:51 INFO - PROCESS | 7739 | UnknownError 16:46:52 INFO - TEST-INFO | 7739: exit 0 16:46:52 INFO - Browser initialized. 16:46:52 INFO - Running cycle 1/1 for kraken test... 16:46:52 INFO - TEST-INFO | started process 7819 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYiAhSD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:04 INFO - PROCESS | 7819 | RSS: Main: 151900160 16:47:04 INFO - PROCESS | 7819 | 16:47:07 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50163/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:08 INFO - PROCESS | 7819 | RSS: Main: 206274560 16:47:08 INFO - PROCESS | 7819 | 16:47:10 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50163/tests/kraken/driver.html?testName=audio-dft) 16:47:11 INFO - PROCESS | 7819 | RSS: Main: 185987072 16:47:11 INFO - PROCESS | 7819 | 16:47:14 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50163/tests/kraken/driver.html?testName=audio-fft) 16:47:15 INFO - PROCESS | 7819 | RSS: Main: 268603392 16:47:15 INFO - PROCESS | 7819 | 16:47:17 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50163/tests/kraken/driver.html?testName=audio-oscillator) 16:47:18 INFO - PROCESS | 7819 | RSS: Main: 179163136 16:47:18 INFO - PROCESS | 7819 | 16:47:20 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50163/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:20 INFO - PROCESS | 7819 | RSS: Main: 183242752 16:47:20 INFO - PROCESS | 7819 | 16:47:23 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50163/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:24 INFO - PROCESS | 7819 | RSS: Main: 222322688 16:47:24 INFO - PROCESS | 7819 | 16:47:28 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50163/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:28 INFO - PROCESS | 7819 | RSS: Main: 216190976 16:47:28 INFO - PROCESS | 7819 | 16:47:31 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50163/tests/kraken/driver.html?testName=json-parse-financial) 16:47:32 INFO - PROCESS | 7819 | RSS: Main: 217706496 16:47:32 INFO - PROCESS | 7819 | 16:47:33 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50163/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:34 INFO - PROCESS | 7819 | RSS: Main: 181608448 16:47:34 INFO - PROCESS | 7819 | 16:47:36 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:36 INFO - PROCESS | 7819 | RSS: Main: 189718528 16:47:36 INFO - PROCESS | 7819 | 16:47:39 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:39 INFO - PROCESS | 7819 | RSS: Main: 196943872 16:47:39 INFO - PROCESS | 7819 | 16:47:42 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:43 INFO - PROCESS | 7819 | RSS: Main: 205819904 16:47:43 INFO - PROCESS | 7819 | 16:47:46 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:46 INFO - PROCESS | 7819 | RSS: Main: 202948608 16:47:46 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | Cycle 1(1): loaded http://localhost:50163/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50163/tests/kraken/driver.html?testName=ai-astar) 16:47:48 INFO - PROCESS | 7819 | RSS: Main: 254394368 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | __start_tp_report 16:47:48 INFO - PROCESS | 7819 | _x_x_mozilla_page_load 16:47:48 INFO - PROCESS | 7819 | _x_x_mozilla_page_load_details 16:47:48 INFO - PROCESS | 7819 | |i|pagename|runs| 16:47:48 INFO - PROCESS | 7819 | |0;ai-astar;114;117;115;116;114;114;114;113;115;114 16:47:48 INFO - PROCESS | 7819 | |1;audio-beat-detection;147;129;121;165;125;128;123;122;149;124 16:47:48 INFO - PROCESS | 7819 | |2;audio-dft;163;161;159;162;162;161;162;164;162;161 16:47:48 INFO - PROCESS | 7819 | |3;audio-fft;75;78;77;102;77;77;76;77;93;76 16:47:48 INFO - PROCESS | 7819 | |4;audio-oscillator;85;85;102;84;84;84;104;84;85;106 16:47:48 INFO - PROCESS | 7819 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;118;117 16:47:48 INFO - PROCESS | 7819 | |6;imaging-darkroom;149;149;149;149;148;149;148;148;148;149 16:47:48 INFO - PROCESS | 7819 | |7;imaging-desaturate;95;95;95;96;95;95;96;96;96;96 16:47:48 INFO - PROCESS | 7819 | |8;json-parse-financial;60;59;59;59;61;61;61;66;59;60 16:47:48 INFO - PROCESS | 7819 | |9;json-stringify-tinderbox;66;68;68;67;67;66;67;67;68;68 16:47:48 INFO - PROCESS | 7819 | |10;stanford-crypto-aes;84;84;83;89;83;84;90;84;84;87 16:47:48 INFO - PROCESS | 7819 | |11;stanford-crypto-ccm;133;134;132;134;141;132;132;138;173;143 16:47:48 INFO - PROCESS | 7819 | |12;stanford-crypto-pbkdf2;177;179;178;176;174;175;176;175;174;177 16:47:48 INFO - PROCESS | 7819 | |13;stanford-crypto-sha256-iterative;76;67;67;68;67;71;68;68;66;65 16:47:48 INFO - PROCESS | 7819 | __end_tp_report 16:47:48 INFO - PROCESS | 7819 | __start_cc_report 16:47:48 INFO - PROCESS | 7819 | _x_x_mozilla_cycle_collect,1858 16:47:48 INFO - PROCESS | 7819 | __end_cc_report 16:47:48 INFO - PROCESS | 7819 | __startTimestamp1483750068880__endTimestamp 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | ------- Summary: start ------- 16:47:48 INFO - PROCESS | 7819 | Number of tests: 14 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#0] ai-astar Cycles:10 Average:114.60 Median:114.50 stddev:1.17 (1.0%) stddev-sans-first:1.22 16:47:48 INFO - PROCESS | 7819 | Values: 114.0 117.0 115.0 116.0 114.0 114.0 114.0 113.0 115.0 114.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:128.50 stddev:15.00 (11.7%) stddev-sans-first:15.07 16:47:48 INFO - PROCESS | 7819 | Values: 147.0 129.0 121.0 165.0 125.0 128.0 123.0 122.0 149.0 124.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.34 (0.8%) stddev-sans-first:1.33 16:47:48 INFO - PROCESS | 7819 | Values: 163.0 161.0 159.0 162.0 162.0 161.0 162.0 164.0 162.0 161.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#3] audio-fft Cycles:10 Average:80.80 Median:77.00 stddev:9.09 (11.8%) stddev-sans-first:9.40 16:47:48 INFO - PROCESS | 7819 | Values: 75.0 78.0 77.0 102.0 77.0 77.0 76.0 77.0 93.0 76.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:84.00 stddev:9.51 (11.3%) stddev-sans-first:9.89 16:47:48 INFO - PROCESS | 7819 | Values: 85.0 85.0 102.0 84.0 84.0 84.0 104.0 84.0 85.0 106.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:47:48 INFO - PROCESS | 7819 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:149.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 16:47:48 INFO - PROCESS | 7819 | Values: 149.0 149.0 149.0 149.0 148.0 149.0 148.0 148.0 148.0 149.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:47:48 INFO - PROCESS | 7819 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:60.50 stddev:2.12 (3.5%) stddev-sans-first:2.24 16:47:48 INFO - PROCESS | 7819 | Values: 60.0 59.0 59.0 59.0 61.0 61.0 61.0 66.0 59.0 60.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:67.50 stddev:0.79 (1.2%) stddev-sans-first:0.71 16:47:48 INFO - PROCESS | 7819 | Values: 66.0 68.0 68.0 67.0 67.0 66.0 67.0 67.0 68.0 68.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.00 stddev:2.53 (3.0%) stddev-sans-first:2.65 16:47:48 INFO - PROCESS | 7819 | Values: 84.0 84.0 83.0 89.0 83.0 84.0 90.0 84.0 84.0 87.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:136.00 stddev:12.51 (9.2%) stddev-sans-first:13.07 16:47:48 INFO - PROCESS | 7819 | Values: 133.0 134.0 132.0 134.0 141.0 132.0 132.0 138.0 173.0 143.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:176.50 stddev:1.66 (0.9%) stddev-sans-first:1.73 16:47:48 INFO - PROCESS | 7819 | Values: 177.0 179.0 178.0 176.0 174.0 175.0 176.0 175.0 174.0 177.0 16:47:48 INFO - PROCESS | 7819 | 16:47:48 INFO - PROCESS | 7819 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:3.13 (4.6%) stddev-sans-first:1.67 16:47:48 INFO - PROCESS | 7819 | Values: 76.0 67.0 67.0 68.0 67.0 71.0 68.0 68.0 66.0 65.0 16:47:48 INFO - PROCESS | 7819 | -------- Summary: end -------- 16:47:48 INFO - PROCESS | 7819 | 16:47:49 INFO - TEST-INFO | 7819: exit 0 16:47:49 INFO - TEST-OK | kraken | took 63416ms 16:47:49 INFO - SUITE-END | took 1184s 16:47:50 INFO - Completed test suite (00:19:44) 16:47:50 INFO - javascript benchmark 16:47:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.820252582319, 2575.832570882894, 2610.6997695265045, 2612.8301660392085, 2617.7120516743144, 4619.214733495305, 4649.418822647169, 4726.314684370765, 4760.143174228642, 4773.236282194849, 4694.671373003883, 4709.952900470994, 4725.173094708421, 4746.976265118678, 4757.881052973668, 4225.746455212682, 4266.679999000072, 4304.677149213806, 4339.566043395651, 4420.182266280733, 4286.228478873817, 4363.3454981752675, 4391.802368893407, 4414.602685758281, 4466.665000124987, 4225.408442818008, 4255.5957184067465, 4364.978175109104, 4370.453693288339, 4440.7335559866515, 4271.8718438446895, 4277.550857160016, 4312.116016216668, 4313.4176886120285, 4391.341298805173, 4306.2248795217165, 4306.310990241546, 4310.676699247538, 4312.137572485515, 4338.891527711832, 4669.066186762661, 4694.2723877799, 4696.60078893291, 4699.765011749433, 4735.195016847144, 4938.604911607063, 4965.726885021322, 4968.652194346397, 4978.77595508203, 5009.874753131201, 5039.09296326661, 5053.646244762868, 5054.620903432257, 5056.873578160538, 5106.08090543702, 4737.786799594027, 4740.194166991585, 4746.311784791255, 4776.832810851603, 4784.2823576463115, 5025.924611130836, 5066.189409694432, 5082.720450375265, 5115.130427827278, 5145.871353216199, 4923.532264434873, 4924.1875090610365, 4925.088858561177, 4968.056069346812, 4975.0547395994645, 4934.087193869145, 4948.950510494849, 5005.74971251436, 5005.74971251436, 5056.974715126475, 4922.58158056562, 4998.450170481244, 5003.674761140514, 5004.699718016931, 5013.874653133628, 4946.727929963887, 4988.476209997971, 4989.7006179628615, 5034.546890779848, 5039.420466646383, 4323.913521729548, 4326.069894972596, 4329.393884856123, 4359.324304732771, 4366.454193225847, 3716.405375140019, 3740.8690695825517, 3775.320442320354, 3788.905277368088, 3804.2962052020466, 4379.4525684289465, 4397.966477877696, 4406.008648054162, 4428.114377124523, 4458.7324760514475, 5132.743362831843, 5134.358205224347, 5136.43499215086, 5147.536721695065, 5190.039842629126, 4305.870823875226, 4426.468823741172, 4430.224710675619, 4432.069265454226, 4468.128714900563, 4533.161365147458, 4536.138133754642, 4556.612687921497, 4610.585047345755, 4639.350490931272, 4769.594584460359, 4770.332153498583, 4777.856664300077, 4784.282357646381, 4826.32431459603, 5097.592192624507, 5101.540861322499, 5107.948920510896, 5108.463611320906, 5153.819616313411, 4617.676762626584, 4646.488886222445, 4694.2723877797625, 4716.1510928033285, 4742.407199100113, 4968.950310496849, 4975.452700203047, 4976.303317535476, 4979.900401992012, 5020.322256495472, 5081.898362032664, 5083.339165908408, 5093.847184584469, 5095.796168153232, 5125.231215317583, 4762.666613337033, 4783.449903261169, 4823.614110871192, 4875.0493653737185, 4882.829100981448, 4861.732604706775, 4892.339534162985, 4894.2658601210105, 4896.6572339935865, 4900.044491324157, 4815.109204797123, 4817.9036419272115, 4831.033793241436, 4841.951580484291, 4844.4186697595105, 4949.480304568113, 4952.957823399392, 4958.628102892226, 4963.156263435152, 5000.699958002531, 4804.855854324376, 4810.7113573185725, 4813.229883218668, 4828.444728856227, 4844.297576851266], "value": 4630.323131189622, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.837484567207, 556.9108942569188, 564.3369041376382, 569.9399117641186, 576.4120596991066, 29481.852590737042, 38572.61427385725, 40769.59230407695, 40824.59175408245, 44775.77612111943, 41472.0, 41634.791826040826, 41852.79073604628, 41944.0, 41974.79012604932, 7378.299061589168, 7644.923550764491, 7812.53124812513, 7813.296803287703, 7817.2964433201005, 7345.88981165283, 9808.558614862348, 10044.347117437379, 10048.0, 10280.485975701222, 9995.250356223276, 10021.59913603455, 10034.498275086253, 10047.397156170617, 10070.194384449263, 9824.90175098247, 9889.851652225187, 9896.10935015852, 9897.505124743733, 9960.501974901226, 9709.90290097097, 9769.462679552622, 9772.071653192936, 9775.51122443882, 9778.706638800848, 39226.21547569061, 39296.80351598252, 39587.8020609898, 39611.80194099011, 39620.801895990335, 3557.768745031565, 3628.365036118695, 3631.055925459373, 3654.28741395428, 3661.0, 28479.145625631263, 28876.0, 29181.56227656587, 29279.70720292791, 29409.558856616954, 3477.600075991268, 3478.878239261639, 3665.816709164531, 3684.244729830378, 3699.8335074921692, 235909.0, 236653.0, 236713.0, 237068.0, 237643.0, 55060.72469637626, 55980.44019559752, 56244.718776405854, 56274.43725562692, 56306.0, 58485.0, 58737.706311468166, 59197.40802591919, 59271.0, 59512.0, 14410.207438590769, 15100.320485578175, 15102.924485377504, 15113.47102851417, 15121.546353609408, 9726.2705297103, 10087.0, 10391.53238104287, 11397.544098235978, 11446.713832154097, 17187.484375468757, 17783.46649600514, 17797.7330340045, 17819.910900445673, 17859.285628574973, 6688.230853451915, 6748.662566871637, 6770.830729231809, 6904.965475172692, 6926.134233220848, 823.3043078598574, 827.0902007791382, 829.4940086547201, 830.2818062376001, 849.5667209722963, 27478.86260568724, 28000.299992499942, 28045.298867528476, 28387.57418638722, 28622.713772862422, 974.8155990471611, 978.3738407419257, 986.6004268271362, 997.805427941559, 1048.7220886465093, 14523.201223932574, 15187.316570754343, 15255.152863748339, 15336.386544538316, 15390.3074361654, 336759.0, 337193.0, 337308.0, 337445.0, 338648.0, 52521.737391312796, 52564.73717631387, 52583.73708131435, 52753.0, 52756.73621631867, 1218.993905030487, 1255.1026016398318, 1257.911946163778, 1258.1444617659897, 1260.5188554688173, 25457.36356591026, 26467.205983820517, 27192.72807271902, 27749.306267342672, 27814.0, 42643.57356426396, 44075.0, 44080.0, 44101.943689009604, 44121.77939110412, 274950.0, 276616.0, 276913.0, 277930.0, 278081.8287725726, 34762.82618586891, 35691.82154089213, 36464.81767591145, 37141.81429092837, 37228.441573376964, 36698.81650591837, 37009.25981480409, 37129.6287037137, 37328.25343493169, 37343.6265637351, 32916.83541582373, 33247.0, 33364.83317583396, 33931.83034084814, 34059.318813624086, 32993.67006329906, 33031.33937321192, 33324.0, 33579.49630755492, 33846.323073538886, 31956.763026587916, 33261.0, 33416.832915835264, 33670.15824604405, 33744.662553375136, 25748.485030299664, 26069.0, 29598.852005739835, 31347.216319592193, 31957.68042319547, 23128.4217894554, 23151.652725208798, 23287.41781455477, 23348.76651233534, 23528.529429411657], "value": 17893.874833648493, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.6473937910539, 371.6320842366055, 382.0715660943906, 383.7448097015484, 400.94787677601926, 17563.209655565497, 17651.73522397165, 18049.54876128096, 18154.636907261847, 18641.62716745664, 10914.563417463312, 11312.773744525104, 11325.150613703985, 11331.320120792758, 11344.546218151285, 4031.6774658027434, 4639.6984196027315, 5266.631335806495, 5297.152455607104, 5311.176767601009, 5189.169732842746, 5272.103742363788, 5275.630705850592, 5275.973620131894, 5276.050310944029, 4358.956410435902, 4742.620590352763, 4764.166270902596, 4773.689710168828, 5237.423883372826, 4752.144613969484, 4753.096911586805, 4773.6897101688455, 4788.114198873185, 4791.233402655575, 4599.908001839978, 4653.185692503798, 4658.650601204923, 4692.272697731857, 4707.246840505537, 12629.936850315782, 12663.746725065539, 12745.936270318682, 12763.680907977281, 19992.300269490497, 2182.297807709989, 2192.6162921488676, 2232.8548644338066, 2285.06312411912, 2302.781235782598, 9089.545522723838, 9611.90388096117, 9647.179989700884, 9851.556679949419, 9853.50732463374, 1715.4596302164778, 1740.8346207110305, 1757.7275522294065, 1776.4670598820303, 1808.312841120379, 63367.0, 66342.99999999952, 76829.0, 78694.0, 78971.0, 10309.896901031043, 10319.84520232197, 10437.68686939393, 10699.62551310716, 10751.51618177184, 11949.10381721357, 12413.565525206574, 12438.81341779874, 12983.285919274342, 13475.663108422368, 7023.683934222972, 7093.751718689821, 7217.133943926762, 7319.194888562254, 7336.633168341562, 6819.454443644596, 6837.213720422215, 6950.0, 7063.964680176669, 7066.964665176642, 4121.752694838259, 4211.936820947688, 4244.745315281031, 4273.401723758676, 4307.160103779733, 1785.6071664234107, 1810.4749622609295, 1864.1052294898525, 1899.8575106866763, 1931.8551108666907, 952.9475878826593, 966.0918736387773, 972.154225823538, 981.2738573455591, 983.593461350276, 16562.668820966894, 20087.598248034956, 20113.59772804565, 20214.393568192976, 20241.696374554394, 825.4881973176668, 857.7769779857282, 864.1617630898019, 884.5798245833306, 890.4301247201796, 9342.392744471723, 9849.621053052579, 9855.211583073336, 9915.55380007887, 9920.206383489303, 513890.0, 532804.0, 533237.0, 536918.0, 548356.0, 91929.0, 92412.53793730988, 92824.0, 93575.0, 94758.5262073699, 949.126803340928, 949.8670186174079, 975.020104794691, 975.1662328708993, 979.020979020979, 14853.968822791803, 15032.774508382165, 15405.69188616199, 15659.0, 15789.763153552476, 37177.4423383644, 38019.619803802714, 38632.0, 39616.0, 40648.187036259704, 54067.45932540624, 55660.0, 56044.43955560555, 56765.71617141888, 56930.430695692514, 5260.342457192851, 7785.221477852169, 7953.0, 8096.149904259968, 8199.344052475906, 11671.474783634587, 12045.457954391899, 12186.146969712036, 12601.369931503572, 12639.115261931944, 10091.69724908254, 10723.839142412715, 10758.246922715334, 10764.569417223223, 10823.160062789188, 10286.897131028594, 11091.5563377464, 11152.21934464612, 11192.552297908318, 11210.719732006764, 10794.56821727122, 10940.070094042096, 10975.560977560808, 11130.109591232844, 11579.710507237387, 6865.931340686529, 6868.313168683092, 6951.617661028489, 7156.892646610409, 8000.59997000136, 8334.70828521023, 10665.0, 10695.94652026735, 10722.410267435365, 10873.45632718345], "value": 8244.430062794907, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.4486937920763, 1328.8604696506866, 1333.6665833541615, 1333.873282038206, 1343.3417625363568, 6725.159355080615, 7101.041359416484, 7101.289871012896, 7102.396296314807, 7108.289171082889, 6204.503639708823, 6411.903821442682, 6641.568298060634, 6838.487113466485, 7005.474589405802, 2091.111277706972, 2128.5849259394427, 2129.574085182962, 2129.606022885763, 2131.8720876747366, 2105.494681276498, 2133.4453042208984, 2133.893305334735, 2138.8182004529635, 2140.807327340539, 1859.82331678491, 1922.865399422041, 2010.8190262876337, 2093.633614117531, 2111.556573119639, 2113.3342996955907, 2115.6932244824416, 2117.163720330461, 2117.5976564452703, 2118.0362934864675, 2069.968950465744, 2084.3434318189875, 2086.6974288728047, 2088.5405210853587, 2088.895555222233, 6893.93106068943, 6904.723811047552, 6957.9652101739675, 6985.89521157183, 6987.231404545496, 4251.91496170078, 4399.824007039714, 4401.955980440187, 4402.119576084796, 4408.074304396081, 2643.814932954694, 2757.8483183424905, 2771.5565509518583, 2775.9444811103867, 2819.4361127774528, 1046.3042077018774, 1078.1967434261446, 1107.2304748200004, 1119.7424592343725, 1154.5381847261078, 9035.548222588845, 9120.452772833585, 9138.223251023672, 9159.633614655406, 9228.354015218938, 3757.2109856929806, 3937.9803100984313, 3983.48214732083, 4013.5986401359633, 4028.9049694159457, 7966.0, 7977.800554986172, 7989.0812556555575, 8001.959990200128, 8010.519368837889, 4327.956720432819, 4372.912541749147, 4395.626371758436, 4406.317020861771, 4423.889402764892, 3552.502649629054, 3677.9632203678157, 3878.0692633767694, 4007.0, 4012.1574469361176, 3387.2378714788974, 3417.624061353249, 3474.583050034012, 3502.5796904371637, 3535.505029295901, 1148.3339662995443, 1186.7567148734488, 1186.9228500147462, 1187.857457105153, 1188.6909403555137, 799.4244144216156, 820.913804050578, 825.4551995682821, 832.2343444031505, 834.0741776627938, 4725.64557658177, 4755.524447555217, 4763.332533781094, 4825.565699087099, 4873.366462359871, 520.9476856749345, 865.5628907401848, 882.1311008656468, 908.2416182487565, 915.624593916491, 3223.403670320998, 3271.1822044488877, 3278.5246139309666, 3279.8032118072992, 3385.3398587275246, 7885.645145968445, 8205.466644668077, 8213.79465513355, 8221.218984196492, 8221.342292616577, 8122.634481448349, 8133.877991830127, 8137.95931020353, 8141.633626486822, 8147.266745992889, 6618.4705223582, 6725.764598239038, 6733.696983635748, 6734.023566582818, 6735.090762746966, 4571.748553829571, 4638.768061596974, 4676.532346765296, 4738.099761045391, 4758.976205118952, 7398.741044063432, 7579.090509138939, 7641.541507509567, 7666.348360389206, 7762.0, 7703.268189521987, 7772.92227077722, 7820.0, 7823.335016523659, 7852.60736963161, 5506.256655351636, 5653.660780353192, 6122.387761223842, 6284.3087260402235, 6297.559170857996, 6189.5048396129105, 6411.71147298382, 6416.743330266924, 6601.867962640817, 6604.042413849868, 5916.852078697895, 5996.070609055603, 6058.5758996871555, 6084.269887613515, 6245.96877015612, 5902.1441890926435, 5947.434993675594, 5971.611845230046, 5976.0, 5976.8804623908145, 5318.8936221276135, 5344.706041167773, 5352.4915133062295, 5375.462453754593, 5406.162044883049, 4254.617084462413, 4259.850905218303, 4314.331278651814, 4370.934435983527, 4442.133783912106, 4096.549379568305, 4104.4664193654635, 4139.172165566959, 4220.240356735817, 4293.248681480791], "value": 3806.545953536823, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.31736526946105, 356.4830995057167, 358.27449414934756, 360.8394264552272, 363.4602615116551, 4891.241857512083, 4904.852854414365, 4923.310736496894, 4959.429665588454, 4985.501449855013, 4586.8394606188795, 4710.929336059962, 4711.764411779406, 4714.5285471452835, 4849.151398505265, 3770.868019619314, 3831.808409579524, 3836.2327534493074, 3849.903752406184, 3917.471141395918, 3890.610938906115, 3894.9805250973704, 3899.532056153266, 3902.824372903212, 3913.7651740895494, 3775.697944164474, 3857.498525191735, 3864.613538646141, 3875.9418608720753, 3878.3794592865147, 3834.8112085253383, 3837.9232415351535, 3877.673395962428, 3882.7476214046137, 3897.9220415591813, 3481.442969124928, 3481.9825900870333, 3484.8257587120797, 3499.965000349989, 3502.807345595991, 4730.976345118287, 4747.501512341189, 4753.786079626425, 4754.096721622881, 4774.618030557582, 4318.740875547477, 4455.309427038814, 4469.486009108961, 4478.58924438804, 4479.3281007848755, 5039.546440820343, 5048.697078175322, 5054.469280725544, 5062.265971434158, 5105.157648987913, 3828.5451528419303, 3833.21419109082, 3835.17543728097, 3841.673457756094, 3849.287881741887, 5078.187490001619, 5191.766370513336, 5252.2142471559855, 5303.337082864642, 5305.469453054702, 3904.882853514399, 3905.1994341159993, 3925.450436938831, 3955.584663610337, 3956.3274243378696, 4015.9397609035886, 4016.819243134066, 4019.2162528307285, 4019.2765302245293, 4020.618041286073, 4350.0854427532995, 4472.932906006413, 4497.6851620386915, 4505.301678239878, 4516.683832131719, 5108.335916330928, 5110.233464980282, 5124.974375128175, 5139.331886854685, 5160.148575485078, 1357.8254809589635, 1362.1486570893192, 1367.7264547090422, 1368.6928982821382, 1378.5244090788663, 926.8285367207088, 941.2470023980788, 961.5000199896155, 978.4422878959067, 979.7060881735451, 4288.506821715543, 4300.419443375167, 4300.8709738707885, 4313.482382114166, 4343.326784348368, 5016.974915125474, 5018.322526458881, 5046.798128074909, 5056.57019153376, 5057.443681195065, 4104.322786740214, 4304.0, 4307.504636966726, 4322.827086916488, 4326.178026175016, 2696.177665811946, 2698.311930457721, 2703.2566044337573, 2709.4962295925952, 2713.782897368206, 4689.62483001359, 4689.85930422081, 4750.358701575243, 4757.14371413149, 4762.214234651313, 4910.607151427877, 5028.547430731252, 5072.873178170502, 5143.0, 5144.485551444825, 4676.532346765296, 4685.484596694361, 4695.624350051988, 4698.389209402756, 4709.7174169549935, 4995.900081998267, 5009.096543313518, 5040.57155141817, 5051.671641343448, 5066.468020857904, 4782.856514304576, 4909.95090049095, 4947.381577302837, 4953.851384458472, 4958.84290333706, 4633.953660463352, 4690.835820746258, 4699.530046995274, 4700.0, 4718.386609740712, 3839.2513459876172, 3849.82675779585, 3880.96119038806, 3892.3188442023097, 3903.2974064668633, 4783.545563171493, 4978.726170060681, 5045.133300678773, 5054.721990290569, 5073.518015788494, 4828.275758636232, 4836.733979631013, 4851.563359297539, 4880.41435027799, 4885.267209918541, 4872.4883887192755, 4873.468613124201, 4878.219484882402, 4881.487443818348, 4888.462269150319, 4600.677952543289, 4817.435098027505, 4824.396950381202, 4827.686200397104, 4840.080384726891, 4869.09921664479, 4870.196417591058, 4900.852974410774, 4901.750053736417, 4904.411470623548, 4801.0, 4810.089895613243, 4819.060283244734, 4825.565699087099, 4847.927281090716], "value": 3841.8180598797617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.8793926742068, 618.2580902916501, 718.6658203935169, 742.5173637135862, 748.2143749063483, 12612.873871261285, 13161.21032738034, 13341.93329033356, 13411.59765207043, 13752.862471375283, 11044.558217671303, 11175.21773475855, 11192.10463162947, 11198.776024479506, 11222.382768947726, 667.7929841749049, 812.2567850416862, 825.0800357601262, 826.7809030606894, 827.1769589258666, 689.1764341611776, 792.1603100713262, 811.2860682599334, 822.9053658829251, 823.8269963307713, 881.0836729800999, 881.8456770065245, 884.2616415293238, 884.460479107747, 884.5488800711619, 704.1655638068853, 880.3001613717046, 880.6917578847384, 882.2192359761625, 882.699882040103, 526.1555203897766, 609.893268677984, 625.8216408323606, 629.7418058595999, 631.7788773929088, 13509.797353039712, 13527.661808454768, 13549.0, 13551.186928784304, 13556.728865422636, 12097.395130243453, 12129.21160124598, 12307.569235076819, 12551.748965020739, 12641.115121941468, 11637.47631356591, 11705.48707178242, 11720.589779357682, 11812.763744725058, 11950.940245298718, 4006.939895901564, 4039.838406463738, 4041.7777022263763, 4075.4498142750645, 4122.835086596532, 56559.71720141373, 56636.71681641607, 57035.0, 57877.42122578762, 58163.709181454244, 9747.268954828416, 10041.949790251148, 10075.412092673303, 10118.898811011795, 10262.332948358333, 18898.338558150397, 18999.81000190008, 19151.329703460313, 19324.903375483314, 19385.612287754167, 6511.38141876521, 6540.6729663516635, 6541.2150541934325, 6639.568428052257, 6738.427233685094, 3322.103032181346, 3348.983255083709, 3412.590489141319, 3428.177237463041, 3447.0348302475345, 2657.0833062593374, 2702.7837772978114, 2717.6059471376934, 2738.2469820799042, 2740.6163137160815, 460.34400978605356, 461.5961034095167, 463.72408416991834, 464.68401486988495, 468.6742713813867, 582.4874110782484, 583.4020129367433, 592.2744637818638, 598.3806760003375, 599.9268622935627, 7566.508176968479, 7679.961600191963, 8222.917770822334, 8235.629396677165, 8370.12113728062, 544.5616278895448, 561.4383137011779, 574.5977815529146, 601.1823919469518, 605.4429924469499, 4014.9397759033636, 4034.536028356718, 4073.429719839225, 4112.321466957978, 4141.967509476001, 50930.490695093315, 51669.7416512915, 52028.47971520312, 64373.67813160998, 66300.0, 52888.735556321975, 52936.735316323175, 53215.46784532182, 53833.0, 55382.44617553773, 49436.50563494319, 50239.49760502421, 50963.0, 53122.2031669533, 53733.73133134309, 8781.385303028726, 9316.394434361744, 9323.813523729623, 9332.486713230566, 9342.392744471588, 34565.0, 34707.0, 34957.30085398329, 35057.298854023284, 36193.095172620895, 40086.997825054605, 42116.36825447682, 42780.0, 42874.35688464738, 44865.0, 10046.196304295785, 10223.591056357662, 10265.332753371007, 10573.154147668321, 10711.089557387397, 11752.23610465317, 11874.8816299679, 11887.76224475523, 11911.225770324902, 12468.438920248429, 11109.513872021518, 11194.107426602803, 11372.772544549229, 11451.312921224753, 11485.138614603771, 10961.451927403758, 11092.445377731243, 11194.720131996764, 11294.096472282363, 11815.82276265874, 10539.57841686324, 10594.417307048187, 10753.89246107529, 10898.0, 11144.665660030212, 9139.830614335164, 9175.908240917772, 9257.0, 9307.674231401868, 9423.434593924387, 9176.6788162414, 9421.95289023578, 9502.382345147544, 9566.186874115778, 9596.088371604686], "value": 6155.973145452274, "unit": "score"}], "name": "dromaeo_css", "value": 6282.7401301456875, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 115.0, 116.0, 114.0, 114.0, 114.0, 113.0, 115.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 129.0, 121.0, 165.0, 125.0, 128.0, 123.0, 122.0, 149.0, 124.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 159.0, 162.0, 162.0, 161.0, 162.0, 164.0, 162.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 77.0, 102.0, 77.0, 77.0, 76.0, 77.0, 93.0, 76.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 102.0, 84.0, 84.0, 84.0, 104.0, 84.0, 85.0, 106.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 148.0, 149.0, 148.0, 148.0, 148.0, 149.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 61.0, 61.0, 61.0, 66.0, 59.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 67.0, 67.0, 66.0, 67.0, 67.0, 68.0, 68.0], "value": 67.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 89.0, 83.0, 84.0, 90.0, 84.0, 84.0, 87.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 132.0, 134.0, 141.0, 132.0, 132.0, 138.0, 173.0, 143.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 179.0, 178.0, 176.0, 174.0, 175.0, 176.0, 175.0, 174.0, 177.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 67.0, 67.0, 68.0, 67.0, 71.0, 68.0, 68.0, 66.0, 65.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1538.5, "alertThreshold": 2.0}]} 16:47:50 INFO - Return code: 0 16:47:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:50 INFO - # TBPL SUCCESS # 16:47:50 INFO - Running post-action listener: _resource_record_post_action 16:47:50 INFO - [mozharness: 2017-01-07 00:47:50.189837Z] Finished run-tests step (success) 16:47:50 INFO - Running post-run listener: _resource_record_post_run 16:47:50 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382369792; Read time: 16; Write time: 226580 16:47:50 INFO - TinderboxPrint: CPU usage
13.1% 16:47:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:47:50 INFO - TinderboxPrint: I/O write bytes / time
382,369,792 / 226,580 16:47:50 INFO - TinderboxPrint: CPU idle
8,324.9 (86.8%) 16:47:50 INFO - TinderboxPrint: CPU user
1,215.4 (12.7%) 16:47:50 INFO - TinderboxPrint: Swap in / out
0 / 0 16:47:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99147776; Read time: 0; Write time: 54944 16:47:50 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282308608; Read time: 16; Write time: 171616 16:47:50 INFO - Running post-run listener: _upload_blobber_files 16:47:50 INFO - Blob upload gear active. 16:47:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:47:50 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 16:47:50 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'] 16:47:50 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 16:47:51 INFO - (blobuploader) - INFO - Open directory for files ... 16:47:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:47:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:47:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:52 INFO - (blobuploader) - INFO - Done attempting. 16:47:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:47:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:47:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:55 INFO - (blobuploader) - INFO - Done attempting. 16:47:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:47:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:47:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:56 INFO - (blobuploader) - INFO - Done attempting. 16:47:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:47:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:47:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:56 INFO - (blobuploader) - INFO - Done attempting. 16:47:56 INFO - (blobuploader) - INFO - Iteration through files over. 16:47:56 INFO - Return code: 0 16:47:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:47:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:47:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6f336d2b6344ad3d721a83f9d43a8fa1d8da2677d4d09494f66b36b072b4112101b984ca0b33c3367f63b32bd9dfbd0f64f318d58c94e50e40bcfe248ef42d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e48d7a3a328736a563f6cea96e68e42ea22ce404773ac6761e3d70adb0fea734c1082477535f19d5200f7c2267a9c71a1ec5933f1be2dd700f31862a71cf26a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7e7d2428090d953034fd37fcecc3c4a1e19755a548a7540d810a86794a713ffd1d1ee898bd5705ab857d126c35aadafa1e48ba6dcb3e45b71154b395059e5763", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f3019cdf5a4ab470ddb27b4e29eab04536dd44ce9dcdbce0f6e312ba4f8ac570809b8d3253f7cca3c8e3a151592731a0711ae72b2eb826ea0087a84e4a8e8c6"} 16:47:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:47:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:47:56 INFO - Contents: 16:47:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6f336d2b6344ad3d721a83f9d43a8fa1d8da2677d4d09494f66b36b072b4112101b984ca0b33c3367f63b32bd9dfbd0f64f318d58c94e50e40bcfe248ef42d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e48d7a3a328736a563f6cea96e68e42ea22ce404773ac6761e3d70adb0fea734c1082477535f19d5200f7c2267a9c71a1ec5933f1be2dd700f31862a71cf26a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7e7d2428090d953034fd37fcecc3c4a1e19755a548a7540d810a86794a713ffd1d1ee898bd5705ab857d126c35aadafa1e48ba6dcb3e45b71154b395059e5763", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f3019cdf5a4ab470ddb27b4e29eab04536dd44ce9dcdbce0f6e312ba4f8ac570809b8d3253f7cca3c8e3a151592731a0711ae72b2eb826ea0087a84e4a8e8c6"} 16:47:56 INFO - Running post-run listener: copy_logs_to_upload_dir 16:47:56 INFO - Copying logs to upload dir... 16:47:56 INFO - mkdir: /builds/slave/test/build/upload/logs 16:47:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.298390 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-01-06 16:47:56.736891) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 16:47:56.740829) ========= 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=4300f881736b422476174874000001bc-1483748825.981598-1286120602 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6f336d2b6344ad3d721a83f9d43a8fa1d8da2677d4d09494f66b36b072b4112101b984ca0b33c3367f63b32bd9dfbd0f64f318d58c94e50e40bcfe248ef42d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e48d7a3a328736a563f6cea96e68e42ea22ce404773ac6761e3d70adb0fea734c1082477535f19d5200f7c2267a9c71a1ec5933f1be2dd700f31862a71cf26a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7e7d2428090d953034fd37fcecc3c4a1e19755a548a7540d810a86794a713ffd1d1ee898bd5705ab857d126c35aadafa1e48ba6dcb3e45b71154b395059e5763", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f3019cdf5a4ab470ddb27b4e29eab04536dd44ce9dcdbce0f6e312ba4f8ac570809b8d3253f7cca3c8e3a151592731a0711ae72b2eb826ea0087a84e4a8e8c6"} build_url:https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011751 build_url: 'https://queue.taskcluster.net/v1/task/UO8-Lu3cQSq6E94FANkudQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6f336d2b6344ad3d721a83f9d43a8fa1d8da2677d4d09494f66b36b072b4112101b984ca0b33c3367f63b32bd9dfbd0f64f318d58c94e50e40bcfe248ef42d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e48d7a3a328736a563f6cea96e68e42ea22ce404773ac6761e3d70adb0fea734c1082477535f19d5200f7c2267a9c71a1ec5933f1be2dd700f31862a71cf26a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7e7d2428090d953034fd37fcecc3c4a1e19755a548a7540d810a86794a713ffd1d1ee898bd5705ab857d126c35aadafa1e48ba6dcb3e45b71154b395059e5763", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f3019cdf5a4ab470ddb27b4e29eab04536dd44ce9dcdbce0f6e312ba4f8ac570809b8d3253f7cca3c8e3a151592731a0711ae72b2eb826ea0087a84e4a8e8c6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 16:47:56.765816) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:47:56.766113) ========= 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=4300f881736b422476174874000001bc-1483748825.981598-1286120602 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004751 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 16:47:56.850301) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 16:47:56.850623) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 16:47:56.850912) ========= ========= Total master_lag: 0.16 =========