builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1484141261.32 results: success (0) revision: ea4621db7b6a42b723740599f6536acedea04910 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.323925) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.324631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.324907) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.349512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.349844) ========= 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=31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-11 05:27:41-- 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% 40.0M=0s 2017-01-11 05:27:41 (40.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.264808 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.628974) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.629285) ========= 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=31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018040 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.678591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:41.678926) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev ea4621db7b6a42b723740599f6536acedea04910 --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 ea4621db7b6a42b723740599f6536acedea04910 --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=31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-11 05:27:41,963 truncating revision to first 12 chars 2017-01-11 05:27:41,963 Setting DEBUG logging. 2017-01-11 05:27:41,963 attempt 1/10 2017-01-11 05:27:41,963 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/ea4621db7b6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 05:27:42,326 unpacking tar archive at: graphics-ea4621db7b6a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.693051 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:42.385388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:42.385728) ========= script_repo_revision: ea4621db7b6a42b723740599f6536acedea04910 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:42.386110) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:42.386374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 05:27:42.396923) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-01-11 05:27:42.397226) ========= /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=31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:27:42 INFO - MultiFileLogger online at 20170111 05:27:42 in /builds/slave/test 05:27:42 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 05:27:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:27:42 INFO - {'append_to_log': False, 05:27:42 INFO - 'base_work_dir': '/builds/slave/test', 05:27:42 INFO - 'blob_upload_branch': 'Graphics', 05:27:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:27:42 INFO - 'branch': 'Graphics', 05:27:42 INFO - 'buildbot_json_path': 'buildprops.json', 05:27:42 INFO - 'config_files': ('talos/linux_config.py',), 05:27:42 INFO - 'default_actions': ('clobber', 05:27:42 INFO - 'read-buildbot-config', 05:27:42 INFO - 'download-and-extract', 05:27:42 INFO - 'populate-webroot', 05:27:42 INFO - 'create-virtualenv', 05:27:42 INFO - 'install', 05:27:42 INFO - 'run-tests'), 05:27:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:27:42 INFO - 'download_minidump_stackwalk': True, 05:27:42 INFO - 'download_symbols': 'ondemand', 05:27:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:27:42 INFO - 'tooltool.py': '/tools/tooltool.py', 05:27:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:27:42 INFO - '/tools/misc-python/virtualenv.py')}, 05:27:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:27:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:27:42 INFO - 'installer_path': 'installer.exe', 05:27:42 INFO - 'log_level': 'info', 05:27:42 INFO - 'log_name': 'talos', 05:27:42 INFO - 'log_to_console': True, 05:27:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:27:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:27:42 INFO - 'opt_config_files': (), 05:27:42 INFO - 'pip_index': False, 05:27:42 INFO - 'sps_profile': False, 05:27:42 INFO - 'sps_profile_interval': 0, 05:27:42 INFO - 'suite': 'dromaeojs', 05:27:42 INFO - 'system_bits': '32', 05:27:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:27:42 INFO - 'title': 'talos-linux64-ix-014', 05:27:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:27:42 INFO - 'use_talos_json': True, 05:27:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:27:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:27:42 INFO - 'work_dir': 'build'} 05:27:42 INFO - [mozharness: 2017-01-11 13:27:42.776917Z] Running clobber step. 05:27:42 INFO - Running pre-action listener: _resource_record_pre_action 05:27:42 INFO - Running main action method: clobber 05:27:42 INFO - rmtree: /builds/slave/test/build 05:27:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:27:43 INFO - Running post-action listener: _resource_record_post_action 05:27:43 INFO - [mozharness: 2017-01-11 13:27:43.761166Z] Finished clobber step (success) 05:27:43 INFO - [mozharness: 2017-01-11 13:27:43.761268Z] Running read-buildbot-config step. 05:27:43 INFO - Running pre-action listener: _resource_record_pre_action 05:27:43 INFO - Running main action method: read_buildbot_config 05:27:43 INFO - Using buildbot properties: 05:27:43 INFO - { 05:27:43 INFO - "project": "", 05:27:43 INFO - "product": "firefox", 05:27:43 INFO - "who": "nsilva@mozilla.com", 05:27:43 INFO - "installer_path": "public/build/target.tar.bz2", 05:27:43 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 05:27:43 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 05:27:43 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 05:27:43 INFO - "stage_platform": "linux64", 05:27:43 INFO - "basedir": "/builds/slave/test", 05:27:43 INFO - "buildnumber": 10, 05:27:43 INFO - "platform": "ubuntu64_hw", 05:27:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:27:43 INFO - "slavebuilddir": "test", 05:27:43 INFO - "taskId": "D69sTd7iSGSlxiDPs0IKMA", 05:27:43 INFO - "branch": "graphics", 05:27:43 INFO - "script_repo_revision": "default", 05:27:43 INFO - "revision": "ea4621db7b6a42b723740599f6536acedea04910", 05:27:43 INFO - "slavename": "talos-linux64-ix-014", 05:27:43 INFO - "repo_path": "projects/graphics" 05:27:43 INFO - } 05:27:43 INFO - Finding installer, test and symbols from parent task. 05:27:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D69sTd7iSGSlxiDPs0IKMA'}, attempt #1 05:27:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D69sTd7iSGSlxiDPs0IKMA'}, attempt #1 05:27:44 INFO - Task dependencies: QHDEy41HQ7uYqYXLRjW0Sw 05:27:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw'}, attempt #1 05:27:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2 05:27:45 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/test_packages.json 05:27:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.crashreporter-symbols.zip 05:27:45 INFO - Running post-action listener: _resource_record_post_action 05:27:45 INFO - [mozharness: 2017-01-11 13:27:45.161151Z] Finished read-buildbot-config step (success) 05:27:45 INFO - [mozharness: 2017-01-11 13:27:45.161369Z] Running download-and-extract step. 05:27:45 INFO - Running pre-action listener: _resource_record_pre_action 05:27:45 INFO - Running main action method: download_and_extract 05:27:45 INFO - mkdir: /builds/slave/test/build/tests 05:27:45 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')]} 05:27:45 INFO - trying https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.test_packages.json 05:27:45 INFO - Downloading https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:27:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:27:45 INFO - Downloaded 896 bytes. 05:27:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:27:45 INFO - Using the following test package requirements: 05:27:45 INFO - {u'common': [u'target.common.tests.zip'], 05:27:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:27:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:27:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:27:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:27:45 INFO - u'mozbase': [u'target.common.tests.zip'], 05:27:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:27:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:27:45 INFO - u'web-platform': [u'target.common.tests.zip', 05:27:45 INFO - u'target.web-platform.tests.zip'], 05:27:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:27:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:27:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.common.tests.zip 05:27:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:27:45 INFO - Fetch https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.common.tests.zip into memory 05:27:47 INFO - Expected file size: 20256464 05:27:47 INFO - Obtained file size: 20256464 05:27:51 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:27:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.common.tests.zip 05:27:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:27:51 INFO - Fetch https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.common.tests.zip into memory 05:27:51 INFO - Expected file size: 20256464 05:27:51 INFO - Obtained file size: 20256464 05:27:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.talos.tests.zip 05:27:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:27:56 INFO - Fetch https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.talos.tests.zip into memory 05:27:57 INFO - Expected file size: 14403529 05:27:57 INFO - Obtained file size: 14403529 05:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:58 INFO - trying https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2 05:27:58 INFO - Downloading https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:27:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:28:00 INFO - Downloaded 55993737 bytes. 05:28:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2 05:28:00 INFO - mkdir: /builds/slave/test/properties 05:28:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:28:00 INFO - Writing to file /builds/slave/test/properties/build_url 05:28:00 INFO - Contents: 05:28:00 INFO - build_url:https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2 05:28:00 INFO - Running post-action listener: _resource_record_post_action 05:28:00 INFO - Running post-action listener: set_extra_try_arguments 05:28:00 INFO - [mozharness: 2017-01-11 13:28:00.072851Z] Finished download-and-extract step (success) 05:28:00 INFO - [mozharness: 2017-01-11 13:28:00.072948Z] Running populate-webroot step. 05:28:00 INFO - Running pre-action listener: _resource_record_pre_action 05:28:00 INFO - Running main action method: populate_webroot 05:28:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:00 INFO - '--tppagecycles', 05:28:00 INFO - '7'], 05:28:00 INFO - 'tests': ['tsvgm']}}, 05:28:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:28:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:28:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:28:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:28:00 INFO - 'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:28:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:28:00 INFO - 'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['damp', 'tps']}, 05:28:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:28:00 INFO - 'tests': ['damp', 'tps']}, 05:28:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['dromaeo_dom']}, 05:28:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['basic_compositor_video']}, 05:28:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:28:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['a11yr', 05:28:00 INFO - 'ts_paint', 05:28:00 INFO - 'tpaint', 05:28:00 INFO - 'sessionrestore', 05:28:00 INFO - 'sessionrestore_no_auto_restore', 05:28:00 INFO - 'tabpaint']}, 05:28:00 INFO - 'other-e10s': {'tests': ['a11yr', 05:28:00 INFO - 'ts_paint', 05:28:00 INFO - 'tpaint', 05:28:00 INFO - 'sessionrestore', 05:28:00 INFO - 'sessionrestore_no_auto_restore', 05:28:00 INFO - 'tabpaint']}, 05:28:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:28:00 INFO - 'ts_paint', 05:28:00 INFO - 'tpaint', 05:28:00 INFO - 'sessionrestore', 05:28:00 INFO - 'sessionrestore_no_auto_restore', 05:28:00 INFO - 'tabpaint']}, 05:28:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:28:00 INFO - 'ts_paint', 05:28:00 INFO - 'tpaint', 05:28:00 INFO - 'sessionrestore', 05:28:00 INFO - 'sessionrestore_no_auto_restore', 05:28:00 INFO - 'tabpaint']}, 05:28:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['a11yr', 05:28:00 INFO - 'ts_paint', 05:28:00 INFO - 'tpaint', 05:28:00 INFO - 'sessionrestore', 05:28:00 INFO - 'sessionrestore_no_auto_restore', 05:28:00 INFO - 'tabpaint']}, 05:28:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['a11yr', 05:28:00 INFO - 'ts_paint', 05:28:00 INFO - 'tpaint', 05:28:00 INFO - 'sessionrestore', 05:28:00 INFO - 'sessionrestore_no_auto_restore', 05:28:00 INFO - 'tabpaint']}, 05:28:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['tsvgx', 05:28:00 INFO - 'tsvgr_opacity', 05:28:00 INFO - 'tart', 05:28:00 INFO - 'tscrollx', 05:28:00 INFO - 'cart', 05:28:00 INFO - 'tsvg_static']}, 05:28:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:28:00 INFO - 'tsvgr_opacity', 05:28:00 INFO - 'tart', 05:28:00 INFO - 'tscrollx', 05:28:00 INFO - 'cart', 05:28:00 INFO - 'tsvg_static']}, 05:28:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:28:00 INFO - 'talos_options': ['--disable-e10s'], 05:28:00 INFO - 'tests': ['tp5o']}, 05:28:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:28:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:28:00 INFO - 'talos_options': ['--disable-e10s', 05:28:00 INFO - '--xperf_path', 05:28:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:00 INFO - 'tests': ['tp5n']}, 05:28:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:28:00 INFO - 'talos_options': ['--xperf_path', 05:28:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:00 INFO - 'tests': ['tp5n']}}, 05:28:00 INFO - 'talos.zip': {'path': '', 05:28:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:00 INFO - Running post-action listener: _resource_record_post_action 05:28:00 INFO - [mozharness: 2017-01-11 13:28:00.086075Z] Finished populate-webroot step (success) 05:28:00 INFO - [mozharness: 2017-01-11 13:28:00.086169Z] Running create-virtualenv step. 05:28:00 INFO - Running pre-action listener: _resource_record_pre_action 05:28:00 INFO - Running main action method: create_virtualenv 05:28:00 INFO - Creating virtualenv /builds/slave/test/build/venv 05:28:00 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 05:28:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:28:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:00 INFO - Using real prefix '/usr' 05:28:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:28:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:02 INFO - Installing pip.................done. 05:28:02 INFO - Return code: 0 05:28:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:28:02 INFO - Reading from file tmpfile_stdout 05:28:02 INFO - Output received: 05:28:02 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) 05:28:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:28:02 INFO - Using env: {'DISPLAY': ':0', 05:28:02 INFO - 'HOME': '/home/cltbld', 05:28:02 INFO - 'LANG': 'en_US.UTF-8', 05:28:02 INFO - 'LANGUAGE': 'en_US:en', 05:28:02 INFO - 'LOGNAME': 'cltbld', 05:28:02 INFO - 'MAIL': '/var/mail/cltbld', 05:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:02 INFO - 'MOZ_NO_REMOTE': '1', 05:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:02 INFO - 'NO_EM_RESTART': '1', 05:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:02 INFO - 'PWD': '/builds/slave/test', 05:28:02 INFO - 'SHELL': '/bin/bash', 05:28:02 INFO - 'SHLVL': '1', 05:28:02 INFO - 'TERM': 'linux', 05:28:02 INFO - 'TMOUT': '86400', 05:28:02 INFO - 'USER': 'cltbld', 05:28:02 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:02 INFO - '_': '/tools/buildbot/bin/python'} 05:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:03 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 05:28:03 INFO - Cleaning up... 05:28:03 INFO - Return code: 0 05:28:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:28:03 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')]} 05:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:03 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')]} 05:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:03 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:03 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 05:28:03 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 05:28:03 INFO - Using env: {'DISPLAY': ':0', 05:28:03 INFO - 'HOME': '/home/cltbld', 05:28:03 INFO - 'LANG': 'en_US.UTF-8', 05:28:03 INFO - 'LANGUAGE': 'en_US:en', 05:28:03 INFO - 'LOGNAME': 'cltbld', 05:28:03 INFO - 'MAIL': '/var/mail/cltbld', 05:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:03 INFO - 'MOZ_NO_REMOTE': '1', 05:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:03 INFO - 'NO_EM_RESTART': '1', 05:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:03 INFO - 'PWD': '/builds/slave/test', 05:28:03 INFO - 'SHELL': '/bin/bash', 05:28:03 INFO - 'SHLVL': '1', 05:28:03 INFO - 'TERM': 'linux', 05:28:03 INFO - 'TMOUT': '86400', 05:28:03 INFO - 'USER': 'cltbld', 05:28:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:03 INFO - '_': '/tools/buildbot/bin/python'} 05:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:03 INFO - Downloading/unpacking psutil>=3.1.1 05:28:03 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 05:28:03 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 05:28:03 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 05:28:03 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 05:28:03 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 05:28:03 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 05:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:28:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:05 INFO - Installing collected packages: psutil 05:28:05 INFO - Running setup.py install for psutil 05:28:05 INFO - building 'psutil._psutil_linux' extension 05:28:05 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 05:28:05 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 05:28:05 INFO - building 'psutil._psutil_posix' extension 05:28:05 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 05:28:05 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 05:28:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:05 INFO - Successfully installed psutil 05:28:05 INFO - Cleaning up... 05:28:06 INFO - Return code: 0 05:28:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:28:06 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')]} 05:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:06 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')]} 05:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:06 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:06 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 05:28:06 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 05:28:06 INFO - Using env: {'DISPLAY': ':0', 05:28:06 INFO - 'HOME': '/home/cltbld', 05:28:06 INFO - 'LANG': 'en_US.UTF-8', 05:28:06 INFO - 'LANGUAGE': 'en_US:en', 05:28:06 INFO - 'LOGNAME': 'cltbld', 05:28:06 INFO - 'MAIL': '/var/mail/cltbld', 05:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:06 INFO - 'MOZ_NO_REMOTE': '1', 05:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:06 INFO - 'NO_EM_RESTART': '1', 05:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:06 INFO - 'PWD': '/builds/slave/test', 05:28:06 INFO - 'SHELL': '/bin/bash', 05:28:06 INFO - 'SHLVL': '1', 05:28:06 INFO - 'TERM': 'linux', 05:28:06 INFO - 'TMOUT': '86400', 05:28:06 INFO - 'USER': 'cltbld', 05:28:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:06 INFO - '_': '/tools/buildbot/bin/python'} 05:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:28:06 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 05:28:06 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 05:28:06 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 05:28:06 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 05:28:06 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 05:28:06 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 05:28:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:28:08 INFO - Installing collected packages: mozsystemmonitor 05:28:08 INFO - Running setup.py install for mozsystemmonitor 05:28:08 INFO - Successfully installed mozsystemmonitor 05:28:08 INFO - Cleaning up... 05:28:08 INFO - Return code: 0 05:28:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:08 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:08 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 05:28:08 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 05:28:08 INFO - Using env: {'DISPLAY': ':0', 05:28:08 INFO - 'HOME': '/home/cltbld', 05:28:08 INFO - 'LANG': 'en_US.UTF-8', 05:28:08 INFO - 'LANGUAGE': 'en_US:en', 05:28:08 INFO - 'LOGNAME': 'cltbld', 05:28:08 INFO - 'MAIL': '/var/mail/cltbld', 05:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:08 INFO - 'MOZ_NO_REMOTE': '1', 05:28:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:08 INFO - 'NO_EM_RESTART': '1', 05:28:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:08 INFO - 'PWD': '/builds/slave/test', 05:28:08 INFO - 'SHELL': '/bin/bash', 05:28:08 INFO - 'SHLVL': '1', 05:28:08 INFO - 'TERM': 'linux', 05:28:08 INFO - 'TMOUT': '86400', 05:28:08 INFO - 'USER': 'cltbld', 05:28:08 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:08 INFO - '_': '/tools/buildbot/bin/python'} 05:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:08 INFO - Downloading/unpacking jsonschema==2.5.1 05:28:08 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 05:28:08 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 05:28:08 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 05:28:08 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 05:28:08 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 05:28:08 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 05:28:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:28:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:28:10 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 05:28:10 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 05:28:10 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 05:28:10 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 05:28:10 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 05:28:10 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 05:28:10 INFO - Downloading functools32-3.2.3-2.tar.gz 05:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:28:10 INFO - warning: no files found matching '*.txt' 05:28:10 INFO - no previously-included directories found matching 'build' 05:28:10 INFO - no previously-included directories found matching 'dist' 05:28:10 INFO - no previously-included directories found matching '.git*' 05:28:10 INFO - Installing collected packages: jsonschema, functools32 05:28:11 INFO - Running setup.py install for functools32 05:28:11 INFO - warning: no files found matching '*.txt' 05:28:11 INFO - no previously-included directories found matching 'build' 05:28:11 INFO - no previously-included directories found matching 'dist' 05:28:11 INFO - no previously-included directories found matching '.git*' 05:28:11 INFO - Successfully installed jsonschema functools32 05:28:11 INFO - Cleaning up... 05:28:11 INFO - Return code: 0 05:28:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:28:11 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')]} 05:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:11 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')]} 05:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:11 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:11 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 05:28:11 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 05:28:11 INFO - Using env: {'DISPLAY': ':0', 05:28:11 INFO - 'HOME': '/home/cltbld', 05:28:11 INFO - 'LANG': 'en_US.UTF-8', 05:28:11 INFO - 'LANGUAGE': 'en_US:en', 05:28:11 INFO - 'LOGNAME': 'cltbld', 05:28:11 INFO - 'MAIL': '/var/mail/cltbld', 05:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:11 INFO - 'MOZ_NO_REMOTE': '1', 05:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:11 INFO - 'NO_EM_RESTART': '1', 05:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:11 INFO - 'PWD': '/builds/slave/test', 05:28:11 INFO - 'SHELL': '/bin/bash', 05:28:11 INFO - 'SHLVL': '1', 05:28:11 INFO - 'TERM': 'linux', 05:28:11 INFO - 'TMOUT': '86400', 05:28:11 INFO - 'USER': 'cltbld', 05:28:11 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:11 INFO - '_': '/tools/buildbot/bin/python'} 05:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:28:11 INFO - Cleaning up... 05:28:11 INFO - Return code: 0 05:28:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:28:11 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')]} 05:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:11 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')]} 05:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:11 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:11 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 05:28:11 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 05:28:11 INFO - Using env: {'DISPLAY': ':0', 05:28:11 INFO - 'HOME': '/home/cltbld', 05:28:11 INFO - 'LANG': 'en_US.UTF-8', 05:28:11 INFO - 'LANGUAGE': 'en_US:en', 05:28:11 INFO - 'LOGNAME': 'cltbld', 05:28:11 INFO - 'MAIL': '/var/mail/cltbld', 05:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:11 INFO - 'MOZ_NO_REMOTE': '1', 05:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:11 INFO - 'NO_EM_RESTART': '1', 05:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:11 INFO - 'PWD': '/builds/slave/test', 05:28:11 INFO - 'SHELL': '/bin/bash', 05:28:11 INFO - 'SHLVL': '1', 05:28:11 INFO - 'TERM': 'linux', 05:28:11 INFO - 'TMOUT': '86400', 05:28:11 INFO - 'USER': 'cltbld', 05:28:11 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:11 INFO - '_': '/tools/buildbot/bin/python'} 05:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:28:11 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 05:28:11 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 05:28:11 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 05:28:11 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 05:28:11 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 05:28:11 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 05:28:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:28:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:28:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:28:14 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 05:28:14 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 05:28:14 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 05:28:14 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 05:28:14 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 05:28:14 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 05:28:14 INFO - Downloading docopt-0.6.1.tar.gz 05:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:28:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:28:14 INFO - Running setup.py install for blobuploader 05:28:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:28:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:28:14 INFO - Running setup.py install for requests 05:28:14 INFO - Running setup.py install for docopt 05:28:14 INFO - Successfully installed blobuploader requests docopt 05:28:14 INFO - Cleaning up... 05:28:15 INFO - Return code: 0 05:28:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:15 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')]} 05:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:15 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')]} 05:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:15 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:15 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 05:28:15 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 05:28:15 INFO - Using env: {'DISPLAY': ':0', 05:28:15 INFO - 'HOME': '/home/cltbld', 05:28:15 INFO - 'LANG': 'en_US.UTF-8', 05:28:15 INFO - 'LANGUAGE': 'en_US:en', 05:28:15 INFO - 'LOGNAME': 'cltbld', 05:28:15 INFO - 'MAIL': '/var/mail/cltbld', 05:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:15 INFO - 'MOZ_NO_REMOTE': '1', 05:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:15 INFO - 'NO_EM_RESTART': '1', 05:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:15 INFO - 'PWD': '/builds/slave/test', 05:28:15 INFO - 'SHELL': '/bin/bash', 05:28:15 INFO - 'SHLVL': '1', 05:28:15 INFO - 'TERM': 'linux', 05:28:15 INFO - 'TMOUT': '86400', 05:28:15 INFO - 'USER': 'cltbld', 05:28:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:15 INFO - '_': '/tools/buildbot/bin/python'} 05:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:15 INFO - Running setup.py (path:/tmp/pip-lTDtF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:15 INFO - Running setup.py (path:/tmp/pip-eupsLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:15 INFO - Running setup.py (path:/tmp/pip-ua9MuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:15 INFO - Running setup.py (path:/tmp/pip-n60kYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:15 INFO - Running setup.py (path:/tmp/pip-Qdx4wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:15 INFO - Running setup.py (path:/tmp/pip-OeauXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:15 INFO - Running setup.py (path:/tmp/pip-cBDAGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:15 INFO - Running setup.py (path:/tmp/pip-dUe4k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:15 INFO - Running setup.py (path:/tmp/pip-0Y0ENA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:15 INFO - Running setup.py (path:/tmp/pip-lGXNL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:16 INFO - Running setup.py (path:/tmp/pip-aAWwzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:16 INFO - Running setup.py (path:/tmp/pip-Zp_8Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:16 INFO - Running setup.py (path:/tmp/pip-4u7yyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:16 INFO - Running setup.py (path:/tmp/pip-cgWmQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:16 INFO - Running setup.py (path:/tmp/pip-VbwJER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:16 INFO - Running setup.py (path:/tmp/pip-xX60gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:16 INFO - Running setup.py (path:/tmp/pip-TbzmEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:28:16 INFO - Running setup.py install for manifestparser 05:28:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:28:16 INFO - Running setup.py install for mozcrash 05:28:16 INFO - Running setup.py install for mozdebug 05:28:16 INFO - Running setup.py install for mozdevice 05:28:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:28:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:28:16 INFO - Running setup.py install for mozfile 05:28:17 INFO - Running setup.py install for mozhttpd 05:28:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Running setup.py install for mozinfo 05:28:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Running setup.py install for mozInstall 05:28:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Running setup.py install for mozleak 05:28:17 INFO - Running setup.py install for mozlog 05:28:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Running setup.py install for moznetwork 05:28:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Running setup.py install for mozprocess 05:28:17 INFO - Running setup.py install for mozprofile 05:28:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:28:17 INFO - Running setup.py install for mozrunner 05:28:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:28:18 INFO - Running setup.py install for mozscreenshot 05:28:18 INFO - Running setup.py install for moztest 05:28:18 INFO - Running setup.py install for mozversion 05:28:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:28:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:28:18 INFO - Cleaning up... 05:28:18 INFO - Return code: 0 05:28:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:18 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:18 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 05:28:18 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 05:28:18 INFO - Using env: {'DISPLAY': ':0', 05:28:18 INFO - 'HOME': '/home/cltbld', 05:28:18 INFO - 'LANG': 'en_US.UTF-8', 05:28:18 INFO - 'LANGUAGE': 'en_US:en', 05:28:18 INFO - 'LOGNAME': 'cltbld', 05:28:18 INFO - 'MAIL': '/var/mail/cltbld', 05:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:18 INFO - 'MOZ_NO_REMOTE': '1', 05:28:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:18 INFO - 'NO_EM_RESTART': '1', 05:28:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:18 INFO - 'PWD': '/builds/slave/test', 05:28:18 INFO - 'SHELL': '/bin/bash', 05:28:18 INFO - 'SHLVL': '1', 05:28:18 INFO - 'TERM': 'linux', 05:28:18 INFO - 'TMOUT': '86400', 05:28:18 INFO - 'USER': 'cltbld', 05:28:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:18 INFO - '_': '/tools/buildbot/bin/python'} 05:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:18 INFO - Running setup.py (path:/tmp/pip-lSGZ8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:18 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)) 05:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:18 INFO - Running setup.py (path:/tmp/pip-etlA66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:18 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)) 05:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:18 INFO - Running setup.py (path:/tmp/pip-QE9hHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:18 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)) 05:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:18 INFO - Running setup.py (path:/tmp/pip-WMJOST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:18 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)) 05:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:18 INFO - Running setup.py (path:/tmp/pip-ky5UaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:18 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)) 05:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:18 INFO - Running setup.py (path:/tmp/pip-KiMbOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:18 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)) 05:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:18 INFO - Running setup.py (path:/tmp/pip-tUoq4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:19 INFO - Running setup.py (path:/tmp/pip-MXfVHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:19 INFO - Running setup.py (path:/tmp/pip-dmm9q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:19 INFO - Running setup.py (path:/tmp/pip-fckCqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:19 INFO - Running setup.py (path:/tmp/pip-BfjWZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:19 INFO - Running setup.py (path:/tmp/pip-5sH0sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:19 INFO - Running setup.py (path:/tmp/pip-Sbhl5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:19 INFO - Running setup.py (path:/tmp/pip-Tmmfa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:19 INFO - Running setup.py (path:/tmp/pip-hpWCyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:19 INFO - Running setup.py (path:/tmp/pip-6u8Y1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:19 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)) 05:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:19 INFO - Running setup.py (path:/tmp/pip-5v3DBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:19 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)) 05:28:19 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)) 05:28:19 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)) 05:28:19 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)) 05:28:19 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)) 05:28:19 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)) 05:28:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:21 INFO - Downloading blessings-1.6.tar.gz 05:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:28:21 INFO - Installing collected packages: blessings 05:28:21 INFO - Running setup.py install for blessings 05:28:22 INFO - Successfully installed blessings 05:28:22 INFO - Cleaning up... 05:28:22 INFO - Return code: 0 05:28:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:22 INFO - Reading from file tmpfile_stdout 05:28:22 INFO - Current package versions: 05:28:22 INFO - argparse == 1.2.1 05:28:22 INFO - blessings == 1.6 05:28:22 INFO - blobuploader == 1.2.4 05:28:22 INFO - docopt == 0.6.1 05:28:22 INFO - functools32 == 3.2.3-2 05:28:22 INFO - jsonschema == 2.5.1 05:28:22 INFO - manifestparser == 1.1 05:28:22 INFO - mozInstall == 1.12 05:28:22 INFO - mozcrash == 1.0 05:28:22 INFO - mozdebug == 0.1 05:28:22 INFO - mozdevice == 0.49 05:28:22 INFO - mozfile == 1.2 05:28:22 INFO - mozhttpd == 0.7 05:28:22 INFO - mozinfo == 0.9 05:28:22 INFO - mozleak == 0.1 05:28:22 INFO - mozlog == 3.4 05:28:22 INFO - moznetwork == 0.27 05:28:22 INFO - mozprocess == 0.24 05:28:22 INFO - mozprofile == 0.28 05:28:22 INFO - mozrunner == 6.13 05:28:22 INFO - mozscreenshot == 0.1 05:28:22 INFO - mozsystemmonitor == 0.3 05:28:22 INFO - moztest == 0.8 05:28:22 INFO - mozversion == 1.4 05:28:22 INFO - psutil == 3.1.1 05:28:22 INFO - requests == 1.2.3 05:28:22 INFO - wsgiref == 0.1.2 05:28:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:22 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')]} 05:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:22 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')]} 05:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:22 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 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:22 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 05:28:22 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 05:28:22 INFO - Using env: {'DISPLAY': ':0', 05:28:22 INFO - 'HOME': '/home/cltbld', 05:28:22 INFO - 'LANG': 'en_US.UTF-8', 05:28:22 INFO - 'LANGUAGE': 'en_US:en', 05:28:22 INFO - 'LOGNAME': 'cltbld', 05:28:22 INFO - 'MAIL': '/var/mail/cltbld', 05:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:22 INFO - 'MOZ_NO_REMOTE': '1', 05:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:22 INFO - 'NO_EM_RESTART': '1', 05:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:22 INFO - 'PWD': '/builds/slave/test', 05:28:22 INFO - 'SHELL': '/bin/bash', 05:28:22 INFO - 'SHLVL': '1', 05:28:22 INFO - 'TERM': 'linux', 05:28:22 INFO - 'TMOUT': '86400', 05:28:22 INFO - 'USER': 'cltbld', 05:28:22 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:22 INFO - '_': '/tools/buildbot/bin/python'} 05:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 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)) 05:28:22 INFO - Cleaning up... 05:28:22 INFO - Return code: 0 05:28:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:28:22 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')]} 05:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:22 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')]} 05:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267ec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, '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': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', '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 05:28:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:28:22 INFO - Using env: {'DISPLAY': ':0', 05:28:22 INFO - 'HOME': '/home/cltbld', 05:28:22 INFO - 'LANG': 'en_US.UTF-8', 05:28:22 INFO - 'LANGUAGE': 'en_US:en', 05:28:22 INFO - 'LOGNAME': 'cltbld', 05:28:22 INFO - 'MAIL': '/var/mail/cltbld', 05:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:22 INFO - 'MOZ_NO_REMOTE': '1', 05:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:22 INFO - 'NO_EM_RESTART': '1', 05:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:22 INFO - 'PWD': '/builds/slave/test', 05:28:22 INFO - 'SHELL': '/bin/bash', 05:28:22 INFO - 'SHLVL': '1', 05:28:22 INFO - 'TERM': 'linux', 05:28:22 INFO - 'TMOUT': '86400', 05:28:22 INFO - 'USER': 'cltbld', 05:28:22 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:22 INFO - '_': '/tools/buildbot/bin/python'} 05:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:28:22 INFO - Cleaning up... 05:28:22 INFO - Return code: 0 05:28:22 INFO - Running post-action listener: _resource_record_post_action 05:28:22 INFO - Running post-action listener: _start_resource_monitoring 05:28:22 INFO - Starting resource monitoring. 05:28:22 INFO - [mozharness: 2017-01-11 13:28:22.788174Z] Finished create-virtualenv step (success) 05:28:22 INFO - [mozharness: 2017-01-11 13:28:22.788636Z] Running install step. 05:28:22 INFO - Running pre-action listener: _resource_record_pre_action 05:28:22 INFO - Running main action method: install 05:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:22 INFO - Reading from file tmpfile_stdout 05:28:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:28:23 INFO - Reading from file tmpfile_stdout 05:28:23 INFO - Output received: 05:28:23 INFO - Usage: mozinstall [options] installer 05:28:23 INFO - Options: 05:28:23 INFO - -h, --help show this help message and exit 05:28:23 INFO - -d DEST, --destination=DEST 05:28:23 INFO - Directory to install application into. [default: 05:28:23 INFO - "/builds/slave/test"] 05:28:23 INFO - --app=APP Application being installed. [default: firefox] 05:28:23 INFO - mkdir: /builds/slave/test/build/application 05:28:23 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'] 05:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:28:39 INFO - Reading from file tmpfile_stdout 05:28:39 INFO - Output received: 05:28:39 INFO - /builds/slave/test/build/application/firefox/firefox 05:28:39 INFO - Running post-action listener: _resource_record_post_action 05:28:39 INFO - [mozharness: 2017-01-11 13:28:39.197470Z] Finished install step (success) 05:28:39 INFO - [mozharness: 2017-01-11 13:28:39.197744Z] Running run-tests step. 05:28:39 INFO - Running pre-action listener: _resource_record_pre_action 05:28:39 INFO - Running main action method: run_tests 05:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:28:39 INFO - Python 2.7.3 05:28:39 INFO - Return code: 0 05:28:39 INFO - grabbing minidump binary from tooltool 05:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:39 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 0x267c3f0>, '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 0x267ccf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:28:39 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 05:28:39 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 05:28:39 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 05:28:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:28:39 INFO - Return code: 0 05:28:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:28:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:28:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:28:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:28:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:28:39 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/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 05:28:39 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/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 05:28:39 INFO - Using env: {'DISPLAY': ':0', 05:28:39 INFO - 'HOME': '/home/cltbld', 05:28:39 INFO - 'LANG': 'en_US.UTF-8', 05:28:39 INFO - 'LANGUAGE': 'en_US:en', 05:28:39 INFO - 'LOGNAME': 'cltbld', 05:28:39 INFO - 'MAIL': '/var/mail/cltbld', 05:28:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:28:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:39 INFO - 'MOZ_NO_REMOTE': '1', 05:28:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:39 INFO - 'NO_EM_RESTART': '1', 05:28:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:39 INFO - 'PWD': '/builds/slave/test', 05:28:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:28:39 INFO - 'SHELL': '/bin/bash', 05:28:39 INFO - 'SHLVL': '1', 05:28:39 INFO - 'TERM': 'linux', 05:28:39 INFO - 'TMOUT': '86400', 05:28:39 INFO - 'USER': 'cltbld', 05:28:39 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298', 05:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:39 INFO - '_': '/tools/buildbot/bin/python'} 05:28:39 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/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 05:28:39 INFO - mozversion application_buildid: 20170111125144 05:28:39 INFO - mozversion application_changeset: ea4621db7b6a42b723740599f6536acedea04910 05:28:39 INFO - mozversion application_display_name: Nightly 05:28:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:28:39 INFO - mozversion application_name: Firefox 05:28:39 INFO - mozversion application_remotingname: firefox 05:28:39 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 05:28:39 INFO - mozversion application_vendor: Mozilla 05:28:39 INFO - mozversion application_version: 53.0a1 05:28:39 INFO - mozversion platform_buildid: 20170111125144 05:28:39 INFO - mozversion platform_changeset: ea4621db7b6a42b723740599f6536acedea04910 05:28:39 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 05:28:39 INFO - mozversion platform_version: 53.0a1 05:28:39 INFO - using testdate: 1484141319 05:28:39 INFO - actual date: 1484141319 05:28:39 INFO - starting webserver on 'localhost:59364' 05:28:39 INFO - SUITE-START | Running 2 tests 05:28:39 INFO - TEST-START | dromaeo_css 05:28:39 INFO - operating with platform_type : linux_ 05:28:39 INFO - Initialising browser for dromaeo_css test... 05:28:39 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj4gyTV/profile http://localhost:59364/getInfo.html) 05:28:43 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 05:28:43 INFO - PROCESS | 1936 | colorDepth:24 05:28:43 INFO - PROCESS | 1936 | Browser inner width/height: 1024/697 05:28:43 INFO - PROCESS | 1936 | __metrics 05:28:43 INFO - PROCESS | 1936 | 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" 05:28:43 INFO - PROCESS | 1936 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:44 INFO - PROCESS | 1936 | console.error: PushService: 05:28:44 INFO - PROCESS | 1936 | stateChangeProcessEnqueue: Error transitioning state 05:28:44 INFO - PROCESS | 1936 | UnknownError 05:28:45 INFO - TEST-INFO | 1936: exit 0 05:28:45 INFO - Browser initialized. 05:28:45 INFO - Running cycle 1/1 for dromaeo_css test... 05:28:45 INFO - TEST-INFO | started process 2020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj4gyTV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:57 INFO - PROCESS | 2020 | RSS: Main: 152338432 05:28:57 INFO - PROCESS | 2020 | 05:32:01 INFO - PROCESS | 2020 | Cycle 1(1): loaded http://localhost:59364/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59364/tests/dromaeo/cssquery-ext.html) 05:32:04 INFO - PROCESS | 2020 | RSS: Main: 329814016 05:32:04 INFO - PROCESS | 2020 | 05:33:46 INFO - PROCESS | 2020 | ************************* 05:33:46 INFO - PROCESS | 2020 | A coding exception was thrown and uncaught in a Task. 05:33:46 INFO - PROCESS | 2020 | 05:33:46 INFO - PROCESS | 2020 | Full message: ReferenceError: fetch is not defined 05:33:46 INFO - PROCESS | 2020 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 05:33:46 INFO - PROCESS | 2020 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 05:33:46 INFO - PROCESS | 2020 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 05:33:46 INFO - PROCESS | 2020 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:33:46 INFO - PROCESS | 2020 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:33:46 INFO - PROCESS | 2020 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:33:46 INFO - PROCESS | 2020 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 05:33:46 INFO - PROCESS | 2020 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:33:46 INFO - PROCESS | 2020 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:33:46 INFO - PROCESS | 2020 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:33:46 INFO - PROCESS | 2020 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 05:33:46 INFO - PROCESS | 2020 | 05:33:46 INFO - PROCESS | 2020 | ************************* 05:35:07 INFO - PROCESS | 2020 | Cycle 1(1): loaded http://localhost:59364/tests/dromaeo/cssquery-ext.html (next: http://localhost:59364/tests/dromaeo/cssquery-jquery.html) 05:35:07 INFO - PROCESS | 2020 | RSS: Main: 244682752 05:35:07 INFO - PROCESS | 2020 | 05:38:10 INFO - PROCESS | 2020 | Cycle 1(1): loaded http://localhost:59364/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59364/tests/dromaeo/cssquery-mootools.html) 05:38:10 INFO - PROCESS | 2020 | RSS: Main: 227524608 05:38:10 INFO - PROCESS | 2020 | 05:41:12 INFO - PROCESS | 2020 | Cycle 1(1): loaded http://localhost:59364/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59364/tests/dromaeo/cssquery-prototype.html) 05:41:13 INFO - PROCESS | 2020 | RSS: Main: 185843712 05:41:13 INFO - PROCESS | 2020 | 05:44:16 INFO - PROCESS | 2020 | Cycle 1(1): loaded http://localhost:59364/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59364/tests/dromaeo/cssquery-yui.html) 05:44:17 INFO - PROCESS | 2020 | RSS: Main: 185032704 05:44:17 INFO - PROCESS | 2020 | 05:47:19 INFO - PROCESS | 2020 | Cycle 1(1): loaded http://localhost:59364/tests/dromaeo/cssquery-yui.html (next: http://localhost:59364/tests/dromaeo/cssquery-dojo.html) 05:47:20 INFO - PROCESS | 2020 | RSS: Main: 280494080 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | __start_tp_report 05:47:20 INFO - PROCESS | 2020 | _x_x_mozilla_page_load 05:47:20 INFO - PROCESS | 2020 | _x_x_mozilla_page_load_details 05:47:20 INFO - PROCESS | 2020 | |i|pagename|runs| 05:47:20 INFO - PROCESS | 2020 | |0;dojo.html;2611.1122218445726;2618.1229288188456;2637.98681006595;2644.8016398770096;2657.4552216795546;4651.232546629811;4716.740579268139;4770.522947705228;4786.808527658898;4794.280857871321;4692.741899195543;4694.554017368353;4742.051589682069;4765.166095933207;4819.8795030124265;4360.084382279725;4384.188925048876;4418.491873434548;4478.373027776114;4563.954360456386;4098.692598055143;4424.77876106195;4443.111377724468;4468.5531446855375;4521.683482156251;4357.782110894443;4400.8019639116;4419.226635338803;4425.9114817703785;4513.413256276679;4371.322445021026;4385.736855788663;4415.624671902892;4439.067795762861;4501.819927202941;4383.780810959439;4388.539203383662;4393.802278897458;4401.119776044804;4432.157890000922;4800.399950006249;4819.879503012417;4844.806207751686;4851.248056551205;4886.584640305578;5072.264521644341;5108.693478391271;5108.923366149511;5186.222066689989;5189.636725429222;5136.974315128437;5195.376554813409;5218.243354713583;5224.76488558012;5229.189475631283;4772.952270477285;4834.5890599299455;4873.098476781806;4873.244647079709;4884.804607815719;5279.762410691528;5283.841484755463;5286.022085914118;5288.365396152486;5306.708131052752;4953.108440480748;5013.047520971004;5047.798088076509;5056.292119103328;5057.721825299644;5058.1654027085115;5116.539511443988;5131.7690703918415;5155.458676838953;5163.812323165693;5135.178371460623;5138.254953031789;5138.871528211825;5139.743012849342;5178.352705911761;5119.87200319995;5128.358955130609;5132.3327967364;5134.383873935077;5179.741012949337;4241.024564350155;4255.148970205972;4263.360495925574;4293.828246870153;4323.718958267703;3737.3459644562095;3755.830987605564;3763.3790424580184;3779.7921114338424;3784.0539865033743;4418.867433976986;4419.447569053868;4424.3142312941545;4428.645708343325;4472.888177795581;5037.042961837239;5114.565261952701;5123.052235336475;5167.250748641426;5217.3999990000875;4370.453693288339;4383.671224658164;4400.559944005638;4427.490838553543;4454.331850222427;4570.565796249351;4591.1965406053805;4595.425571803525;4597.241455159928;4637.373954516097;4688.92966605508;4835.540623640749;4845.248986407112;4848.854534363974;4875.68308059975;5027.3464449621315;5082.059818933509;5117.744112794271;5120;5193.506616871391;4824.300476430968;4824.8793780155775;4830.420349558076;4831.057943700945;4876.122297986397;4952.281919121635;4968.751562421792;4971.279164521196;4974.452810190804;5012.799488020439;5082.33929589151;5092.490750924878;5094.5160209780015;5097.388313402415;5101.056304583664;4875.853724388274;4939.135651260943;4954.5788607968725;5034.572061374824;5039.546440820343;5010.348654674869;5022.84931452057;5024.924626130684;5026.346574945233;5027.622928280321;4925.926111108264;4942.481039490802;4946.579540738933;4947.802087916443;4952.8018879245865;5051.2423136529815;5054.317667114892;5055.7472126392795;5057.620678449204;5057.8735531612;4913.9017219656125;4934.679245849008;4941.876453088702;4942.159832828356;4942.802287908444 05:47:20 INFO - PROCESS | 2020 | |1;ext.html;527.8812267239872;539.2019810680193;541.9322584676916;559.0691498654741;562.1567648527212;39344.40983385248;41355.58644413555;41406;41571.16857662845;45763.0847383053;40937.38593921094;41349;41369.793151034275;41398.793006035005;41451.792741036326;7461.850762984737;7509.324160825525;7623.085229772408;7629.504082234637;7669.654865531064;9785.168260697848;10035.59857605695;10038.949805251;10338.896611033906;10358.585656573729;10164.08523232909;10233.795324093513;10242.28304018719;10245.180385569174;10268.075873171412;10175.033371829664;10216.642417515388;10226.744331391701;10229.846552301722;10234.181265498743;10003.89996100037;10069.09378155962;10073.546690398876;10079.34484258521;10084.394936303845;32870.835645821855;33112.21199654065;33158.33683326344;33205.83397083023;33216.83391583051;3555.9111022224392;3589.10272431892;3600.9279814403567;3635.2366003139373;3636.3636363636524;27772.722272777217;28159.296017599518;28350.14949551517;28383.290417739514;28614.284642883886;3314.4531152360073;3329.7336213102894;3448.0345503259127;3505.491703702974;3543.362194804934;233842;233993;234079;234438;234984;43921.34117988233;48974.26538601924;49042.75478622656;49147.75426122847;49210.26184607234;58211.70894145502;58237.41762582405;58386.950022745696;58437.41562584405;58622;12684.746305073846;12917.870821291855;12956.67608309781;12961.41673624689;12961.935190323988;8514.020887597882;8593.06656002407;9993.300468967247;10412.271141020208;10485.26603137773;17267.65464690699;17295.913520432317;17304.394346197823;17320.480385588453;17349;6236.65911828959;6278.529110316745;6455.967720161463;6471.6764161791725;6595.208574971034;770.595437395372;793.9705992090165;837.1795640272567;844.7845799321134;863.7797361672733;22972.19597314086;23815.52368952611;23828.88085559561;23845.165419210593;23896.7610323898;985.1675334342469;989.5250279865704;992.4194346307347;996.2129917365345;1121.9846039334416;12134.332611706264;12528;12814.487420503261;12831.230126192459;12840.935795320964;326202;327956;329755;329877;330192;49698.254526182136;49887.50112498829;49907;49909.75045124751;49921;1181.3337317595533;1221.9144659873723;1229.661842993184;1234.2841152131562;1257.063487701668;22377.216797412013;22403.43991400163;23230;23269;23392.64911026302;41453.1709365817;42033.369499458146;42044;42067;42088.78955605203;279646;280950;281279;281663;282150;22730.545389091796;23342.183023594094;24180;25015.49969000646;25252.494950101263;40884;40929.59070409258;41340.586594133674;42198.367024494044;42397;34781;35226.64773352234;35345.29309413849;35504.64495355013;35638.465423019195;34137.146571335914;34239.31521369509;34320;34367.14082147966;34676.306473869874;28137.71862281351;28216.576751348337;28228.15315540537;33703.66296337005;34477;28770.424591507635;31224.53163202599;31292.217694556908;31339.373212536077;31436.371272573964;21059.894700526398;21372.35882923515;21444.24945126913;21590.460238494165;21606.351809445743 05:47:20 INFO - PROCESS | 2020 | |2;jquery.html;376.4541414948326;380.9125989577116;383.1035377217312;397.1302846765585;404.7146761533119;14169.71660566788;14581.963784139132;16084.839151608481;18916.432507024776;19130;14513.782293265584;16101.919490402532;17467.563310917234;17484.475465736017;17504.299828006897;4719.740414277205;4915.238138088602;4920;4928.679635823678;4933.309336692866;3773.3019391412677;3934.1148241645687;4867.537583929538;4920.950790492085;5911.645301281916;6153;6176.444120029197;6206.627602343874;6218.689065546727;6219.9689001554925;5521.917171242435;5559.805406810742;5574.637648552831;5616.269884914956;6211.782587609435;5451.945480545183;5460.808871689472;5463.48096930791;5464.4262352452815;5467.043267428184;17338.913305433518;20040.599188016156;20045.899770501055;20074;20266.79733202664;2251.9324420267417;2286.725592928859;2339.1111377676375;2371.537550177716;2378.191414918936;9439.764005899839;9461.24310055194;9654.71035868918;9902.108810207044;10003;1723.5432610358266;1760.9911950440294;1781.6703909776734;1798.4424828303268;1816.1010299901502;58340.41659583392;70075.64962175157;70198.64900675464;77979;81255;10298.742531436625;10302.278840481094;10478.790424191473;10583.94708026455;10654.467276636293;14614.123152610877;14639.634009149857;14658.120512769268;14658.63353416173;14667.119972801665;6699.933000670028;6887.242403335629;6911.723531058802;7001.649917504104;7064.116985376828;6291.937080629226;6323.9051414228825;6466.094746735461;6478.546501744927;6484.578502397329;4392.845316291765;4454.821807127679;4495.910081798345;4554.27131658933;4611.962308480565;1786.3658401267362;1854.9536261593298;1878.8966606836755;1902.1060101752155;1903.2387045182038;706.3718129711066;724.851405461879;732.6446673363414;734.8089496730888;748.5508694783086;15839.762403563725;15881.126538040287;15944.2825072872;16213.2704028319;16798.496045118667;724.1852915470096;728.1880703016099;740.9814754631069;744.8715395339487;745.7725393754848;8531.189536993976;8593.742187734379;8880.111988801069;8933.910660893307;9059.637614495477;446072;463067;489564;490030;493285;76931.61534192406;90924;94543;95177;95509;919.4115765909823;937.1191080384554;953.9284553658504;967.2020583019038;978.4373984958677;15189.16459594733;15226.497905482023;15773.526794196192;15994.760078599063;16100.355985760438;40495;41438;41613;41694.79152604217;41915.580844191165;53120.46879531155;57200;58208.4179158203;58408;59092;7832;7859.921400785919;7957.323627491496;8007.83984320322;8309.584520774059;9773.31586788918;11491.195616306779;11679.474423651127;11810.173287869766;12149;9327.300452465957;9728.22174226046;9929.751756206153;10212.693619191436;10370.896291036994;10122.89877101249;10307.433091180057;10423.895761042293;10506.324685514872;10719.088877445505;9866.506674666382;10038.498075096362;10068.194544436574;10386.169106471312;10698.62554810578;7794.766157015299;7827.178146294557;7856.214378562327;7936.087349954829;8248.917510825055;7994.2405471480115;9716.562754675915;9845.704628861145;10321.690349289533;10406.947965260126 05:47:20 INFO - PROCESS | 2020 | |3;mootools.html;1327.2567362277125;1345.2601069411821;1345.3945724424009;1356.125299182027;1356.6879617687928;6725.4283385912195;7163.928360716392;7172.067631207949;7172.784816455502;7175.964120179398;6248.4064013918605;6463.321351258121;6796.830079248021;6902.378785909267;6970.233274339818;2089.676100204471;2125.2774056820676;2125.8937053147356;2129.2760461443186;2131.7761635028332;2046.9897650511728;2126.755423126337;2156.493224092337;2157.1910533549917;2165.1123039553786;1890.4895678166956;1944.5916357564859;2083.489470134148;2148.215901196068;2157.3743614351897;2129.872207667545;2135.135270215565;2151.8924053797327;2155.536559639685;2157.1910533549917;2026.9189232430685;2052.0252879882087;2105.8420618453524;2133.5839511295303;2136.198925402974;6717.16035495563;6723.966380168117;6742.797716068526;6766.932330676679;6767.526273160881;4314.417553630251;4439.489458712257;4448.199324121689;4449.288113901793;4454.153710794939;2636.8022398320204;2762.6408566886175;2764.1845655531665;2766.1701489553257;2769.072360759148;997.7356000659831;1076.1982323169289;1082.826747720361;1090.820014697574;1121.3496172220093;9314.86027709585;9334.113259240361;9337.159655631094;9340.159385655255;9349.766255843659;3988.66096381805;4049.6152865477734;4058.4780707234786;4126.051008268115;4154.750714957112;8085.070216925012;8156.429049966564;8159.348211831179;8163.10205877353;8174.754757357289;4399.934000989988;4491.73049617024;4494.640428765691;4497.100579884036;4500.077484115748;3889.5219816470126;4098.774567398762;4104.363823607286;4105.568915263854;4107.301758701028;3389.5932488101585;3417.316536692622;3452.9309413811584;3459.238967427212;3492.7729697569575;1061.4639606998417;1080.475969154959;1094.3324572011068;1094.819354806464;1105.0220106010313;635.640862912453;696.7979286007103;697.5082566790401;712.9626393597304;718.9496735228483;4631.189541830234;4648.093621743795;4685.765711714401;4717.245240761462;4960.603151747924;915.2792749630652;916.9862452063225;919.1819280840058;925.1026504290827;940.6754669638964;3110.626724792994;3125.781195316306;3336.115929278743;3413.0102270341818;3454.4472884338384;7859.803504912423;8276.544790036605;8280.089190189074;8289.336853051742;8292.58537073144;7866.803329916798;8226.712065077694;8231.794205144919;8233.752987410388;8246.628901699438;6416.615003099829;6693.631850248185;6712.865742685217;6715.899261511081;6723.495737819585;4777.737224452689;4850.684705494132;4933.506649335038;4940.827071052496;5116.667416617908;7687.615619219128;7734.690612375442;7745.728899488491;7802.726904558313;7879.369650428067;7923.484973476706;7951.721689740832;7987.480813747088;7995.480293780885;7999.760007199793;6197.504199664107;6363.427291543783;6372.299047104908;6377.840553986002;6383.202099737533;6647.700853461509;6662.067310576426;6673.165854268216;6736.966315168393;6743.055972163999;6183.96908015457;6279.811605651837;6281.277653069956;6290.182276304051;6399.2640846303275;5907.409259074058;5919.970400147971;5956.463918247206;5960.523158147251;5976.581639285382;5276.103062479311;5288.206768984683;5332.546733527538;5336.066188416934;5337.973310133424;4348.717333373321;4405.229084910064;4415.09490554442;4441.311596702516;4470.664700147567;4436.13495368413;4447.533009034134;4553.2259515881715;4629.351890735367;4715.929261061018 05:47:20 INFO - PROCESS | 2020 | |4;prototype.html;345.69406075623067;350.32562317538765;350.91227193201684;351.9736019798516;355.2699203137552;4855.490173531782;4874.902501949959;4889.584385327247;4892.510748925106;4899.730514821684;4774.522547745224;4793.376861008072;4827.324174615556;4831.806727730896;4839.588634966023;3647.197616524367;3760.191558814854;3791.696664266866;3798.8670396536127;3833.3291673957087;3862.4592557042038;3869.419587061935;3884.4561761353434;3887.378019516878;3905.297046531623;3817.560980487238;3826;3838.5777564467885;3843.8078096095223;3845.788481633523;3707.665240513413;3708.5364329458685;3796.6583007529457;3797.1836055247977;3854.4218367244857;3441.5221040850797;3442.3287458945515;3444.2767018926056;3449.1721986723255;3455.87904423344;4699.67102302839;4714.858554243344;4716.174669432836;4725.503822098664;4733.508944682449;4304.288298372006;4367.606915377632;4369.60673539383;4381.882619931929;4391.385206071152;5013.448520662724;5039.588915103777;5052.671576347562;5075.5178258065425;5076.44159142494;3826.9858487500833;3828.1578052828613;3832.6550610445192;3836.3478208704587;3836.5779764225913;5130.358705161855;5271.970291437824;5277.498637629457;5277.683338999634;5307;3905.628965248297;3915.7846318452757;3916.412538119248;3918.8236529356245;3932.8623498177426;3920.5883382245024;3922.5489068757456;3927.5876033016693;3953.5058117735284;3969.2458432897656;4368.934465983013;4377.365282034088;4384.671149663788;4389.846355377546;4402.229609818269;4728;5019.824306149268;5025.974870125699;5065.873353166126;5067.670601410897;1241.4537603454633;1242.7079636285646;1256.5036810459817;1259.741752940645;1260.836091308124;810.1776696652902;832.5379414425053;857.1728282207699;871.6774793326509;872.7905302727299;4213.304804707251;4351.564843515623;4454.77726113693;4455.287154055336;4464.308032255006;5199.948000519947;5204.34945631796;5220.1908704150155;5223.869403264949;5223.973880130651;4427.977860110744;4439.822407103744;4443.4445694288215;4458;4465.173942820588;2582.1607977407416;2705.8364903091465;2717.6874659414025;2720.2111387697346;2725.9591106133425;4764.117708786203;4765.642576806684;4773.689710168828;4796.880077998078;4804.591609713144;4997.475265097185;5116.411612664591;5144.8938478227365;5205.6356055075785;5208.5051920067535;4835.395575553056;4838.612910967114;4868.318435418973;4875.999999999929;4895.265710143507;5204.34945631796;5210.062188806051;5212.895742085213;5228.424873264014;5236.972506393905;4930.506949305184;5101.158308878993;5151.716655583829;5156.355455568054;5160.690358578498;4859.31969524274;4861.246506791453;4876.707397556158;4877.292792545132;4878.07316609843;3947.5657677654863;3964.048628329238;3979.184267225154;3981.2482507696523;3982.581828907923;5061.190209566599;5104.719240441811;5203.505666961632;5211.530962213419;5212.348456442945;5037.244413337882;5052.494750524918;5052.772625231947;5058.974705126451;5062.240663900445;5044.8991020178655;5048.384516954539;5055.7472126392795;5068.797248110034;5070.909754402821;4808.966072294474;4991.550760431579;4997.625178111729;4999.950000500094;5010.473900240568;4864.902701946012;4950.2079667253065;5003.299538064746;5040.218766091261;5088.389393272831;4892.779824907817;4928.236123400879;4954.628402869727;4955.35580374549;4956.430010548833 05:47:20 INFO - PROCESS | 2020 | |5;yui.html;665.4908994619115;688.9001094841249;744.140517702054;779.6296759039456;785.5286827903259;12845.100842940998;14283.285835708224;14321.713565728693;14322.355494002768;14338.856611433883;12950.611481655566;13039.152455090412;13041.869581304185;13046.73906521869;13063.673408164801;659.7690808217134;671.8320419895026;787.3779714025912;792.9960350198255;808.8180159464132;790.3913986230599;817.6933649881295;818.8444195602846;818.868980963046;818.8976377952756;869.7955980344614;870.7692461497708;870.7910101575608;871.2072014466836;871.4814685262259;692.3526502719973;703.6657587645878;818.7871153500073;863.5768473447997;863.6286396849383;613.3835495327168;626.4174317884366;627.1971875998729;627.385162540708;627.7018416252289;11849.526018959232;11896.762064758655;11900.880991190064;11926.821097683542;11927.88072119285;13046.152000119962;13382.531611393651;13466.797998030037;13584;13708.725825483534;11250.718732031683;11450.713732156762;11574.6527604172;11616.709582260426;11738.354390508519;4096.795160241976;4108.794560271975;4134.689898257643;4138.896527586804;4196.81114349855;63941.68029159824;65549;65550;65611.67194164047;65897.34102659007;10532.210084243712;10580.629677961235;10622.628208012682;10637.787244255072;10763.623273185402;20797.480062998548;20826.791732082787;20831;20849.270275540282;21050;6550.705218265094;6606.8348291293105;7004.789856304319;7019.824504387329;7050.647467626598;3531.5232443619784;3545.858165673396;3565.679088881961;3585.6772890439984;3674.5223120994633;2793.9441211175663;2864.527352986734;2878.8992385266833;2878.9280267993468;2884.8846046158337;481.9132556139858;486.817443458703;492.90634779391803;497.60689055645634;499.79258607678133;501.65396803321204;504.8572635345021;509.4472497340427;522.4383787428529;529.4890430734359;7308.123025237006;7379.409647228209;7726.497777644435;7750.14748377678;7854.646540905673;453.3449165955229;459.80917919063216;464.3893280336347;501.133039841077;502.09622679177335;3573.249617580337;3580.6240344763423;3620.2759448110482;3631.7094632429344;3793.8103094845146;65901.6704916482;66155.33844661491;68846;71580;71901;58599.121013184835;59433.70283148556;60310.698446507486;60838.695806520685;61039;47763.5223647766;53174.20238696345;53768;54661.72669136629;55139.724301379036;6847.1783385994;7029.261927497743;7095.254998225112;7110.182329032021;7150.070490836158;36506.99999999894;36576;37531;37965.430518542795;38349.424758629204;44724.55275447204;48180.663740263546;48908;48932.266016009075;49275.50724492853;9246.537673116183;9254.629814807333;9319.394239374418;9342.813143737223;9533.142017218484;9666.613335466502;9757.365771224848;9794.951025244829;9882.604695812375;9933.255005874444;9141;9146.679866204651;9147.118657622828;9474.099960503741;9653.372530785477;9060.22988045997;9206;9293.581788819654;9304.116108969638;9318.440893546409;8655.48067115975;8669.306455483673;8773.692920747742;8943.329250306122;9221.17009469151;7694.807629809299;7861.2531809478005;7924.603769811371;7967.760967170994;8201.22088401601;7156.105486814148;7161.892571611325;7221.927780722125;7266.455015873937;7279.344858962719 05:47:20 INFO - PROCESS | 2020 | __end_tp_report 05:47:20 INFO - PROCESS | 2020 | __start_cc_report 05:47:20 INFO - PROCESS | 2020 | _x_x_mozilla_cycle_collect,2053 05:47:20 INFO - PROCESS | 2020 | __end_cc_report 05:47:20 INFO - PROCESS | 2020 | __startTimestamp1484142440189__endTimestamp 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | ------- Summary: start ------- 05:47:20 INFO - PROCESS | 2020 | Number of tests: 6 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | [#0] dojo.html Cycles:165 Average:4736.80 Median:4875.85 stddev:507.96 (10.4%) stddev-sans-first:481.37 05:47:20 INFO - PROCESS | 2020 | Values: 2611.1 2618.1 2638.0 2644.8 2657.5 4651.2 4716.7 4770.5 4786.8 4794.3 4692.7 4694.6 4742.1 4765.2 4819.9 4360.1 4384.2 4418.5 4478.4 4564.0 4098.7 4424.8 4443.1 4468.6 4521.7 4357.8 4400.8 4419.2 4425.9 4513.4 4371.3 4385.7 4415.6 4439.1 4501.8 4383.8 4388.5 4393.8 4401.1 4432.2 4800.4 4819.9 4844.8 4851.2 4886.6 5072.3 5108.7 5108.9 5186.2 5189.6 5137.0 5195.4 5218.2 5224.8 5229.2 4773.0 4834.6 4873.1 4873.2 4884.8 5279.8 5283.8 5286.0 5288.4 5306.7 4953.1 5013.0 5047.8 5056.3 5057.7 5058.2 5116.5 5131.8 5155.5 5163.8 5135.2 5138.3 5138.9 5139.7 5178.4 5119.9 5128.4 5132.3 5134.4 5179.7 4241.0 4255.1 4263.4 4293.8 4323.7 3737.3 3755.8 3763.4 3779.8 3784.1 4418.9 4419.4 4424.3 4428.6 4472.9 5037.0 5114.6 5123.1 5167.3 5217.4 4370.5 4383.7 4400.6 4427.5 4454.3 4570.6 4591.2 4595.4 4597.2 4637.4 4688.9 4835.5 4845.2 4848.9 4875.7 5027.3 5082.1 5117.7 5120.0 5193.5 4824.3 4824.9 4830.4 4831.1 4876.1 4952.3 4968.8 4971.3 4974.5 5012.8 5082.3 5092.5 5094.5 5097.4 5101.1 4875.9 4939.1 4954.6 5034.6 5039.5 5010.3 5022.8 5024.9 5026.3 5027.6 4925.9 4942.5 4946.6 4947.8 4952.8 5051.2 5054.3 5055.7 5057.6 5057.9 4913.9 4934.7 4941.9 4942.2 4942.8 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | [#1] ext.html Cycles:180 Average:43593.59 Median:181652.11 stddev:74438.78 (41.0%) stddev-sans-first:74577.38 05:47:20 INFO - PROCESS | 2020 | Values: 527.9 539.2 541.9 559.1 562.2 39344.4 41355.6 41406.0 41571.2 45763.1 40937.4 41349.0 41369.8 41398.8 41451.8 7461.9 7509.3 7623.1 7629.5 7669.7 9785.2 10035.6 10038.9 10338.9 10358.6 10164.1 10233.8 10242.3 10245.2 10268.1 10175.0 10216.6 10226.7 10229.8 10234.2 10003.9 10069.1 10073.5 10079.3 10084.4 32870.8 33112.2 33158.3 33205.8 33216.8 3555.9 3589.1 3600.9 3635.2 3636.4 27772.7 28159.3 28350.1 28383.3 28614.3 3314.5 3329.7 3448.0 3505.5 3543.4 233842.0 233993.0 234079.0 234438.0 234984.0 43921.3 48974.3 49042.8 49147.8 49210.3 58211.7 58237.4 58387.0 58437.4 58622.0 12684.7 12917.9 12956.7 12961.4 12961.9 8514.0 8593.1 9993.3 10412.3 10485.3 17267.7 17295.9 17304.4 17320.5 17349.0 6236.7 6278.5 6456.0 6471.7 6595.2 770.6 794.0 837.2 844.8 863.8 22972.2 23815.5 23828.9 23845.2 23896.8 985.2 989.5 992.4 996.2 1122.0 12134.3 12528.0 12814.5 12831.2 12840.9 326202.0 327956.0 329755.0 329877.0 330192.0 49698.3 49887.5 49907.0 49909.8 49921.0 1181.3 1221.9 1229.7 1234.3 1257.1 22377.2 22403.4 23230.0 23269.0 23392.6 41453.2 42033.4 42044.0 42067.0 42088.8 279646.0 280950.0 281279.0 281663.0 282150.0 22730.5 23342.2 24180.0 25015.5 25252.5 40884.0 40929.6 41340.6 42198.4 42397.0 34781.0 35226.6 35345.3 35504.6 35638.5 34137.1 34239.3 34320.0 34367.1 34676.3 28137.7 28216.6 28228.2 33703.7 34477.0 28770.4 31224.5 31292.2 31339.4 31436.4 21059.9 21372.4 21444.2 21590.5 21606.4 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | [#2] jquery.html Cycles:180 Average:27399.63 Median:247472.62 stddev:78675.53 (31.8%) stddev-sans-first:78870.07 05:47:20 INFO - PROCESS | 2020 | Values: 376.5 380.9 383.1 397.1 404.7 14169.7 14582.0 16084.8 18916.4 19130.0 14513.8 16101.9 17467.6 17484.5 17504.3 4719.7 4915.2 4920.0 4928.7 4933.3 3773.3 3934.1 4867.5 4921.0 5911.6 6153.0 6176.4 6206.6 6218.7 6220.0 5521.9 5559.8 5574.6 5616.3 6211.8 5451.9 5460.8 5463.5 5464.4 5467.0 17338.9 20040.6 20045.9 20074.0 20266.8 2251.9 2286.7 2339.1 2371.5 2378.2 9439.8 9461.2 9654.7 9902.1 10003.0 1723.5 1761.0 1781.7 1798.4 1816.1 58340.4 70075.6 70198.6 77979.0 81255.0 10298.7 10302.3 10478.8 10583.9 10654.5 14614.1 14639.6 14658.1 14658.6 14667.1 6699.9 6887.2 6911.7 7001.6 7064.1 6291.9 6323.9 6466.1 6478.5 6484.6 4392.8 4454.8 4495.9 4554.3 4612.0 1786.4 1855.0 1878.9 1902.1 1903.2 706.4 724.9 732.6 734.8 748.6 15839.8 15881.1 15944.3 16213.3 16798.5 724.2 728.2 741.0 744.9 745.8 8531.2 8593.7 8880.1 8933.9 9059.6 446072.0 463067.0 489564.0 490030.0 493285.0 76931.6 90924.0 94543.0 95177.0 95509.0 919.4 937.1 953.9 967.2 978.4 15189.2 15226.5 15773.5 15994.8 16100.4 40495.0 41438.0 41613.0 41694.8 41915.6 53120.5 57200.0 58208.4 58408.0 59092.0 7832.0 7859.9 7957.3 8007.8 8309.6 9773.3 11491.2 11679.5 11810.2 12149.0 9327.3 9728.2 9929.8 10212.7 10370.9 10122.9 10307.4 10423.9 10506.3 10719.1 9866.5 10038.5 10068.2 10386.2 10698.6 7794.8 7827.2 7856.2 7936.1 8248.9 7994.2 9716.6 9845.7 10321.7 10406.9 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | [#3] mootools.html Cycles:180 Average:4669.49 Median:4937.17 stddev:2459.50 (49.8%) stddev-sans-first:2453.58 05:47:20 INFO - PROCESS | 2020 | Values: 1327.3 1345.3 1345.4 1356.1 1356.7 6725.4 7163.9 7172.1 7172.8 7176.0 6248.4 6463.3 6796.8 6902.4 6970.2 2089.7 2125.3 2125.9 2129.3 2131.8 2047.0 2126.8 2156.5 2157.2 2165.1 1890.5 1944.6 2083.5 2148.2 2157.4 2129.9 2135.1 2151.9 2155.5 2157.2 2026.9 2052.0 2105.8 2133.6 2136.2 6717.2 6724.0 6742.8 6766.9 6767.5 4314.4 4439.5 4448.2 4449.3 4454.2 2636.8 2762.6 2764.2 2766.2 2769.1 997.7 1076.2 1082.8 1090.8 1121.3 9314.9 9334.1 9337.2 9340.2 9349.8 3988.7 4049.6 4058.5 4126.1 4154.8 8085.1 8156.4 8159.3 8163.1 8174.8 4399.9 4491.7 4494.6 4497.1 4500.1 3889.5 4098.8 4104.4 4105.6 4107.3 3389.6 3417.3 3452.9 3459.2 3492.8 1061.5 1080.5 1094.3 1094.8 1105.0 635.6 696.8 697.5 713.0 718.9 4631.2 4648.1 4685.8 4717.2 4960.6 915.3 917.0 919.2 925.1 940.7 3110.6 3125.8 3336.1 3413.0 3454.4 7859.8 8276.5 8280.1 8289.3 8292.6 7866.8 8226.7 8231.8 8233.8 8246.6 6416.6 6693.6 6712.9 6715.9 6723.5 4777.7 4850.7 4933.5 4940.8 5116.7 7687.6 7734.7 7745.7 7802.7 7879.4 7923.5 7951.7 7987.5 7995.5 7999.8 6197.5 6363.4 6372.3 6377.8 6383.2 6647.7 6662.1 6673.2 6737.0 6743.1 6184.0 6279.8 6281.3 6290.2 6399.3 5907.4 5920.0 5956.5 5960.5 5976.6 5276.1 5288.2 5332.5 5336.1 5338.0 4348.7 4405.2 4415.1 4441.3 4470.7 4436.1 4447.5 4553.2 4629.4 4715.9 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | [#4] prototype.html Cycles:180 Average:4225.71 Median:4795.13 stddev:1198.14 (25.0%) stddev-sans-first:1165.57 05:47:20 INFO - PROCESS | 2020 | Values: 345.7 350.3 350.9 352.0 355.3 4855.5 4874.9 4889.6 4892.5 4899.7 4774.5 4793.4 4827.3 4831.8 4839.6 3647.2 3760.2 3791.7 3798.9 3833.3 3862.5 3869.4 3884.5 3887.4 3905.3 3817.6 3826.0 3838.6 3843.8 3845.8 3707.7 3708.5 3796.7 3797.2 3854.4 3441.5 3442.3 3444.3 3449.2 3455.9 4699.7 4714.9 4716.2 4725.5 4733.5 4304.3 4367.6 4369.6 4381.9 4391.4 5013.4 5039.6 5052.7 5075.5 5076.4 3827.0 3828.2 3832.7 3836.3 3836.6 5130.4 5272.0 5277.5 5277.7 5307.0 3905.6 3915.8 3916.4 3918.8 3932.9 3920.6 3922.5 3927.6 3953.5 3969.2 4368.9 4377.4 4384.7 4389.8 4402.2 4728.0 5019.8 5026.0 5065.9 5067.7 1241.5 1242.7 1256.5 1259.7 1260.8 810.2 832.5 857.2 871.7 872.8 4213.3 4351.6 4454.8 4455.3 4464.3 5199.9 5204.3 5220.2 5223.9 5224.0 4428.0 4439.8 4443.4 4458.0 4465.2 2582.2 2705.8 2717.7 2720.2 2726.0 4764.1 4765.6 4773.7 4796.9 4804.6 4997.5 5116.4 5144.9 5205.6 5208.5 4835.4 4838.6 4868.3 4876.0 4895.3 5204.3 5210.1 5212.9 5228.4 5237.0 4930.5 5101.2 5151.7 5156.4 5160.7 4859.3 4861.2 4876.7 4877.3 4878.1 3947.6 3964.0 3979.2 3981.2 3982.6 5061.2 5104.7 5203.5 5211.5 5212.3 5037.2 5052.5 5052.8 5059.0 5062.2 5044.9 5048.4 5055.7 5068.8 5070.9 4809.0 4991.6 4997.6 5000.0 5010.5 4864.9 4950.2 5003.3 5040.2 5088.4 4892.8 4928.2 4954.6 4955.4 4956.4 05:47:20 INFO - PROCESS | 2020 | 05:47:20 INFO - PROCESS | 2020 | [#5] yui.html Cycles:180 Average:14761.06 Median:619.90 stddev:19360.62 (3123.2%) stddev-sans-first:19386.00 05:47:20 INFO - PROCESS | 2020 | Values: 665.5 688.9 744.1 779.6 785.5 12845.1 14283.3 14321.7 14322.4 14338.9 12950.6 13039.2 13041.9 13046.7 13063.7 659.8 671.8 787.4 793.0 808.8 790.4 817.7 818.8 818.9 818.9 869.8 870.8 870.8 871.2 871.5 692.4 703.7 818.8 863.6 863.6 613.4 626.4 627.2 627.4 627.7 11849.5 11896.8 11900.9 11926.8 11927.9 13046.2 13382.5 13466.8 13584.0 13708.7 11250.7 11450.7 11574.7 11616.7 11738.4 4096.8 4108.8 4134.7 4138.9 4196.8 63941.7 65549.0 65550.0 65611.7 65897.3 10532.2 10580.6 10622.6 10637.8 10763.6 20797.5 20826.8 20831.0 20849.3 21050.0 6550.7 6606.8 7004.8 7019.8 7050.6 3531.5 3545.9 3565.7 3585.7 3674.5 2793.9 2864.5 2878.9 2878.9 2884.9 481.9 486.8 492.9 497.6 499.8 501.7 504.9 509.4 522.4 529.5 7308.1 7379.4 7726.5 7750.1 7854.6 453.3 459.8 464.4 501.1 502.1 3573.2 3580.6 3620.3 3631.7 3793.8 65901.7 66155.3 68846.0 71580.0 71901.0 58599.1 59433.7 60310.7 60838.7 61039.0 47763.5 53174.2 53768.0 54661.7 55139.7 6847.2 7029.3 7095.3 7110.2 7150.1 36507.0 36576.0 37531.0 37965.4 38349.4 44724.6 48180.7 48908.0 48932.3 49275.5 9246.5 9254.6 9319.4 9342.8 9533.1 9666.6 9757.4 9795.0 9882.6 9933.3 9141.0 9146.7 9147.1 9474.1 9653.4 9060.2 9206.0 9293.6 9304.1 9318.4 8655.5 8669.3 8773.7 8943.3 9221.2 7694.8 7861.3 7924.6 7967.8 8201.2 7156.1 7161.9 7221.9 7266.5 7279.3 05:47:20 INFO - PROCESS | 2020 | -------- Summary: end -------- 05:47:20 INFO - PROCESS | 2020 | 05:47:21 INFO - TEST-INFO | 2020: exit 0 05:47:21 INFO - TEST-OK | dromaeo_css | took 1121578ms 05:47:21 INFO - TEST-START | kraken 05:47:21 INFO - operating with platform_type : linux_ 05:47:21 INFO - Initialising browser for kraken test... 05:47:21 INFO - TEST-INFO | started process 2317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjJKHSe/profile http://localhost:59364/getInfo.html) 05:47:24 INFO - PROCESS | 2317 | __metrics Screen width/height:1600/1200 05:47:24 INFO - PROCESS | 2317 | colorDepth:24 05:47:24 INFO - PROCESS | 2317 | Browser inner width/height: 1024/697 05:47:24 INFO - PROCESS | 2317 | __metrics 05:47:25 INFO - PROCESS | 2317 | 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" 05:47:25 INFO - PROCESS | 2317 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:47:26 INFO - PROCESS | 2317 | console.error: PushService: 05:47:26 INFO - PROCESS | 2317 | stateChangeProcessEnqueue: Error transitioning state 05:47:26 INFO - PROCESS | 2317 | UnknownError 05:47:26 INFO - TEST-INFO | 2317: exit 0 05:47:26 INFO - Browser initialized. 05:47:26 INFO - Running cycle 1/1 for kraken test... 05:47:26 INFO - TEST-INFO | started process 2470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjJKHSe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:38 INFO - PROCESS | 2470 | RSS: Main: 152346624 05:47:38 INFO - PROCESS | 2470 | 05:47:41 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59364/tests/kraken/driver.html?testName=audio-beat-detection) 05:47:42 INFO - PROCESS | 2470 | RSS: Main: 206516224 05:47:42 INFO - PROCESS | 2470 | 05:47:45 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59364/tests/kraken/driver.html?testName=audio-dft) 05:47:45 INFO - PROCESS | 2470 | RSS: Main: 186380288 05:47:45 INFO - PROCESS | 2470 | 05:47:49 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59364/tests/kraken/driver.html?testName=audio-fft) 05:47:49 INFO - PROCESS | 2470 | RSS: Main: 269070336 05:47:49 INFO - PROCESS | 2470 | 05:47:51 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59364/tests/kraken/driver.html?testName=audio-oscillator) 05:47:52 INFO - PROCESS | 2470 | RSS: Main: 178642944 05:47:52 INFO - PROCESS | 2470 | 05:47:54 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59364/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:47:55 INFO - PROCESS | 2470 | RSS: Main: 183599104 05:47:55 INFO - PROCESS | 2470 | 05:47:58 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59364/tests/kraken/driver.html?testName=imaging-darkroom) 05:47:59 INFO - PROCESS | 2470 | RSS: Main: 222093312 05:47:59 INFO - PROCESS | 2470 | 05:48:02 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59364/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:03 INFO - PROCESS | 2470 | RSS: Main: 222904320 05:48:03 INFO - PROCESS | 2470 | 05:48:05 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59364/tests/kraken/driver.html?testName=json-parse-financial) 05:48:06 INFO - PROCESS | 2470 | RSS: Main: 220856320 05:48:06 INFO - PROCESS | 2470 | 05:48:08 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59364/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:08 INFO - PROCESS | 2470 | RSS: Main: 183001088 05:48:08 INFO - PROCESS | 2470 | 05:48:10 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:11 INFO - PROCESS | 2470 | RSS: Main: 191279104 05:48:11 INFO - PROCESS | 2470 | 05:48:13 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:48:14 INFO - PROCESS | 2470 | RSS: Main: 199200768 05:48:14 INFO - PROCESS | 2470 | 05:48:16 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:48:17 INFO - PROCESS | 2470 | RSS: Main: 207302656 05:48:17 INFO - PROCESS | 2470 | 05:48:20 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:48:21 INFO - PROCESS | 2470 | RSS: Main: 204718080 05:48:21 INFO - PROCESS | 2470 | 05:48:22 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:59364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59364/tests/kraken/driver.html?testName=ai-astar) 05:48:23 INFO - PROCESS | 2470 | RSS: Main: 255692800 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | __start_tp_report 05:48:23 INFO - PROCESS | 2470 | _x_x_mozilla_page_load 05:48:23 INFO - PROCESS | 2470 | _x_x_mozilla_page_load_details 05:48:23 INFO - PROCESS | 2470 | |i|pagename|runs| 05:48:23 INFO - PROCESS | 2470 | |0;ai-astar;114;116;114;116;116;117;117;115;117;116 05:48:23 INFO - PROCESS | 2470 | |1;audio-beat-detection;147;125;123;172;123;125;123;134;142;125 05:48:23 INFO - PROCESS | 2470 | |2;audio-dft;163;160;161;162;162;163;161;162;162;161 05:48:23 INFO - PROCESS | 2470 | |3;audio-fft;75;76;77;120;76;76;77;76;98;77 05:48:23 INFO - PROCESS | 2470 | |4;audio-oscillator;84;86;120;84;84;85;107;84;84;123 05:48:23 INFO - PROCESS | 2470 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;118;117;120 05:48:23 INFO - PROCESS | 2470 | |6;imaging-darkroom;149;149;150;151;148;148;149;150;151;149 05:48:23 INFO - PROCESS | 2470 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 05:48:23 INFO - PROCESS | 2470 | |8;json-parse-financial;59;59;60;59;59;60;60;59;61;59 05:48:23 INFO - PROCESS | 2470 | |9;json-stringify-tinderbox;67;68;67;68;68;68;68;68;68;68 05:48:23 INFO - PROCESS | 2470 | |10;stanford-crypto-aes;85;85;88;83;91;84;83;84;89;85 05:48:23 INFO - PROCESS | 2470 | |11;stanford-crypto-ccm;141;133;132;132;143;138;130;140;183;141 05:48:23 INFO - PROCESS | 2470 | |12;stanford-crypto-pbkdf2;171;173;171;171;171;173;173;173;170;173 05:48:23 INFO - PROCESS | 2470 | |13;stanford-crypto-sha256-iterative;70;67;68;67;67;68;67;67;68;67 05:48:23 INFO - PROCESS | 2470 | __end_tp_report 05:48:23 INFO - PROCESS | 2470 | __start_cc_report 05:48:23 INFO - PROCESS | 2470 | _x_x_mozilla_cycle_collect,1835 05:48:23 INFO - PROCESS | 2470 | __end_cc_report 05:48:23 INFO - PROCESS | 2470 | __startTimestamp1484142503212__endTimestamp 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | ------- Summary: start ------- 05:48:23 INFO - PROCESS | 2470 | Number of tests: 14 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#0] ai-astar Cycles:10 Average:115.80 Median:116.00 stddev:1.14 (1.0%) stddev-sans-first:1.00 05:48:23 INFO - PROCESS | 2470 | Values: 114.0 116.0 114.0 116.0 116.0 117.0 117.0 115.0 117.0 116.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:129.50 stddev:15.93 (12.3%) stddev-sans-first:16.17 05:48:23 INFO - PROCESS | 2470 | Values: 147.0 125.0 123.0 172.0 123.0 125.0 123.0 134.0 142.0 125.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:0.88 05:48:23 INFO - PROCESS | 2470 | Values: 163.0 160.0 161.0 162.0 162.0 163.0 161.0 162.0 162.0 161.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#3] audio-fft Cycles:10 Average:82.80 Median:76.50 stddev:14.76 (19.3%) stddev-sans-first:15.39 05:48:23 INFO - PROCESS | 2470 | Values: 75.0 76.0 77.0 120.0 76.0 76.0 77.0 76.0 98.0 77.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:84.00 stddev:16.09 (19.2%) stddev-sans-first:16.65 05:48:23 INFO - PROCESS | 2470 | Values: 84.0 86.0 120.0 84.0 84.0 85.0 107.0 84.0 84.0 123.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:1.06 (0.9%) stddev-sans-first:1.12 05:48:23 INFO - PROCESS | 2470 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 118.0 117.0 120.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:149.50 stddev:1.07 (0.7%) stddev-sans-first:1.13 05:48:23 INFO - PROCESS | 2470 | Values: 149.0 149.0 150.0 151.0 148.0 148.0 149.0 150.0 151.0 149.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 05:48:23 INFO - PROCESS | 2470 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 05:48:23 INFO - PROCESS | 2470 | Values: 59.0 59.0 60.0 59.0 59.0 60.0 60.0 59.0 61.0 59.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 05:48:23 INFO - PROCESS | 2470 | Values: 67.0 68.0 67.0 68.0 68.0 68.0 68.0 68.0 68.0 68.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:2.71 (3.2%) stddev-sans-first:2.86 05:48:23 INFO - PROCESS | 2470 | Values: 85.0 85.0 88.0 83.0 91.0 84.0 83.0 84.0 89.0 85.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:140.50 stddev:15.36 (10.9%) stddev-sans-first:16.29 05:48:23 INFO - PROCESS | 2470 | Values: 141.0 133.0 132.0 132.0 143.0 138.0 130.0 140.0 183.0 141.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:173.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 05:48:23 INFO - PROCESS | 2470 | Values: 171.0 173.0 171.0 171.0 171.0 173.0 173.0 173.0 170.0 173.0 05:48:23 INFO - PROCESS | 2470 | 05:48:23 INFO - PROCESS | 2470 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:0.97 (1.4%) stddev-sans-first:0.50 05:48:23 INFO - PROCESS | 2470 | Values: 70.0 67.0 68.0 67.0 67.0 68.0 67.0 67.0 68.0 67.0 05:48:23 INFO - PROCESS | 2470 | -------- Summary: end -------- 05:48:23 INFO - PROCESS | 2470 | 05:48:24 INFO - TEST-INFO | 2470: exit 0 05:48:24 INFO - TEST-OK | kraken | took 63369ms 05:48:24 INFO - SUITE-END | took 1184s 05:48:24 INFO - Completed test suite (00:19:45) 05:48:24 INFO - javascript benchmark 05:48:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2611.1122218445726, 2618.1229288188456, 2637.98681006595, 2644.8016398770096, 2657.4552216795546, 4651.232546629811, 4716.740579268139, 4770.522947705228, 4786.808527658898, 4794.280857871321, 4692.741899195543, 4694.554017368353, 4742.051589682069, 4765.166095933207, 4819.8795030124265, 4360.084382279725, 4384.188925048876, 4418.491873434548, 4478.373027776114, 4563.954360456386, 4098.692598055143, 4424.77876106195, 4443.111377724468, 4468.5531446855375, 4521.683482156251, 4357.782110894443, 4400.8019639116, 4419.226635338803, 4425.9114817703785, 4513.413256276679, 4371.322445021026, 4385.736855788663, 4415.624671902892, 4439.067795762861, 4501.819927202941, 4383.780810959439, 4388.539203383662, 4393.802278897458, 4401.119776044804, 4432.157890000922, 4800.399950006249, 4819.879503012417, 4844.806207751686, 4851.248056551205, 4886.584640305578, 5072.264521644341, 5108.693478391271, 5108.923366149511, 5186.222066689989, 5189.636725429222, 5136.974315128437, 5195.376554813409, 5218.243354713583, 5224.76488558012, 5229.189475631283, 4772.952270477285, 4834.5890599299455, 4873.098476781806, 4873.244647079709, 4884.804607815719, 5279.762410691528, 5283.841484755463, 5286.022085914118, 5288.365396152486, 5306.708131052752, 4953.108440480748, 5013.047520971004, 5047.798088076509, 5056.292119103328, 5057.721825299644, 5058.1654027085115, 5116.539511443988, 5131.7690703918415, 5155.458676838953, 5163.812323165693, 5135.178371460623, 5138.254953031789, 5138.871528211825, 5139.743012849342, 5178.352705911761, 5119.87200319995, 5128.358955130609, 5132.3327967364, 5134.383873935077, 5179.741012949337, 4241.024564350155, 4255.148970205972, 4263.360495925574, 4293.828246870153, 4323.718958267703, 3737.3459644562095, 3755.830987605564, 3763.3790424580184, 3779.7921114338424, 3784.0539865033743, 4418.867433976986, 4419.447569053868, 4424.3142312941545, 4428.645708343325, 4472.888177795581, 5037.042961837239, 5114.565261952701, 5123.052235336475, 5167.250748641426, 5217.3999990000875, 4370.453693288339, 4383.671224658164, 4400.559944005638, 4427.490838553543, 4454.331850222427, 4570.565796249351, 4591.1965406053805, 4595.425571803525, 4597.241455159928, 4637.373954516097, 4688.92966605508, 4835.540623640749, 4845.248986407112, 4848.854534363974, 4875.68308059975, 5027.3464449621315, 5082.059818933509, 5117.744112794271, 5120.0, 5193.506616871391, 4824.300476430968, 4824.8793780155775, 4830.420349558076, 4831.057943700945, 4876.122297986397, 4952.281919121635, 4968.751562421792, 4971.279164521196, 4974.452810190804, 5012.799488020439, 5082.33929589151, 5092.490750924878, 5094.5160209780015, 5097.388313402415, 5101.056304583664, 4875.853724388274, 4939.135651260943, 4954.5788607968725, 5034.572061374824, 5039.546440820343, 5010.348654674869, 5022.84931452057, 5024.924626130684, 5026.346574945233, 5027.622928280321, 4925.926111108264, 4942.481039490802, 4946.579540738933, 4947.802087916443, 4952.8018879245865, 5051.2423136529815, 5054.317667114892, 5055.7472126392795, 5057.620678449204, 5057.8735531612, 4913.9017219656125, 4934.679245849008, 4941.876453088702, 4942.159832828356, 4942.802287908444], "value": 4703.393118087464, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.8812267239872, 539.2019810680193, 541.9322584676916, 559.0691498654741, 562.1567648527212, 39344.40983385248, 41355.58644413555, 41406.0, 41571.16857662845, 45763.0847383053, 40937.38593921094, 41349.0, 41369.793151034275, 41398.793006035005, 41451.792741036326, 7461.850762984737, 7509.324160825525, 7623.085229772408, 7629.504082234637, 7669.654865531064, 9785.168260697848, 10035.59857605695, 10038.949805251, 10338.896611033906, 10358.585656573729, 10164.08523232909, 10233.795324093513, 10242.28304018719, 10245.180385569174, 10268.075873171412, 10175.033371829664, 10216.642417515388, 10226.744331391701, 10229.846552301722, 10234.181265498743, 10003.89996100037, 10069.09378155962, 10073.546690398876, 10079.34484258521, 10084.394936303845, 32870.835645821855, 33112.21199654065, 33158.33683326344, 33205.83397083023, 33216.83391583051, 3555.9111022224392, 3589.10272431892, 3600.9279814403567, 3635.2366003139373, 3636.3636363636524, 27772.722272777217, 28159.296017599518, 28350.14949551517, 28383.290417739514, 28614.284642883886, 3314.4531152360073, 3329.7336213102894, 3448.0345503259127, 3505.491703702974, 3543.362194804934, 233842.0, 233993.0, 234079.0, 234438.0, 234984.0, 43921.34117988233, 48974.26538601924, 49042.75478622656, 49147.75426122847, 49210.26184607234, 58211.70894145502, 58237.41762582405, 58386.950022745696, 58437.41562584405, 58622.0, 12684.746305073846, 12917.870821291855, 12956.67608309781, 12961.41673624689, 12961.935190323988, 8514.020887597882, 8593.06656002407, 9993.300468967247, 10412.271141020208, 10485.26603137773, 17267.65464690699, 17295.913520432317, 17304.394346197823, 17320.480385588453, 17349.0, 6236.65911828959, 6278.529110316745, 6455.967720161463, 6471.6764161791725, 6595.208574971034, 770.595437395372, 793.9705992090165, 837.1795640272567, 844.7845799321134, 863.7797361672733, 22972.19597314086, 23815.52368952611, 23828.88085559561, 23845.165419210593, 23896.7610323898, 985.1675334342469, 989.5250279865704, 992.4194346307347, 996.2129917365345, 1121.9846039334416, 12134.332611706264, 12528.0, 12814.487420503261, 12831.230126192459, 12840.935795320964, 326202.0, 327956.0, 329755.0, 329877.0, 330192.0, 49698.254526182136, 49887.50112498829, 49907.0, 49909.75045124751, 49921.0, 1181.3337317595533, 1221.9144659873723, 1229.661842993184, 1234.2841152131562, 1257.063487701668, 22377.216797412013, 22403.43991400163, 23230.0, 23269.0, 23392.64911026302, 41453.1709365817, 42033.369499458146, 42044.0, 42067.0, 42088.78955605203, 279646.0, 280950.0, 281279.0, 281663.0, 282150.0, 22730.545389091796, 23342.183023594094, 24180.0, 25015.49969000646, 25252.494950101263, 40884.0, 40929.59070409258, 41340.586594133674, 42198.367024494044, 42397.0, 34781.0, 35226.64773352234, 35345.29309413849, 35504.64495355013, 35638.465423019195, 34137.146571335914, 34239.31521369509, 34320.0, 34367.14082147966, 34676.306473869874, 28137.71862281351, 28216.576751348337, 28228.15315540537, 33703.66296337005, 34477.0, 28770.424591507635, 31224.53163202599, 31292.217694556908, 31339.373212536077, 31436.371272573964, 21059.894700526398, 21372.35882923515, 21444.24945126913, 21590.460238494165, 21606.351809445743], "value": 17139.01788940417, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.4541414948326, 380.9125989577116, 383.1035377217312, 397.1302846765585, 404.7146761533119, 14169.71660566788, 14581.963784139132, 16084.839151608481, 18916.432507024776, 19130.0, 14513.782293265584, 16101.919490402532, 17467.563310917234, 17484.475465736017, 17504.299828006897, 4719.740414277205, 4915.238138088602, 4920.0, 4928.679635823678, 4933.309336692866, 3773.3019391412677, 3934.1148241645687, 4867.537583929538, 4920.950790492085, 5911.645301281916, 6153.0, 6176.444120029197, 6206.627602343874, 6218.689065546727, 6219.9689001554925, 5521.917171242435, 5559.805406810742, 5574.637648552831, 5616.269884914956, 6211.782587609435, 5451.945480545183, 5460.808871689472, 5463.48096930791, 5464.4262352452815, 5467.043267428184, 17338.913305433518, 20040.599188016156, 20045.899770501055, 20074.0, 20266.79733202664, 2251.9324420267417, 2286.725592928859, 2339.1111377676375, 2371.537550177716, 2378.191414918936, 9439.764005899839, 9461.24310055194, 9654.71035868918, 9902.108810207044, 10003.0, 1723.5432610358266, 1760.9911950440294, 1781.6703909776734, 1798.4424828303268, 1816.1010299901502, 58340.41659583392, 70075.64962175157, 70198.64900675464, 77979.0, 81255.0, 10298.742531436625, 10302.278840481094, 10478.790424191473, 10583.94708026455, 10654.467276636293, 14614.123152610877, 14639.634009149857, 14658.120512769268, 14658.63353416173, 14667.119972801665, 6699.933000670028, 6887.242403335629, 6911.723531058802, 7001.649917504104, 7064.116985376828, 6291.937080629226, 6323.9051414228825, 6466.094746735461, 6478.546501744927, 6484.578502397329, 4392.845316291765, 4454.821807127679, 4495.910081798345, 4554.27131658933, 4611.962308480565, 1786.3658401267362, 1854.9536261593298, 1878.8966606836755, 1902.1060101752155, 1903.2387045182038, 706.3718129711066, 724.851405461879, 732.6446673363414, 734.8089496730888, 748.5508694783086, 15839.762403563725, 15881.126538040287, 15944.2825072872, 16213.2704028319, 16798.496045118667, 724.1852915470096, 728.1880703016099, 740.9814754631069, 744.8715395339487, 745.7725393754848, 8531.189536993976, 8593.742187734379, 8880.111988801069, 8933.910660893307, 9059.637614495477, 446072.0, 463067.0, 489564.0, 490030.0, 493285.0, 76931.61534192406, 90924.0, 94543.0, 95177.0, 95509.0, 919.4115765909823, 937.1191080384554, 953.9284553658504, 967.2020583019038, 978.4373984958677, 15189.16459594733, 15226.497905482023, 15773.526794196192, 15994.760078599063, 16100.355985760438, 40495.0, 41438.0, 41613.0, 41694.79152604217, 41915.580844191165, 53120.46879531155, 57200.0, 58208.4179158203, 58408.0, 59092.0, 7832.0, 7859.921400785919, 7957.323627491496, 8007.83984320322, 8309.584520774059, 9773.31586788918, 11491.195616306779, 11679.474423651127, 11810.173287869766, 12149.0, 9327.300452465957, 9728.22174226046, 9929.751756206153, 10212.693619191436, 10370.896291036994, 10122.89877101249, 10307.433091180057, 10423.895761042293, 10506.324685514872, 10719.088877445505, 9866.506674666382, 10038.498075096362, 10068.194544436574, 10386.169106471312, 10698.62554810578, 7794.766157015299, 7827.178146294557, 7856.214378562327, 7936.087349954829, 8248.917510825055, 7994.2405471480115, 9716.562754675915, 9845.704628861145, 10321.690349289533, 10406.947965260126], "value": 8313.560312083651, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.2567362277125, 1345.2601069411821, 1345.3945724424009, 1356.125299182027, 1356.6879617687928, 6725.4283385912195, 7163.928360716392, 7172.067631207949, 7172.784816455502, 7175.964120179398, 6248.4064013918605, 6463.321351258121, 6796.830079248021, 6902.378785909267, 6970.233274339818, 2089.676100204471, 2125.2774056820676, 2125.8937053147356, 2129.2760461443186, 2131.7761635028332, 2046.9897650511728, 2126.755423126337, 2156.493224092337, 2157.1910533549917, 2165.1123039553786, 1890.4895678166956, 1944.5916357564859, 2083.489470134148, 2148.215901196068, 2157.3743614351897, 2129.872207667545, 2135.135270215565, 2151.8924053797327, 2155.536559639685, 2157.1910533549917, 2026.9189232430685, 2052.0252879882087, 2105.8420618453524, 2133.5839511295303, 2136.198925402974, 6717.16035495563, 6723.966380168117, 6742.797716068526, 6766.932330676679, 6767.526273160881, 4314.417553630251, 4439.489458712257, 4448.199324121689, 4449.288113901793, 4454.153710794939, 2636.8022398320204, 2762.6408566886175, 2764.1845655531665, 2766.1701489553257, 2769.072360759148, 997.7356000659831, 1076.1982323169289, 1082.826747720361, 1090.820014697574, 1121.3496172220093, 9314.86027709585, 9334.113259240361, 9337.159655631094, 9340.159385655255, 9349.766255843659, 3988.66096381805, 4049.6152865477734, 4058.4780707234786, 4126.051008268115, 4154.750714957112, 8085.070216925012, 8156.429049966564, 8159.348211831179, 8163.10205877353, 8174.754757357289, 4399.934000989988, 4491.73049617024, 4494.640428765691, 4497.100579884036, 4500.077484115748, 3889.5219816470126, 4098.774567398762, 4104.363823607286, 4105.568915263854, 4107.301758701028, 3389.5932488101585, 3417.316536692622, 3452.9309413811584, 3459.238967427212, 3492.7729697569575, 1061.4639606998417, 1080.475969154959, 1094.3324572011068, 1094.819354806464, 1105.0220106010313, 635.640862912453, 696.7979286007103, 697.5082566790401, 712.9626393597304, 718.9496735228483, 4631.189541830234, 4648.093621743795, 4685.765711714401, 4717.245240761462, 4960.603151747924, 915.2792749630652, 916.9862452063225, 919.1819280840058, 925.1026504290827, 940.6754669638964, 3110.626724792994, 3125.781195316306, 3336.115929278743, 3413.0102270341818, 3454.4472884338384, 7859.803504912423, 8276.544790036605, 8280.089190189074, 8289.336853051742, 8292.58537073144, 7866.803329916798, 8226.712065077694, 8231.794205144919, 8233.752987410388, 8246.628901699438, 6416.615003099829, 6693.631850248185, 6712.865742685217, 6715.899261511081, 6723.495737819585, 4777.737224452689, 4850.684705494132, 4933.506649335038, 4940.827071052496, 5116.667416617908, 7687.615619219128, 7734.690612375442, 7745.728899488491, 7802.726904558313, 7879.369650428067, 7923.484973476706, 7951.721689740832, 7987.480813747088, 7995.480293780885, 7999.760007199793, 6197.504199664107, 6363.427291543783, 6372.299047104908, 6377.840553986002, 6383.202099737533, 6647.700853461509, 6662.067310576426, 6673.165854268216, 6736.966315168393, 6743.055972163999, 6183.96908015457, 6279.811605651837, 6281.277653069956, 6290.182276304051, 6399.2640846303275, 5907.409259074058, 5919.970400147971, 5956.463918247206, 5960.523158147251, 5976.581639285382, 5276.103062479311, 5288.206768984683, 5332.546733527538, 5336.066188416934, 5337.973310133424, 4348.717333373321, 4405.229084910064, 4415.09490554442, 4441.311596702516, 4470.664700147567, 4436.13495368413, 4447.533009034134, 4553.2259515881715, 4629.351890735367, 4715.929261061018], "value": 3843.2739917716517, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.69406075623067, 350.32562317538765, 350.91227193201684, 351.9736019798516, 355.2699203137552, 4855.490173531782, 4874.902501949959, 4889.584385327247, 4892.510748925106, 4899.730514821684, 4774.522547745224, 4793.376861008072, 4827.324174615556, 4831.806727730896, 4839.588634966023, 3647.197616524367, 3760.191558814854, 3791.696664266866, 3798.8670396536127, 3833.3291673957087, 3862.4592557042038, 3869.419587061935, 3884.4561761353434, 3887.378019516878, 3905.297046531623, 3817.560980487238, 3826.0, 3838.5777564467885, 3843.8078096095223, 3845.788481633523, 3707.665240513413, 3708.5364329458685, 3796.6583007529457, 3797.1836055247977, 3854.4218367244857, 3441.5221040850797, 3442.3287458945515, 3444.2767018926056, 3449.1721986723255, 3455.87904423344, 4699.67102302839, 4714.858554243344, 4716.174669432836, 4725.503822098664, 4733.508944682449, 4304.288298372006, 4367.606915377632, 4369.60673539383, 4381.882619931929, 4391.385206071152, 5013.448520662724, 5039.588915103777, 5052.671576347562, 5075.5178258065425, 5076.44159142494, 3826.9858487500833, 3828.1578052828613, 3832.6550610445192, 3836.3478208704587, 3836.5779764225913, 5130.358705161855, 5271.970291437824, 5277.498637629457, 5277.683338999634, 5307.0, 3905.628965248297, 3915.7846318452757, 3916.412538119248, 3918.8236529356245, 3932.8623498177426, 3920.5883382245024, 3922.5489068757456, 3927.5876033016693, 3953.5058117735284, 3969.2458432897656, 4368.934465983013, 4377.365282034088, 4384.671149663788, 4389.846355377546, 4402.229609818269, 4728.0, 5019.824306149268, 5025.974870125699, 5065.873353166126, 5067.670601410897, 1241.4537603454633, 1242.7079636285646, 1256.5036810459817, 1259.741752940645, 1260.836091308124, 810.1776696652902, 832.5379414425053, 857.1728282207699, 871.6774793326509, 872.7905302727299, 4213.304804707251, 4351.564843515623, 4454.77726113693, 4455.287154055336, 4464.308032255006, 5199.948000519947, 5204.34945631796, 5220.1908704150155, 5223.869403264949, 5223.973880130651, 4427.977860110744, 4439.822407103744, 4443.4445694288215, 4458.0, 4465.173942820588, 2582.1607977407416, 2705.8364903091465, 2717.6874659414025, 2720.2111387697346, 2725.9591106133425, 4764.117708786203, 4765.642576806684, 4773.689710168828, 4796.880077998078, 4804.591609713144, 4997.475265097185, 5116.411612664591, 5144.8938478227365, 5205.6356055075785, 5208.5051920067535, 4835.395575553056, 4838.612910967114, 4868.318435418973, 4875.999999999929, 4895.265710143507, 5204.34945631796, 5210.062188806051, 5212.895742085213, 5228.424873264014, 5236.972506393905, 4930.506949305184, 5101.158308878993, 5151.716655583829, 5156.355455568054, 5160.690358578498, 4859.31969524274, 4861.246506791453, 4876.707397556158, 4877.292792545132, 4878.07316609843, 3947.5657677654863, 3964.048628329238, 3979.184267225154, 3981.2482507696523, 3982.581828907923, 5061.190209566599, 5104.719240441811, 5203.505666961632, 5211.530962213419, 5212.348456442945, 5037.244413337882, 5052.494750524918, 5052.772625231947, 5058.974705126451, 5062.240663900445, 5044.8991020178655, 5048.384516954539, 5055.7472126392795, 5068.797248110034, 5070.909754402821, 4808.966072294474, 4991.550760431579, 4997.625178111729, 4999.950000500094, 5010.473900240568, 4864.902701946012, 4950.2079667253065, 5003.299538064746, 5040.218766091261, 5088.389393272831, 4892.779824907817, 4928.236123400879, 4954.628402869727, 4955.35580374549, 4956.430010548833], "value": 3852.865150989963, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.4908994619115, 688.9001094841249, 744.140517702054, 779.6296759039456, 785.5286827903259, 12845.100842940998, 14283.285835708224, 14321.713565728693, 14322.355494002768, 14338.856611433883, 12950.611481655566, 13039.152455090412, 13041.869581304185, 13046.73906521869, 13063.673408164801, 659.7690808217134, 671.8320419895026, 787.3779714025912, 792.9960350198255, 808.8180159464132, 790.3913986230599, 817.6933649881295, 818.8444195602846, 818.868980963046, 818.8976377952756, 869.7955980344614, 870.7692461497708, 870.7910101575608, 871.2072014466836, 871.4814685262259, 692.3526502719973, 703.6657587645878, 818.7871153500073, 863.5768473447997, 863.6286396849383, 613.3835495327168, 626.4174317884366, 627.1971875998729, 627.385162540708, 627.7018416252289, 11849.526018959232, 11896.762064758655, 11900.880991190064, 11926.821097683542, 11927.88072119285, 13046.152000119962, 13382.531611393651, 13466.797998030037, 13584.0, 13708.725825483534, 11250.718732031683, 11450.713732156762, 11574.6527604172, 11616.709582260426, 11738.354390508519, 4096.795160241976, 4108.794560271975, 4134.689898257643, 4138.896527586804, 4196.81114349855, 63941.68029159824, 65549.0, 65550.0, 65611.67194164047, 65897.34102659007, 10532.210084243712, 10580.629677961235, 10622.628208012682, 10637.787244255072, 10763.623273185402, 20797.480062998548, 20826.791732082787, 20831.0, 20849.270275540282, 21050.0, 6550.705218265094, 6606.8348291293105, 7004.789856304319, 7019.824504387329, 7050.647467626598, 3531.5232443619784, 3545.858165673396, 3565.679088881961, 3585.6772890439984, 3674.5223120994633, 2793.9441211175663, 2864.527352986734, 2878.8992385266833, 2878.9280267993468, 2884.8846046158337, 481.9132556139858, 486.817443458703, 492.90634779391803, 497.60689055645634, 499.79258607678133, 501.65396803321204, 504.8572635345021, 509.4472497340427, 522.4383787428529, 529.4890430734359, 7308.123025237006, 7379.409647228209, 7726.497777644435, 7750.14748377678, 7854.646540905673, 453.3449165955229, 459.80917919063216, 464.3893280336347, 501.133039841077, 502.09622679177335, 3573.249617580337, 3580.6240344763423, 3620.2759448110482, 3631.7094632429344, 3793.8103094845146, 65901.6704916482, 66155.33844661491, 68846.0, 71580.0, 71901.0, 58599.121013184835, 59433.70283148556, 60310.698446507486, 60838.695806520685, 61039.0, 47763.5223647766, 53174.20238696345, 53768.0, 54661.72669136629, 55139.724301379036, 6847.1783385994, 7029.261927497743, 7095.254998225112, 7110.182329032021, 7150.070490836158, 36506.99999999894, 36576.0, 37531.0, 37965.430518542795, 38349.424758629204, 44724.55275447204, 48180.663740263546, 48908.0, 48932.266016009075, 49275.50724492853, 9246.537673116183, 9254.629814807333, 9319.394239374418, 9342.813143737223, 9533.142017218484, 9666.613335466502, 9757.365771224848, 9794.951025244829, 9882.604695812375, 9933.255005874444, 9141.0, 9146.679866204651, 9147.118657622828, 9474.099960503741, 9653.372530785477, 9060.22988045997, 9206.0, 9293.581788819654, 9304.116108969638, 9318.440893546409, 8655.48067115975, 8669.306455483673, 8773.692920747742, 8943.329250306122, 9221.17009469151, 7694.807629809299, 7861.2531809478005, 7924.603769811371, 7967.760967170994, 8201.22088401601, 7156.105486814148, 7161.892571611325, 7221.927780722125, 7266.455015873937, 7279.344858962719], "value": 5994.662282076601, "unit": "score"}], "name": "dromaeo_css", "value": 6248.093592852654, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 114.0, 116.0, 116.0, 117.0, 117.0, 115.0, 117.0, 116.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 125.0, 123.0, 172.0, 123.0, 125.0, 123.0, 134.0, 142.0, 125.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 162.0, 162.0, 163.0, 161.0, 162.0, 162.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 120.0, 76.0, 76.0, 77.0, 76.0, 98.0, 77.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 120.0, 84.0, 84.0, 85.0, 107.0, 84.0, 84.0, 123.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 117.0, 120.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 151.0, 148.0, 148.0, 149.0, 150.0, 151.0, 149.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 59.0, 60.0, 60.0, 59.0, 61.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 88.0, 83.0, 91.0, 84.0, 83.0, 84.0, 89.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 132.0, 132.0, 143.0, 138.0, 130.0, 140.0, 183.0, 141.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 171.0, 171.0, 171.0, 173.0, 173.0, 173.0, 170.0, 173.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1543.8999999999999, "alertThreshold": 2.0}]} 05:48:24 INFO - Return code: 0 05:48:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:48:25 INFO - # TBPL SUCCESS # 05:48:25 INFO - Running post-action listener: _resource_record_post_action 05:48:25 INFO - [mozharness: 2017-01-11 13:48:25.039154Z] Finished run-tests step (success) 05:48:25 INFO - Running post-run listener: _resource_record_post_run 05:48:25 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382722048; Read time: 12; Write time: 182976 05:48:25 INFO - TinderboxPrint: CPU usage
13.0% 05:48:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:48:25 INFO - TinderboxPrint: I/O write bytes / time
382,722,048 / 182,976 05:48:25 INFO - TinderboxPrint: CPU idle
8,341.1 (86.9%) 05:48:25 INFO - TinderboxPrint: CPU user
1,209.0 (12.6%) 05:48:25 INFO - TinderboxPrint: Swap in / out
0 / 0 05:48:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99958784; Read time: 0; Write time: 64780 05:48:25 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 282763264; Read time: 0; Write time: 118196 05:48:25 INFO - Running post-run listener: _upload_blobber_files 05:48:25 INFO - Blob upload gear active. 05:48:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:48:25 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 05:48:25 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'] 05:48:25 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 05:48:26 INFO - (blobuploader) - INFO - Open directory for files ... 05:48:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:48:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:48:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:27 INFO - (blobuploader) - INFO - Done attempting. 05:48:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:48:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:48:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:30 INFO - (blobuploader) - INFO - Done attempting. 05:48:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:48:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:48:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:31 INFO - (blobuploader) - INFO - Done attempting. 05:48:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:48:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:48:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:32 INFO - (blobuploader) - INFO - Done attempting. 05:48:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:48:32 INFO - Return code: 0 05:48:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:48:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:48:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a1b08e4b69f1cfebe66734f6b695c740870c8b6e0418358ca721ce662c1b359108c4170ba950aed6d51092b578cf17376a18581514ee1641a0dc77f3a0f967c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4bdd4bf3ced8bc640ba149fbf3ed217e991d308ff51979757a9e77412ae3aa80203eebae997414adc75aaa96497d6f4c08a9f92c13bfec9a4c55bd8b385388f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4182cded1d1e8f4cd13389ca119c42299fe50a7654d64332d77b5ae96bf5d182f6d4097a02d226f8f4f28eb50f5e7c7b8cb51584892b08b61bd40401ba719f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697c360ecd17b7734a1010101eaeb0dc0089afa82f97f19ed421cc2a8111223a248300adecc84b6d84e3090edd313ac04134d62d1184efa89fae8cf484830b01"} 05:48:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:48:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:48:32 INFO - Contents: 05:48:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a1b08e4b69f1cfebe66734f6b695c740870c8b6e0418358ca721ce662c1b359108c4170ba950aed6d51092b578cf17376a18581514ee1641a0dc77f3a0f967c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4bdd4bf3ced8bc640ba149fbf3ed217e991d308ff51979757a9e77412ae3aa80203eebae997414adc75aaa96497d6f4c08a9f92c13bfec9a4c55bd8b385388f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4182cded1d1e8f4cd13389ca119c42299fe50a7654d64332d77b5ae96bf5d182f6d4097a02d226f8f4f28eb50f5e7c7b8cb51584892b08b61bd40401ba719f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697c360ecd17b7734a1010101eaeb0dc0089afa82f97f19ed421cc2a8111223a248300adecc84b6d84e3090edd313ac04134d62d1184efa89fae8cf484830b01"} 05:48:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:48:32 INFO - Copying logs to upload dir... 05:48:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:48:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.336160 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-01-11 05:48:32.748898) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 05:48:32.751884) ========= 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=31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298 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/a1b08e4b69f1cfebe66734f6b695c740870c8b6e0418358ca721ce662c1b359108c4170ba950aed6d51092b578cf17376a18581514ee1641a0dc77f3a0f967c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4bdd4bf3ced8bc640ba149fbf3ed217e991d308ff51979757a9e77412ae3aa80203eebae997414adc75aaa96497d6f4c08a9f92c13bfec9a4c55bd8b385388f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4182cded1d1e8f4cd13389ca119c42299fe50a7654d64332d77b5ae96bf5d182f6d4097a02d226f8f4f28eb50f5e7c7b8cb51584892b08b61bd40401ba719f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697c360ecd17b7734a1010101eaeb0dc0089afa82f97f19ed421cc2a8111223a248300adecc84b6d84e3090edd313ac04134d62d1184efa89fae8cf484830b01"} build_url:https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011763 build_url: 'https://queue.taskcluster.net/v1/task/QHDEy41HQ7uYqYXLRjW0Sw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a1b08e4b69f1cfebe66734f6b695c740870c8b6e0418358ca721ce662c1b359108c4170ba950aed6d51092b578cf17376a18581514ee1641a0dc77f3a0f967c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b4bdd4bf3ced8bc640ba149fbf3ed217e991d308ff51979757a9e77412ae3aa80203eebae997414adc75aaa96497d6f4c08a9f92c13bfec9a4c55bd8b385388f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4182cded1d1e8f4cd13389ca119c42299fe50a7654d64332d77b5ae96bf5d182f6d4097a02d226f8f4f28eb50f5e7c7b8cb51584892b08b61bd40401ba719f3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697c360ecd17b7734a1010101eaeb0dc0089afa82f97f19ed421cc2a8111223a248300adecc84b6d84e3090edd313ac04134d62d1184efa89fae8cf484830b01"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 05:48:32.776741) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:48:32.777030) ========= 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=31acecf42b6a79ec49471d4a000001bb-1484139799.298781-1730999298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 05:48:32.860170) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 05:48:32.860470) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 05:48:32.860748) ========= ========= Total master_lag: 0.17 =========