builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1484164603.62 results: success (0) revision: 3aacbf7e3899edf7f993062d2d691f890c902f41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.619237) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.619657) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.619927) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.649132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.649456) ========= 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=920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-11 11:56:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2017-01-11 11:56:43 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.197654 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.859665) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.859977) ========= 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=920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.110232 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.985573) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:43.985904) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 3aacbf7e3899edf7f993062d2d691f890c902f41 --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 3aacbf7e3899edf7f993062d2d691f890c902f41 --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=920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-11 11:56:44,049 truncating revision to first 12 chars 2017-01-11 11:56:44,049 Setting DEBUG logging. 2017-01-11 11:56:44,049 attempt 1/10 2017-01-11 11:56:44,049 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/3aacbf7e3899?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 11:56:44,356 unpacking tar archive at: graphics-3aacbf7e3899/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632732 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:44.634875) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:44.635206) ========= script_repo_revision: 3aacbf7e3899edf7f993062d2d691f890c902f41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:44.635561) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:44.635822) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 11:56:44.646496) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-01-11 11:56:44.646791) ========= /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=920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:56:44 INFO - MultiFileLogger online at 20170111 11:56:44 in /builds/slave/test 11:56:44 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 11:56:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:56:44 INFO - {'append_to_log': False, 11:56:44 INFO - 'base_work_dir': '/builds/slave/test', 11:56:44 INFO - 'blob_upload_branch': 'Graphics', 11:56:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:56:44 INFO - 'branch': 'Graphics', 11:56:44 INFO - 'buildbot_json_path': 'buildprops.json', 11:56:44 INFO - 'config_files': ('talos/linux_config.py',), 11:56:44 INFO - 'default_actions': ('clobber', 11:56:44 INFO - 'read-buildbot-config', 11:56:44 INFO - 'download-and-extract', 11:56:44 INFO - 'populate-webroot', 11:56:44 INFO - 'create-virtualenv', 11:56:44 INFO - 'install', 11:56:44 INFO - 'run-tests'), 11:56:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:56:44 INFO - 'download_minidump_stackwalk': True, 11:56:44 INFO - 'download_symbols': 'ondemand', 11:56:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:56:44 INFO - 'tooltool.py': '/tools/tooltool.py', 11:56:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:56:44 INFO - '/tools/misc-python/virtualenv.py')}, 11:56:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:56:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:56:44 INFO - 'installer_path': 'installer.exe', 11:56:44 INFO - 'log_level': 'info', 11:56:44 INFO - 'log_name': 'talos', 11:56:44 INFO - 'log_to_console': True, 11:56:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:56:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:56:44 INFO - 'opt_config_files': (), 11:56:44 INFO - 'pip_index': False, 11:56:44 INFO - 'sps_profile': False, 11:56:44 INFO - 'sps_profile_interval': 0, 11:56:44 INFO - 'suite': 'dromaeojs', 11:56:44 INFO - 'system_bits': '32', 11:56:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:56:44 INFO - 'title': 'talos-linux64-ix-053', 11:56:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:56:44 INFO - 'use_talos_json': True, 11:56:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:56:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:56:44 INFO - 'work_dir': 'build'} 11:56:44 INFO - [mozharness: 2017-01-11 19:56:44.892196Z] Running clobber step. 11:56:44 INFO - Running pre-action listener: _resource_record_pre_action 11:56:44 INFO - Running main action method: clobber 11:56:44 INFO - rmtree: /builds/slave/test/build 11:56:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:56:49 INFO - Running post-action listener: _resource_record_post_action 11:56:49 INFO - [mozharness: 2017-01-11 19:56:49.691507Z] Finished clobber step (success) 11:56:49 INFO - [mozharness: 2017-01-11 19:56:49.691651Z] Running read-buildbot-config step. 11:56:49 INFO - Running pre-action listener: _resource_record_pre_action 11:56:49 INFO - Running main action method: read_buildbot_config 11:56:49 INFO - Using buildbot properties: 11:56:49 INFO - { 11:56:49 INFO - "project": "", 11:56:49 INFO - "product": "firefox", 11:56:49 INFO - "who": "kgupta@mozilla.com", 11:56:49 INFO - "installer_path": "public/build/target.tar.bz2", 11:56:49 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 11:56:49 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 11:56:49 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 11:56:49 INFO - "stage_platform": "linux64", 11:56:49 INFO - "basedir": "/builds/slave/test", 11:56:49 INFO - "buildnumber": 49, 11:56:49 INFO - "platform": "ubuntu64_hw", 11:56:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:56:49 INFO - "slavebuilddir": "test", 11:56:49 INFO - "taskId": "DMgggMEfSMqFBJOh_70dSQ", 11:56:49 INFO - "branch": "graphics", 11:56:49 INFO - "script_repo_revision": "default", 11:56:49 INFO - "revision": "3aacbf7e3899edf7f993062d2d691f890c902f41", 11:56:49 INFO - "slavename": "talos-linux64-ix-053", 11:56:49 INFO - "repo_path": "projects/graphics" 11:56:49 INFO - } 11:56:49 INFO - Finding installer, test and symbols from parent task. 11:56:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DMgggMEfSMqFBJOh_70dSQ'}, attempt #1 11:56:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DMgggMEfSMqFBJOh_70dSQ'}, attempt #1 11:56:50 INFO - Task dependencies: XJGsc4VgRHqPXNBC9-1d3Q 11:56:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q'}, attempt #1 11:56:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2 11:56:51 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/test_packages.json 11:56:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.crashreporter-symbols.zip 11:56:51 INFO - Running post-action listener: _resource_record_post_action 11:56:51 INFO - [mozharness: 2017-01-11 19:56:51.222677Z] Finished read-buildbot-config step (success) 11:56:51 INFO - [mozharness: 2017-01-11 19:56:51.222895Z] Running download-and-extract step. 11:56:51 INFO - Running pre-action listener: _resource_record_pre_action 11:56:51 INFO - Running main action method: download_and_extract 11:56:51 INFO - mkdir: /builds/slave/test/build/tests 11:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:51 INFO - trying https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.test_packages.json 11:56:51 INFO - Downloading https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:56:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:56:51 INFO - Downloaded 896 bytes. 11:56:51 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:56:51 INFO - Using the following test package requirements: 11:56:51 INFO - {u'common': [u'target.common.tests.zip'], 11:56:51 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:56:51 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:56:51 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:56:51 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:56:51 INFO - u'mozbase': [u'target.common.tests.zip'], 11:56:51 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:56:51 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:56:51 INFO - u'web-platform': [u'target.common.tests.zip', 11:56:51 INFO - u'target.web-platform.tests.zip'], 11:56:51 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:56:51 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:56:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.common.tests.zip 11:56:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:56:51 INFO - Fetch https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.common.tests.zip into memory 11:56:52 INFO - Expected file size: 20257948 11:56:52 INFO - Obtained file size: 20257948 11:56:56 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:56:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.common.tests.zip 11:56:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:56:56 INFO - Fetch https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.common.tests.zip into memory 11:57:00 INFO - Expected file size: 20257948 11:57:00 INFO - Obtained file size: 20257948 11:57:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.talos.tests.zip 11:57:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:57:05 INFO - Fetch https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.talos.tests.zip into memory 11:57:05 INFO - Expected file size: 14403529 11:57:05 INFO - Obtained file size: 14403529 11:57: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')]} 11:57:06 INFO - trying https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2 11:57:06 INFO - Downloading https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:57:07 INFO - Downloaded 55987947 bytes. 11:57:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2 11:57:07 INFO - mkdir: /builds/slave/test/properties 11:57:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:57:07 INFO - Writing to file /builds/slave/test/properties/build_url 11:57:07 INFO - Contents: 11:57:07 INFO - build_url:https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2 11:57:07 INFO - Running post-action listener: _resource_record_post_action 11:57:07 INFO - Running post-action listener: set_extra_try_arguments 11:57:07 INFO - [mozharness: 2017-01-11 19:57:07.450033Z] Finished download-and-extract step (success) 11:57:07 INFO - [mozharness: 2017-01-11 19:57:07.450129Z] Running populate-webroot step. 11:57:07 INFO - Running pre-action listener: _resource_record_pre_action 11:57:07 INFO - Running main action method: populate_webroot 11:57:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:57:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:57:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:57:07 INFO - '--tppagecycles', 11:57:07 INFO - '7'], 11:57:07 INFO - 'tests': ['tsvgm']}}, 11:57:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:57:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:57:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:57:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:57:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['damp', 'tps']}, 11:57:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'tests': ['damp', 'tps']}, 11:57:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['dromaeo_dom']}, 11:57:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:57:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['basic_compositor_video']}, 11:57:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:57:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other-e10s': {'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['tsvgx', 11:57:07 INFO - 'tsvgr_opacity', 11:57:07 INFO - 'tart', 11:57:07 INFO - 'tscrollx', 11:57:07 INFO - 'cart', 11:57:07 INFO - 'tsvg_static']}, 11:57:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:57:07 INFO - 'tsvgr_opacity', 11:57:07 INFO - 'tart', 11:57:07 INFO - 'tscrollx', 11:57:07 INFO - 'cart', 11:57:07 INFO - 'tsvg_static']}, 11:57:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['tp5o']}, 11:57:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:57:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s', 11:57:07 INFO - '--xperf_path', 11:57:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:07 INFO - 'tests': ['tp5n']}, 11:57:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--xperf_path', 11:57:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:07 INFO - 'tests': ['tp5n']}}, 11:57:07 INFO - 'talos.zip': {'path': '', 11:57:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:57:07 INFO - Running post-action listener: _resource_record_post_action 11:57:07 INFO - [mozharness: 2017-01-11 19:57:07.463282Z] Finished populate-webroot step (success) 11:57:07 INFO - [mozharness: 2017-01-11 19:57:07.463377Z] Running create-virtualenv step. 11:57:07 INFO - Running pre-action listener: _resource_record_pre_action 11:57:07 INFO - Running main action method: create_virtualenv 11:57:07 INFO - Creating virtualenv /builds/slave/test/build/venv 11:57:07 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 11:57:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:57:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:57:07 INFO - Using real prefix '/usr' 11:57:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:08 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:10 INFO - Installing pip.................done. 11:57:10 INFO - Return code: 0 11:57:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:57:10 INFO - Reading from file tmpfile_stdout 11:57:10 INFO - Output received: 11:57:10 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) 11:57:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57:10 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')]} 11:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:10 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')]} 11:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:10 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:10 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 11:57:10 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 11:57:10 INFO - Using env: {'DISPLAY': ':0', 11:57:10 INFO - 'HOME': '/home/cltbld', 11:57:10 INFO - 'LANG': 'en_US.UTF-8', 11:57:10 INFO - 'LANGUAGE': 'en_US:en', 11:57:10 INFO - 'LOGNAME': 'cltbld', 11:57:10 INFO - 'MAIL': '/var/mail/cltbld', 11:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:10 INFO - 'MOZ_NO_REMOTE': '1', 11:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:10 INFO - 'NO_EM_RESTART': '1', 11:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:10 INFO - 'PWD': '/builds/slave/test', 11:57:10 INFO - 'SHELL': '/bin/bash', 11:57:10 INFO - 'SHLVL': '1', 11:57:10 INFO - 'TERM': 'linux', 11:57:10 INFO - 'TMOUT': '86400', 11:57:10 INFO - 'USER': 'cltbld', 11:57:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:10 INFO - '_': '/tools/buildbot/bin/python'} 11:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:10 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 11:57:10 INFO - Cleaning up... 11:57:10 INFO - Return code: 0 11:57:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57:10 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')]} 11:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:10 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')]} 11:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:10 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:10 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 11:57:10 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 11:57:10 INFO - Using env: {'DISPLAY': ':0', 11:57:10 INFO - 'HOME': '/home/cltbld', 11:57:10 INFO - 'LANG': 'en_US.UTF-8', 11:57:10 INFO - 'LANGUAGE': 'en_US:en', 11:57:10 INFO - 'LOGNAME': 'cltbld', 11:57:10 INFO - 'MAIL': '/var/mail/cltbld', 11:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:10 INFO - 'MOZ_NO_REMOTE': '1', 11:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:10 INFO - 'NO_EM_RESTART': '1', 11:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:10 INFO - 'PWD': '/builds/slave/test', 11:57:10 INFO - 'SHELL': '/bin/bash', 11:57:10 INFO - 'SHLVL': '1', 11:57:10 INFO - 'TERM': 'linux', 11:57:10 INFO - 'TMOUT': '86400', 11:57:10 INFO - 'USER': 'cltbld', 11:57:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:10 INFO - '_': '/tools/buildbot/bin/python'} 11:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:10 INFO - Downloading/unpacking psutil>=3.1.1 11:57: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 11:57: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 11:57: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 11:57: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 11:57: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 11:57: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 11:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:13 INFO - Installing collected packages: psutil 11:57:13 INFO - Running setup.py install for psutil 11:57:13 INFO - building 'psutil._psutil_linux' extension 11:57:13 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 11:57:14 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 11:57:14 INFO - building 'psutil._psutil_posix' extension 11:57:14 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 11:57:14 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 11:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:14 INFO - Successfully installed psutil 11:57:14 INFO - Cleaning up... 11:57:14 INFO - Return code: 0 11:57:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57:14 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')]} 11:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:14 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')]} 11:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:14 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:14 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 11:57:14 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 11:57:14 INFO - Using env: {'DISPLAY': ':0', 11:57:14 INFO - 'HOME': '/home/cltbld', 11:57:14 INFO - 'LANG': 'en_US.UTF-8', 11:57:14 INFO - 'LANGUAGE': 'en_US:en', 11:57:14 INFO - 'LOGNAME': 'cltbld', 11:57:14 INFO - 'MAIL': '/var/mail/cltbld', 11:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:14 INFO - 'MOZ_NO_REMOTE': '1', 11:57:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:14 INFO - 'NO_EM_RESTART': '1', 11:57:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:14 INFO - 'PWD': '/builds/slave/test', 11:57:14 INFO - 'SHELL': '/bin/bash', 11:57:14 INFO - 'SHLVL': '1', 11:57:14 INFO - 'TERM': 'linux', 11:57:14 INFO - 'TMOUT': '86400', 11:57:14 INFO - 'USER': 'cltbld', 11:57:14 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:14 INFO - '_': '/tools/buildbot/bin/python'} 11:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:15 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 11:57:15 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 11:57:15 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 11:57:15 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 11:57:15 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 11:57:15 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 11:57:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:57:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:57:17 INFO - Installing collected packages: mozsystemmonitor 11:57:17 INFO - Running setup.py install for mozsystemmonitor 11:57:17 INFO - Successfully installed mozsystemmonitor 11:57:17 INFO - Cleaning up... 11:57:17 INFO - Return code: 0 11:57:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:57:17 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')]} 11:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:17 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')]} 11:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:17 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:17 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 11:57:17 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 11:57:17 INFO - Using env: {'DISPLAY': ':0', 11:57:17 INFO - 'HOME': '/home/cltbld', 11:57:17 INFO - 'LANG': 'en_US.UTF-8', 11:57:17 INFO - 'LANGUAGE': 'en_US:en', 11:57:17 INFO - 'LOGNAME': 'cltbld', 11:57:17 INFO - 'MAIL': '/var/mail/cltbld', 11:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:17 INFO - 'MOZ_NO_REMOTE': '1', 11:57:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:17 INFO - 'NO_EM_RESTART': '1', 11:57:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:17 INFO - 'PWD': '/builds/slave/test', 11:57:17 INFO - 'SHELL': '/bin/bash', 11:57:17 INFO - 'SHLVL': '1', 11:57:17 INFO - 'TERM': 'linux', 11:57:17 INFO - 'TMOUT': '86400', 11:57:17 INFO - 'USER': 'cltbld', 11:57:17 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:17 INFO - '_': '/tools/buildbot/bin/python'} 11:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:18 INFO - Downloading/unpacking jsonschema==2.5.1 11:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:57:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:20 INFO - Downloading functools32-3.2.3-2.tar.gz 11:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:57:20 INFO - warning: no files found matching '*.txt' 11:57:20 INFO - no previously-included directories found matching 'build' 11:57:20 INFO - no previously-included directories found matching 'dist' 11:57:20 INFO - no previously-included directories found matching '.git*' 11:57:20 INFO - Installing collected packages: jsonschema, functools32 11:57:20 INFO - Running setup.py install for functools32 11:57:20 INFO - warning: no files found matching '*.txt' 11:57:20 INFO - no previously-included directories found matching 'build' 11:57:20 INFO - no previously-included directories found matching 'dist' 11:57:20 INFO - no previously-included directories found matching '.git*' 11:57:20 INFO - Successfully installed jsonschema functools32 11:57:20 INFO - Cleaning up... 11:57:20 INFO - Return code: 0 11:57:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:57:20 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')]} 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:20 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')]} 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:20 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:20 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 11:57:20 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 11:57:20 INFO - Using env: {'DISPLAY': ':0', 11:57:20 INFO - 'HOME': '/home/cltbld', 11:57:20 INFO - 'LANG': 'en_US.UTF-8', 11:57:20 INFO - 'LANGUAGE': 'en_US:en', 11:57:20 INFO - 'LOGNAME': 'cltbld', 11:57:20 INFO - 'MAIL': '/var/mail/cltbld', 11:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:20 INFO - 'MOZ_NO_REMOTE': '1', 11:57:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:20 INFO - 'NO_EM_RESTART': '1', 11:57:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:20 INFO - 'PWD': '/builds/slave/test', 11:57:20 INFO - 'SHELL': '/bin/bash', 11:57:20 INFO - 'SHLVL': '1', 11:57:20 INFO - 'TERM': 'linux', 11:57:20 INFO - 'TMOUT': '86400', 11:57:20 INFO - 'USER': 'cltbld', 11:57:20 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:20 INFO - '_': '/tools/buildbot/bin/python'} 11:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:57:20 INFO - Cleaning up... 11:57:20 INFO - Return code: 0 11:57:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:57:20 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')]} 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:20 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')]} 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:20 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:20 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 11:57:20 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 11:57:20 INFO - Using env: {'DISPLAY': ':0', 11:57:20 INFO - 'HOME': '/home/cltbld', 11:57:20 INFO - 'LANG': 'en_US.UTF-8', 11:57:20 INFO - 'LANGUAGE': 'en_US:en', 11:57:20 INFO - 'LOGNAME': 'cltbld', 11:57:20 INFO - 'MAIL': '/var/mail/cltbld', 11:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:20 INFO - 'MOZ_NO_REMOTE': '1', 11:57:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:20 INFO - 'NO_EM_RESTART': '1', 11:57:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:20 INFO - 'PWD': '/builds/slave/test', 11:57:20 INFO - 'SHELL': '/bin/bash', 11:57:20 INFO - 'SHLVL': '1', 11:57:20 INFO - 'TERM': 'linux', 11:57:20 INFO - 'TMOUT': '86400', 11:57:20 INFO - 'USER': 'cltbld', 11:57:20 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:20 INFO - '_': '/tools/buildbot/bin/python'} 11:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:21 INFO - Downloading/unpacking blobuploader==1.2.4 11:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:23 INFO - Downloading blobuploader-1.2.4.tar.gz 11:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:57:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:57:23 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 11:57:23 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 11:57:23 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 11:57:23 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 11:57:23 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 11:57:23 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 11:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:57:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:57:23 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 11:57:23 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 11:57:23 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 11:57:23 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 11:57:23 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 11:57:23 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 11:57:24 INFO - Downloading docopt-0.6.1.tar.gz 11:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:57:24 INFO - Installing collected packages: blobuploader, requests, docopt 11:57:24 INFO - Running setup.py install for blobuploader 11:57:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:57:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:57:24 INFO - Running setup.py install for requests 11:57:24 INFO - Running setup.py install for docopt 11:57:24 INFO - Successfully installed blobuploader requests docopt 11:57:24 INFO - Cleaning up... 11:57:24 INFO - Return code: 0 11:57:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:24 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:24 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 11:57:24 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 11:57:24 INFO - Using env: {'DISPLAY': ':0', 11:57:24 INFO - 'HOME': '/home/cltbld', 11:57:24 INFO - 'LANG': 'en_US.UTF-8', 11:57:24 INFO - 'LANGUAGE': 'en_US:en', 11:57:24 INFO - 'LOGNAME': 'cltbld', 11:57:24 INFO - 'MAIL': '/var/mail/cltbld', 11:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:24 INFO - 'MOZ_NO_REMOTE': '1', 11:57:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:24 INFO - 'NO_EM_RESTART': '1', 11:57:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:24 INFO - 'PWD': '/builds/slave/test', 11:57:24 INFO - 'SHELL': '/bin/bash', 11:57:24 INFO - 'SHLVL': '1', 11:57:24 INFO - 'TERM': 'linux', 11:57:24 INFO - 'TMOUT': '86400', 11:57:24 INFO - 'USER': 'cltbld', 11:57:24 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:24 INFO - '_': '/tools/buildbot/bin/python'} 11:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:24 INFO - Running setup.py (path:/tmp/pip-oAiuMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:25 INFO - Running setup.py (path:/tmp/pip-G0Sn1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:25 INFO - Running setup.py (path:/tmp/pip-HCUVx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:25 INFO - Running setup.py (path:/tmp/pip-lDKayl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:25 INFO - Running setup.py (path:/tmp/pip-fDBzaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:25 INFO - Running setup.py (path:/tmp/pip-Na9Ul5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:25 INFO - Running setup.py (path:/tmp/pip-Vr9PxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:25 INFO - Running setup.py (path:/tmp/pip-T1utyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:25 INFO - Running setup.py (path:/tmp/pip-pkuPxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:25 INFO - Running setup.py (path:/tmp/pip-UKvPiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:25 INFO - Running setup.py (path:/tmp/pip-mjTUgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:25 INFO - Running setup.py (path:/tmp/pip-7kA0mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:25 INFO - Running setup.py (path:/tmp/pip-2vJvMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:25 INFO - Running setup.py (path:/tmp/pip-wDRDtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:26 INFO - Running setup.py (path:/tmp/pip-MMZqMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:26 INFO - Running setup.py (path:/tmp/pip-GRo7oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:26 INFO - Running setup.py (path:/tmp/pip-xC_oQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:57:26 INFO - Running setup.py install for manifestparser 11:57:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Running setup.py install for mozcrash 11:57:26 INFO - Running setup.py install for mozdebug 11:57:26 INFO - Running setup.py install for mozdevice 11:57:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Running setup.py install for mozfile 11:57:26 INFO - Running setup.py install for mozhttpd 11:57:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Running setup.py install for mozinfo 11:57:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Running setup.py install for mozInstall 11:57:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozleak 11:57:27 INFO - Running setup.py install for mozlog 11:57:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for moznetwork 11:57:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozprocess 11:57:27 INFO - Running setup.py install for mozprofile 11:57:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozrunner 11:57:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozscreenshot 11:57:27 INFO - Running setup.py install for moztest 11:57:27 INFO - Running setup.py install for mozversion 11:57:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:27 INFO - Cleaning up... 11:57:27 INFO - Return code: 0 11:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:27 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')]} 11:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:27 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')]} 11:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:27 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:27 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 11:57:27 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 11:57:28 INFO - Using env: {'DISPLAY': ':0', 11:57:28 INFO - 'HOME': '/home/cltbld', 11:57:28 INFO - 'LANG': 'en_US.UTF-8', 11:57:28 INFO - 'LANGUAGE': 'en_US:en', 11:57:28 INFO - 'LOGNAME': 'cltbld', 11:57:28 INFO - 'MAIL': '/var/mail/cltbld', 11:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:28 INFO - 'MOZ_NO_REMOTE': '1', 11:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:28 INFO - 'NO_EM_RESTART': '1', 11:57:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:28 INFO - 'PWD': '/builds/slave/test', 11:57:28 INFO - 'SHELL': '/bin/bash', 11:57:28 INFO - 'SHLVL': '1', 11:57:28 INFO - 'TERM': 'linux', 11:57:28 INFO - 'TMOUT': '86400', 11:57:28 INFO - 'USER': 'cltbld', 11:57:28 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:28 INFO - '_': '/tools/buildbot/bin/python'} 11:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:28 INFO - Running setup.py (path:/tmp/pip-LPwqdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:28 INFO - Running setup.py (path:/tmp/pip-dp3HoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:28 INFO - Running setup.py (path:/tmp/pip-VrZkVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:28 INFO - Running setup.py (path:/tmp/pip-KWYVCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:28 INFO - Running setup.py (path:/tmp/pip-ktFtM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:28 INFO - Running setup.py (path:/tmp/pip-iUskRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:28 INFO - Running setup.py (path:/tmp/pip-i5Jt2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:28 INFO - Running setup.py (path:/tmp/pip-AaEI0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:28 INFO - Running setup.py (path:/tmp/pip-TdTr7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:28 INFO - Running setup.py (path:/tmp/pip-QpxNtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:28 INFO - Running setup.py (path:/tmp/pip-P9fzaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:28 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)) 11:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:29 INFO - Running setup.py (path:/tmp/pip-TVlRD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:29 INFO - Running setup.py (path:/tmp/pip-yI3ASx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:29 INFO - Running setup.py (path:/tmp/pip-ymAlYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:29 INFO - Running setup.py (path:/tmp/pip-G7PHb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:29 INFO - Running setup.py (path:/tmp/pip-lPEZ2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:29 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)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:29 INFO - Running setup.py (path:/tmp/pip-819kz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:29 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)) 11:57:29 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)) 11:57:29 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)) 11:57:29 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)) 11:57:29 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)) 11:57:29 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)) 11:57:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:31 INFO - Downloading blessings-1.6.tar.gz 11:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:57:31 INFO - Installing collected packages: blessings 11:57:31 INFO - Running setup.py install for blessings 11:57:31 INFO - Successfully installed blessings 11:57:31 INFO - Cleaning up... 11:57:31 INFO - Return code: 0 11:57:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:57:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:31 INFO - Reading from file tmpfile_stdout 11:57:31 INFO - Current package versions: 11:57:31 INFO - argparse == 1.2.1 11:57:31 INFO - blessings == 1.6 11:57:31 INFO - blobuploader == 1.2.4 11:57:31 INFO - docopt == 0.6.1 11:57:31 INFO - functools32 == 3.2.3-2 11:57:31 INFO - jsonschema == 2.5.1 11:57:31 INFO - manifestparser == 1.1 11:57:31 INFO - mozInstall == 1.12 11:57:31 INFO - mozcrash == 1.0 11:57:31 INFO - mozdebug == 0.1 11:57:31 INFO - mozdevice == 0.49 11:57:31 INFO - mozfile == 1.2 11:57:31 INFO - mozhttpd == 0.7 11:57:31 INFO - mozinfo == 0.9 11:57:31 INFO - mozleak == 0.1 11:57:31 INFO - mozlog == 3.4 11:57:31 INFO - moznetwork == 0.27 11:57:31 INFO - mozprocess == 0.24 11:57:31 INFO - mozprofile == 0.28 11:57:31 INFO - mozrunner == 6.13 11:57:31 INFO - mozscreenshot == 0.1 11:57:31 INFO - mozsystemmonitor == 0.3 11:57:31 INFO - moztest == 0.8 11:57:31 INFO - mozversion == 1.4 11:57:31 INFO - psutil == 3.1.1 11:57:31 INFO - requests == 1.2.3 11:57:31 INFO - wsgiref == 0.1.2 11:57:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:31 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 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:31 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 11:57:31 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 11:57:31 INFO - Using env: {'DISPLAY': ':0', 11:57:31 INFO - 'HOME': '/home/cltbld', 11:57:31 INFO - 'LANG': 'en_US.UTF-8', 11:57:31 INFO - 'LANGUAGE': 'en_US:en', 11:57:31 INFO - 'LOGNAME': 'cltbld', 11:57:31 INFO - 'MAIL': '/var/mail/cltbld', 11:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:31 INFO - 'MOZ_NO_REMOTE': '1', 11:57:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:31 INFO - 'NO_EM_RESTART': '1', 11:57:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:31 INFO - 'PWD': '/builds/slave/test', 11:57:31 INFO - 'SHELL': '/bin/bash', 11:57:31 INFO - 'SHLVL': '1', 11:57:31 INFO - 'TERM': 'linux', 11:57:31 INFO - 'TMOUT': '86400', 11:57:31 INFO - 'USER': 'cltbld', 11:57:31 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:31 INFO - '_': '/tools/buildbot/bin/python'} 11:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:57:32 INFO - Cleaning up... 11:57:32 INFO - Return code: 0 11:57:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x221fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, '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': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', '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 11:57:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:57:32 INFO - Using env: {'DISPLAY': ':0', 11:57:32 INFO - 'HOME': '/home/cltbld', 11:57:32 INFO - 'LANG': 'en_US.UTF-8', 11:57:32 INFO - 'LANGUAGE': 'en_US:en', 11:57:32 INFO - 'LOGNAME': 'cltbld', 11:57:32 INFO - 'MAIL': '/var/mail/cltbld', 11:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:32 INFO - 'MOZ_NO_REMOTE': '1', 11:57:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:32 INFO - 'NO_EM_RESTART': '1', 11:57:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:32 INFO - 'PWD': '/builds/slave/test', 11:57:32 INFO - 'SHELL': '/bin/bash', 11:57:32 INFO - 'SHLVL': '1', 11:57:32 INFO - 'TERM': 'linux', 11:57:32 INFO - 'TMOUT': '86400', 11:57:32 INFO - 'USER': 'cltbld', 11:57:32 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:32 INFO - '_': '/tools/buildbot/bin/python'} 11:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:57:32 INFO - Cleaning up... 11:57:32 INFO - Return code: 0 11:57:32 INFO - Running post-action listener: _resource_record_post_action 11:57:32 INFO - Running post-action listener: _start_resource_monitoring 11:57:32 INFO - Starting resource monitoring. 11:57:32 INFO - [mozharness: 2017-01-11 19:57:32.491529Z] Finished create-virtualenv step (success) 11:57:32 INFO - [mozharness: 2017-01-11 19:57:32.492008Z] Running install step. 11:57:32 INFO - Running pre-action listener: _resource_record_pre_action 11:57:32 INFO - Running main action method: install 11:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:32 INFO - Reading from file tmpfile_stdout 11:57:32 INFO - Detecting whether we're running mozinstall >=1.0... 11:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:57:32 INFO - Reading from file tmpfile_stdout 11:57:32 INFO - Output received: 11:57:32 INFO - Usage: mozinstall [options] installer 11:57:32 INFO - Options: 11:57:32 INFO - -h, --help show this help message and exit 11:57:32 INFO - -d DEST, --destination=DEST 11:57:32 INFO - Directory to install application into. [default: 11:57:32 INFO - "/builds/slave/test"] 11:57:32 INFO - --app=APP Application being installed. [default: firefox] 11:57:32 INFO - mkdir: /builds/slave/test/build/application 11:57:32 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'] 11:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:57:48 INFO - Reading from file tmpfile_stdout 11:57:48 INFO - Output received: 11:57:48 INFO - /builds/slave/test/build/application/firefox/firefox 11:57:48 INFO - Running post-action listener: _resource_record_post_action 11:57:48 INFO - [mozharness: 2017-01-11 19:57:48.907246Z] Finished install step (success) 11:57:48 INFO - [mozharness: 2017-01-11 19:57:48.907525Z] Running run-tests step. 11:57:48 INFO - Running pre-action listener: _resource_record_pre_action 11:57:48 INFO - Running main action method: run_tests 11:57:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:57:48 INFO - Python 2.7.3 11:57:48 INFO - Return code: 0 11:57:48 INFO - grabbing minidump binary from tooltool 11:57:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:48 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 0x221d3f0>, '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 0x221dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221e1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:57:48 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 11:57:48 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 11:57:48 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 11:57:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:57:49 INFO - Return code: 0 11:57:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:57:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:57:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:57:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:57:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:57:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 11:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 11:57:49 INFO - Using env: {'DISPLAY': ':0', 11:57:49 INFO - 'HOME': '/home/cltbld', 11:57:49 INFO - 'LANG': 'en_US.UTF-8', 11:57:49 INFO - 'LANGUAGE': 'en_US:en', 11:57:49 INFO - 'LOGNAME': 'cltbld', 11:57:49 INFO - 'MAIL': '/var/mail/cltbld', 11:57:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:57:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:49 INFO - 'MOZ_NO_REMOTE': '1', 11:57:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:57:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:49 INFO - 'NO_EM_RESTART': '1', 11:57:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:49 INFO - 'PWD': '/builds/slave/test', 11:57:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:57:49 INFO - 'SHELL': '/bin/bash', 11:57:49 INFO - 'SHLVL': '1', 11:57:49 INFO - 'TERM': 'linux', 11:57:49 INFO - 'TMOUT': '86400', 11:57:49 INFO - 'USER': 'cltbld', 11:57:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658', 11:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:49 INFO - '_': '/tools/buildbot/bin/python'} 11:57:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 11:57:49 INFO - mozversion application_buildid: 20170111154659 11:57:49 INFO - mozversion application_changeset: 3aacbf7e3899edf7f993062d2d691f890c902f41 11:57:49 INFO - mozversion application_display_name: Nightly 11:57:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:57:49 INFO - mozversion application_name: Firefox 11:57:49 INFO - mozversion application_remotingname: firefox 11:57:49 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 11:57:49 INFO - mozversion application_vendor: Mozilla 11:57:49 INFO - mozversion application_version: 53.0a1 11:57:49 INFO - mozversion platform_buildid: 20170111154659 11:57:49 INFO - mozversion platform_changeset: 3aacbf7e3899edf7f993062d2d691f890c902f41 11:57:49 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 11:57:49 INFO - mozversion platform_version: 53.0a1 11:57:49 INFO - using testdate: 1484164669 11:57:49 INFO - actual date: 1484164669 11:57:49 INFO - starting webserver on 'localhost:55914' 11:57:49 INFO - SUITE-START | Running 2 tests 11:57:49 INFO - TEST-START | dromaeo_css 11:57:49 INFO - operating with platform_type : linux_ 11:57:49 INFO - Initialising browser for dromaeo_css test... 11:57:49 INFO - TEST-INFO | started process 3329 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCLylF7/profile http://localhost:55914/getInfo.html) 11:57:54 INFO - PROCESS | 3329 | __metrics Screen width/height:1600/1200 11:57:54 INFO - PROCESS | 3329 | colorDepth:24 11:57:54 INFO - PROCESS | 3329 | Browser inner width/height: 1024/697 11:57:54 INFO - PROCESS | 3329 | __metrics 11:57:55 INFO - PROCESS | 3329 | 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" 11:57:55 INFO - PROCESS | 3329 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:57:55 INFO - PROCESS | 3329 | console.error: PushService: 11:57:55 INFO - PROCESS | 3329 | stateChangeProcessEnqueue: Error transitioning state 11:57:55 INFO - PROCESS | 3329 | UnknownError 11:57:56 INFO - TEST-INFO | 3329: exit 0 11:57:56 INFO - Browser initialized. 11:57:56 INFO - Running cycle 1/1 for dromaeo_css test... 11:57:56 INFO - TEST-INFO | started process 3410 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCLylF7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:08 INFO - PROCESS | 3410 | RSS: Main: 152059904 11:58:08 INFO - PROCESS | 3410 | 12:01:14 INFO - PROCESS | 3410 | Cycle 1(1): loaded http://localhost:55914/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55914/tests/dromaeo/cssquery-ext.html) 12:01:15 INFO - PROCESS | 3410 | RSS: Main: 255979520 12:01:15 INFO - PROCESS | 3410 | 12:02:57 INFO - PROCESS | 3410 | ************************* 12:02:57 INFO - PROCESS | 3410 | A coding exception was thrown and uncaught in a Task. 12:02:57 INFO - PROCESS | 3410 | 12:02:57 INFO - PROCESS | 3410 | Full message: ReferenceError: fetch is not defined 12:02:57 INFO - PROCESS | 3410 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:02:57 INFO - PROCESS | 3410 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:02:57 INFO - PROCESS | 3410 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:02:57 INFO - PROCESS | 3410 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:02:57 INFO - PROCESS | 3410 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:02:57 INFO - PROCESS | 3410 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:02:57 INFO - PROCESS | 3410 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:02:57 INFO - PROCESS | 3410 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:02:57 INFO - PROCESS | 3410 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:02:57 INFO - PROCESS | 3410 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:02:57 INFO - PROCESS | 3410 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:02:57 INFO - PROCESS | 3410 | 12:02:57 INFO - PROCESS | 3410 | ************************* 12:04:18 INFO - PROCESS | 3410 | Cycle 1(1): loaded http://localhost:55914/tests/dromaeo/cssquery-ext.html (next: http://localhost:55914/tests/dromaeo/cssquery-jquery.html) 12:04:18 INFO - PROCESS | 3410 | RSS: Main: 241811456 12:04:18 INFO - PROCESS | 3410 | 12:07:21 INFO - PROCESS | 3410 | Cycle 1(1): loaded http://localhost:55914/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55914/tests/dromaeo/cssquery-mootools.html) 12:07:21 INFO - PROCESS | 3410 | RSS: Main: 187031552 12:07:21 INFO - PROCESS | 3410 | 12:10:23 INFO - PROCESS | 3410 | Cycle 1(1): loaded http://localhost:55914/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55914/tests/dromaeo/cssquery-prototype.html) 12:10:24 INFO - PROCESS | 3410 | RSS: Main: 203931648 12:10:24 INFO - PROCESS | 3410 | 12:13:27 INFO - PROCESS | 3410 | Cycle 1(1): loaded http://localhost:55914/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55914/tests/dromaeo/cssquery-yui.html) 12:13:28 INFO - PROCESS | 3410 | RSS: Main: 191000576 12:13:28 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | Cycle 1(1): loaded http://localhost:55914/tests/dromaeo/cssquery-yui.html (next: http://localhost:55914/tests/dromaeo/cssquery-dojo.html) 12:16:31 INFO - PROCESS | 3410 | RSS: Main: 277667840 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | __start_tp_report 12:16:31 INFO - PROCESS | 3410 | _x_x_mozilla_page_load 12:16:31 INFO - PROCESS | 3410 | _x_x_mozilla_page_load_details 12:16:31 INFO - PROCESS | 3410 | |i|pagename|runs| 12:16:31 INFO - PROCESS | 3410 | |0;dojo.html;2568.8844002019905;2600.427905860712;2627.894884204632;2636.4858852523757;2641.7754490868274;4717.29240613908;4768.833090841821;4791.712497250168;4807.495213002632;4819.1084649339855;4788.281757736341;4802.471728109905;4803.471618122013;4809.158397280479;4809.831655892044;4392.3191905254735;4409.845655402061;4436.689431739779;4448.310511870649;4473.396091527651;4361.629261512775;4522.186006518826;4533.546645335457;4554.225781617117;4558.84044058458;4378.036831896993;4438.4451943507065;4438.644908407319;4450.354698568705;4477.395551600541;4477.104579084196;4492.326151077332;4494.370788089654;4504.752238626874;4552.9755804943625;4322.956770432287;4417.624501917341;4465.531119232466;4472.843450479218;4486.955130448687;4826.22780355145;4842.322074909515;4852.514748525119;4860.295257187723;4871.098846713369;5148.794048238066;5157.071727089123;5176.767045482962;5197.974010129925;5207.375114986188;5076.390833100015;5214.765335559909;5234.057869583474;5241.030409374278;5244.292020577212;4747.145513807548;4770.0936822004405;4772.49888761682;4789.161896668069;4854.344663470386;5261.842144735664;5262.289590905255;5264.52619264268;5264.894702106013;5265.7103859287345;5045.899082018413;5046.44489106198;5049.798008079709;5053.0399224147295;5085.771140298695;5049.394072711299;5094.719790411563;5114.667546609533;5116.9744151279;5141.2545180948555;5162.328897243335;5163.66436181647;5164.715940623302;5203.531682148625;5211.843644690665;5147.6911385317;5153.484651534816;5162.406323272871;5164.0962831505085;5168.61235407346;4290.099079193342;4326.935095973563;4331.826726930951;4380.7366789844145;4392.824287028483;3785.1104990327253;3812.8093595320124;3825.158465137665;3840.6543411092575;3850.249201405699;4280.379344995021;4335.696501244883;4396.604305612446;4400.73595584266;4405.229084910128;5104.540591346722;5150.896982060338;5153.639245252796;5165.1735722285;5167.250748641502;4413.7572433515825;4428.114377124588;4430.269005614102;4433.179861725591;4462.509123996358;4577.22187228172;4605.585497305258;4641.071785642885;4665.463471700662;4684.063187362541;4879.365682461257;4890.80436782539;4896.1921282987905;4898.21628539432;4899.461059283547;5234.398044224963;5238.371395432572;5246.658967167122;5248.6325957182635;5250.422453530185;4876.439209490812;4882.170031094794;4882.633802464758;4883.706977581356;4887.60899128076;5112.795488180431;5137.229415587692;5139.331886854685;5140.897182056413;5141.126008578476;5092.414372347227;5114.411842638145;5125.410577783454;5173.7671804769625;5227.215917612389;5047.924281135713;5053.418856831511;5055.545000949932;5067.670601410897;5093.108705976513;5033.245013248042;5037.471065538066;5039.924401133912;5060.240963855451;5061.544460998528;4636.698614590041;4818.156822556109;4860.562549370574;4949.480304568113;4995.350604421547;5078.898422031465;5085.542301192911;5086.313347698014;5088.745562721923;5142.717150556755;4964.553190212898;4967.081089998362;4970.080535101018;5001.9245862139815;5002.399712034579 12:16:31 INFO - PROCESS | 3410 | |1;ext.html;534.156033467122;547.1792311532704;550.3423409026215;555.1755642870339;567.9943200567996;29703.40593188135;30423.391532169342;32554.511682324726;32667.67332326676;32668;33623.15942101449;42150.57849421505;43296.567034329724;43430.348544771856;47912.52087479124;7845.529268243895;8033.357331413473;8035.316998055172;8035.919640803576;8038.839223215532;7872.763817085496;7973.60131993398;7973.60131993401;7976.840463190732;7988.081370642392;7691.769246922573;7900.170482099383;10224.182065434747;10508.369497830117;10516.474176291193;10308.793824123513;10365;10385.740356491073;10389.84415233772;10396.220283478731;10176.898231017743;10275.794484110276;10285.845712314322;10290.3825770454;10291.94854025725;40431.1913761726;40670;40794;40852.59147408517;40857.387139192935;3688.2623475305045;3754.4743735877;3763.5860055393887;3779.79211143387;3819.427085937104;29606.555901661493;29666.25834354137;29923.952661656956;30024.69975300241;31362.21594460134;3484.8083355415442;3488.66857648523;3489.7208223342072;3495.5980062292906;3540.716742660581;256792;257913;258571;259267;259400.9999999981;55063.724681376334;55382.72308638431;55395;55417.44582554203;55439.44560554343;51743;51775;51776;51782;51879.221811672855;12981.286029268293;13288.202707837561;13300;13305.268210248336;13312.866871331356;9847.261455390731;9863.605455781832;9883.950580247052;9895.109440150422;9896.703098907044;14489.130652160904;15907.204639768197;16489.6702065958;16489.6702065958;16560.585985350222;5206.947930520646;6194.194754681863;6471.352864713585;6575.416398063067;6616.305287944697;814.6700586262573;832.3008672714949;838.3377132065722;852.8422241885271;868.1665601022962;27900.16299511018;28254.576181357297;28363.440280399227;28405.57391639127;28694.856525717238;900.7793090692691;978.7112801723508;980.4558470049049;984.7095106943469;1019.1337363241333;14784.704305914036;15182.772258416133;15328.386864525517;15476.922615386851;15601.84398156004;340976.54140751634;341862;342986;343168;343463;52224.216636750476;52514.47485525172;52543.474565254626;52964.470355296726;53084;1210.8546974362957;1250.4310538704867;1259.5780413561376;1264.652220639313;1271.510468469646;25168.49663006766;26181;27040.72959270382;27680.58479122855;27741.306467337672;43378;43916.75373563326;44004.11991760211;44009.33985990148;44061;278780;278824;278838;279111;280062;35819.28361432809;36412.635873640924;36529.086772830895;36600;36778;40644.051277460814;40852.79573602113;40923.38614920719;41901.79049104735;42136.789316053226;34835.65164348324;35417;35671;35756.46365304574;35952.28095438129;35193;35463.290734185684;35516.64483355237;35791.28417431585;35929.28141437104;34689.65310346864;35076;35319.64680353266;35510.112247194025;35837.64162358343;29189.41621167607;31648.367032659677;31755;31787.6821231794;31964.6803531971;24154;24156.275311740676;24277.635835462836;24348;24496 12:16:31 INFO - PROCESS | 3410 | |2;jquery.html;366.73045311695915;391.9196564704236;392.621120618603;394.8025987006497;424.6687583684727;12699.466138335565;14553.41786328547;15230.390784368627;18773.155208015636;18868.528286792836;11675.532978680843;16862.241199146036;17276.654466910655;17342.47972560822;17469.7379539307;4835.274708793691;5005.54950054495;5016.749162541876;5017.774200160983;5020.39755229373;4920.040592084545;4934.185859333224;4963.305137280783;5409.56723462122;5667.149927510865;5741.282339707536;5753.539716822645;5757.165211044396;5760.913586296209;5761.884762304751;5413.431589683066;5638.40796716343;5684.403137670568;5745.6265342752795;5745.885082298372;5624.21261023457;5634.04221282383;5639.661620302795;5639.971800141014;5640.858978525529;13484.12353197039;17135.22891469887;22645;22851;22955.196568120034;2440.389902524369;2476.1333533263396;2477.3311205974287;2546.350680576441;2546.4525127097763;9720.75698107546;9753.317267791257;9957.65148219816;10172.742804048354;10290.176785857113;1723.3106757297182;1753.684336819372;1761.7093179625347;1791.802901680814;1816.0556510614406;79931.6003419985;79953;80168.59915700443;84205;86540.13459865384;10790.514426850654;10856;10944.616938407118;11151.275167114112;11177.261570915016;14786.778198327034;15203.239838008056;15224.87889982551;15290.541283761506;15320.616984575474;7161.9641901790155;7317.926820731831;7333.926660733431;7453.701851925971;7496.512726672749;6680.899786503206;6741.662916854138;6896.034555162281;6900.827479313058;6922.203946546111;4588.701734387254;4634.23535116702;4682.839083618544;4727.763611819395;4753.310769938409;1856.0905156473232;1925.5763731978943;1929.778075521333;1956.863019588615;1966.7443232379699;782.5343920367372;826.900771907375;831.983360332802;832.6461253967161;841.1000229754104;17418.17109203541;18181.363652272106;18416.027672528344;18652;18689;790.727199116304;806.7015204374302;811.975640730779;821.4783612406046;826.5206180415345;9638.566264518113;9925.404475731335;10099.747506312255;10278.561001459802;10297.691069267934;476926;479865;480745;481445;486793;91006;92206.53896730607;92306;92611.53694231485;93258.53370733239;907.9001309855911;921.428714197202;937.9859302110474;939.3049143633662;945.6879229854131;15413.460528881435;15487.148206848731;15930.522084337488;16085.195740212708;16328;38979;39386.606133938294;39753.60246397499;39885.202295953335;40132.79933600313;55304.44695553154;55733.721331393084;56009.71995140161;56349;58298;8048.436609437517;8197.631106600098;8227.588620569068;8230.711925082593;8341.040780310099;10945.398003109587;12533.87466125327;12632.11575189728;12829.986027338637;13162.670933226364;8741.694040708544;9961.950190249003;10133.695989120339;10651.680449586525;11060.834087488854;10673.35959842412;10982.560697572328;11165.162612804235;11257.947139030106;11469.655910322703;9789.657361992297;9813.362131461432;10018.549165287433;10389.168866490814;10952.507137179005;8117;8297.419180657538;8299.543525106177;8386.412951093365;8394.405063038002;9509.381890177116;10661.147108231167;10713.250072494851;10966.890331096587;11346.262492937933 12:16:31 INFO - PROCESS | 3410 | |3;mootools.html;1350.9256990865501;1360.2994457854206;1368.1312366647182;1375.77299745542;1387.4242189491358;6747.831304217397;7106.96446517768;7124.893126603105;7127.28727127287;7135.678894449749;6155.26136863577;6335.809925702236;6347.49220062395;6459;7004.299570042993;1868.6169335286233;2088.33173384517;2143.1748776720997;2160.686700428437;2174.7281589801273;2091.8221951134174;2121.1939463004037;2123.1719629344566;2131.4138611881704;2131.7761635028255;1823.1157888424104;2141.379000089972;2148.1729534129395;2153.9784602154014;2155.1271734947295;1826.8173182681758;2032.1566549881782;2086.968695469569;2087.4676957375773;2103.8842863642494;2071.3785864240817;2073.8963051847345;2076.3355726167633;2081.2299449203892;2081.323569839808;7073.610951397671;7129.322714342129;7133.429325653935;7138.179109402433;7150.1777295611155;4168.145530166309;4213.157368526307;4274.7435153890865;4362.411074504933;4402.801873915681;2702.27038699552;2899.5070837957596;2941.911742647724;2956.5121754910415;2958.6449626045014;1097.8902109789037;1109.2512554026;1109.5339957217989;1127.7688073944903;1154.0248490025915;9141.634334626608;9144.725658230263;9151.130642588943;9162.725118246462;9168.954155229248;3843.8270277837564;3998.7400818946676;4011.6590089842107;4064.3700226464352;4070.9796451018146;7669.271419215165;7671.2712292332135;7674.386049116058;7685.730999414994;7705.22947705225;4287.2711639021445;4289.678274129453;4293.677974151951;4298.290782020931;4382.627476664456;3975.741576797499;4089.734167279117;4102.487189101363;4103.076807718299;4108.465899433055;3469.3061387722555;3492.860285588599;3570.232400034005;3588.4617307404096;3588.515550400715;1172.8475298211179;1186.1044911092242;1198.1253684809963;1198.5864876617552;1204.6626944455566;691.640347019547;694.8506071194718;706.3148745716646;711.295817141037;737.1743647115159;4752.453467851172;4814.975925120422;4913.361263035854;4933.555979961749;4976.825811096594;537.5672583570185;552.1882832236606;559.6194587680335;559.9832005039774;565.4967079299427;3154.227214332503;3154.6214454265637;3304.3721692878275;3432.828358582061;3629.9836045907186;7864.449488535862;8197.754067377988;8235.670573177125;8258.545779982158;8329.5418751968;8069.368077137635;8246.505209687439;8248.587570621565;8256.793580160544;8260.132686067996;6486.513511486657;6566;6666.366695163952;6670.899936500957;6673.432758215509;4878.829240976549;4924.384451943507;4950.084234416644;5100.081985242692;5137.922931155961;7328.706851725871;7633.160352361347;7772.494787838772;7805.414593905593;7809.570473624005;7845.921540784748;7891.763247102596;7902.328302094386;7983.32141767933;7990.200979901963;5759.337676167294;5948.256467941507;6392.904106438315;6627.668616569249;6666.566673166229;6821.795346139624;6830.419414349836;6833.3166683331265;6861.519693621399;6864.5881247125335;6000.639961602318;6002.609830361012;6140.723667435065;6145.9692701536205;6171.56799024064;5882.705864706833;5905.379935106752;6049.062395328731;6080.118382834553;6111.786087487095;5433.402325744244;5496.203050557727;5496.285482887199;5504.697241651747;5516.227728117987;4120.7321524100835;4144.067584793397;4155.210510003089;4207.4319966805315;4292.291771857734;3990.0822810753107;3996.620321069494;4268.701190916606;4292.3561465780385;4304.117655880599 12:16:31 INFO - PROCESS | 3410 | |4;prototype.html;357.3603250182174;358.80983078968154;369.7744375930683;379.4876916163178;380.26039353457537;4717.292406139072;4799.400074990626;4892.779824907879;4959.6528243023;4981.128302547053;4673.392458980328;4771.642126840484;4806.567408933195;4808.374911261531;4821.783019764111;3918.0810296329273;3918.4710064141404;3942.231264903345;3942.369220924653;3955.1298714282957;3776.848926042955;3881.6312450317173;3958.3270843956598;3965;3974.5627980922077;3800.163963927945;3857.4406711026886;3886.300465916134;3890.4164375343785;3896.3960586109197;3853.97666225234;3856.151646637735;3871.2257548490416;3872.341701910682;3890.5525864525657;3504.456809194579;3521.7358698097487;3523.6476352364816;3529.699975502085;3539.3983022885914;4559.863204103882;4698.7650617469335;4713.481517033124;4723.881902952419;4758.214894542396;4447.666425018137;4454;4454.175977444183;4454.57681520255;4475.127350166719;5051.368578927634;5075.593952483792;5077.49225077493;5090.2364645303505;5106.259592359124;3436.879709210191;3598.1004748812798;3628.3468975584383;3865.1303456722294;3872.419137129425;5227.163653815372;5237.423883372826;5245.186996015624;5260.9478104378895;5271.759502211583;3695.6304369562827;3705.94441083384;3709.1283548366114;3713.4986776785327;3716.275326311343;3654.9634503655157;3760.9623903761158;3762;3772.8490860365278;3791.450239715226;4211.410402543646;4219.177260434247;4243.7029407941145;4262.680298977589;4273.487181538236;5084.144287335096;5088.084144853962;5088.516590923857;5109.56568691658;5114;1179.1804695736553;1223.62067758995;1249.6563445052502;1266.1959655618564;1271.815586739936;845.940784145104;858.3991206155716;858.8196478739535;862.9266512346395;862.9697960571475;4231.682623803165;4271.6369108625495;4284.078923031501;4289.806958686804;4307.56924307573;5053.166227572483;5055.143843728281;5085.821996230115;5088.211327244283;5105.285260063594;4110.486189226347;4246.171996460661;4249.553796851348;4251.511076226212;4281.266087234675;2646.5765477523887;2649.191996441104;2671.465706858597;2674.558697814839;2686.6238726578295;4671.7430541320555;4680.391549098664;4687.460941991646;4712.528747125329;4716.8113275468595;4954.984228233204;5072.873178170576;5103.744812759347;5113.64204505681;5126.666766660155;4672.789724462407;4677.76611169447;4677.965085187954;4681.789319480564;4700.764961751899;4946.318251794407;4987.9750601248215;5009.323741294878;5017.222330538772;5027.963674711747;4650.720956742606;4903.6077113831525;4922.040202160688;4935.876603084952;4942.55517003457;4677.906441871211;4679.836205732783;4693.647976401852;4715.316279139574;4716.693414928019;3513.2446523997464;3554.240650877832;3555.8222088894936;3578.5526809148855;3587.336342776595;4705.106029854317;4971.328870602422;4999.77501012448;5015.272785446017;5033.320501732365;4829.227323628203;4840.588549973291;4858.352198052141;4860.3195552623365;4896.853094407174;4856.174450343379;4865.537773911473;4874.683145595525;4883.753473402133;4922.950770492249;4658.324542941323;4796.37647105874;4818.927716084191;4854.713500922187;4862.489438609036;4889.413270407432;4890.92663610039;4920.507949205194;4936.925946110883;4972.950270497394;4851.199552073868;4857.489963553776;4857.854264372075;4880.92678609814;4894.975525122351 12:16:31 INFO - PROCESS | 3410 | |5;yui.html;671.7480944645757;677.9491787729019;740.333699670297;772.5441989226357;798.940079494038;12291.323977181251;12484.18852774569;12537.749245015106;12573.811392829091;12585.685357866047;12106.273623582569;12110.455029523671;12128;12135.635930922064;12136.575219867307;634.4892361648871;648.1509222917969;772.4631381190076;773.4237992695436;780.8047988003;797.7726347990824;798.6845196147518;799.1768478467188;799.2087833045301;799.3085980626774;848.0247715127591;850.3537311643165;850.5832142250284;851.2423942691009;851.4550687559966;674.6087269383746;685.6811582614058;809.6397103289039;826.6445428465757;838.5723281126608;610.6702380714408;611.5352332227494;611.5871786544064;612.1124369664005;612.13077430049;12754.61736147917;12794.936025319814;12799.87200127996;12801.103922725413;12815.679608009781;12037.578684745991;12083.637490875288;12184.329861857594;12255.877441225563;12605;9244.168024877794;10618.203634727426;10696.893031069667;11246.831297530543;11401.315921044681;3929.057026313693;3963.0686788604653;4023.698222633285;4055.695822813271;4096.713230073896;66747.99512029246;67900.32099678989;68035;69167.3083269166;70203;9505.42967421957;9641.37331073478;9748.709053372451;9821.263405244636;9909.35589186715;16845;16899.746503802453;16922.49232523026;17311.220995055253;17408.82591174072;6462.676866156673;6515.869682606416;6702.83242918931;6736.292689267556;6801.523893327519;3584.318979393907;3771.905702357463;3773.2830762155104;3782;3782.130110074698;2972.301509145392;2990.9551356729667;3049.9390012199633;3055.297281625194;3064.8161110333454;509.06078285563075;532.6537750462153;548.0792268988138;553.0736017171222;565.1861319699619;639.7409049334934;655.6459511863541;671.4091599392503;672.0644543714072;676.4284179868004;8942.239909607757;9121.954390228138;9173.541322933826;9267.536623168815;9430.19843313326;633.3002032753833;634.3466229783238;634.7492740367529;635.4312889963456;639.7628002308242;4327.978360108243;4491.73049617024;4577.3362862384765;4609.446866375989;4627.421572303462;67073.6646316775;68994.65502672455;69080;69846;71451.64274178595;60395;60530;60858;61503;63668.36331636717;41531;42834;44625;54530.72734636301;55029.72485137549;9125.5437228137;9249.907500924905;9320.254379649747;9392.49336962416;9493.41368407486;30446.391072178874;34544.82727586447;36443.63556364403;36722.63277367192;36777.264454711294;43512.34731479094;43811;44440.777796110815;44523.55476445194;45471;11544.826827597424;11602.94198529002;11686.824697629712;11905.821412678644;12355.69110772238;12145.392730363623;12278.938605307274;12355.382230888239;12995.350232488527;13128.540501082416;12058.638240852788;12130.939345303217;12265.26408415498;12801.359932003548;12879.227246364873;11981.341026243641;11992.340421276565;12130.150889437655;12580;12643.7814521928;11944.581939632419;12040.397980100784;12316.322602256603;12457.314847683465;12819.871801282241;10240.48797560134;10313.98877218935;10516.78966420652;10649.041586257274;10801;10010.249231307535;10359.792804143724;10414.166866650801;10434.582616695247;10449.529771160163 12:16:31 INFO - PROCESS | 3410 | __end_tp_report 12:16:31 INFO - PROCESS | 3410 | __start_cc_report 12:16:31 INFO - PROCESS | 3410 | _x_x_mozilla_cycle_collect,1213 12:16:31 INFO - PROCESS | 3410 | __end_cc_report 12:16:31 INFO - PROCESS | 3410 | __startTimestamp1484165791608__endTimestamp 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | ------- Summary: start ------- 12:16:31 INFO - PROCESS | 3410 | Number of tests: 6 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | [#0] dojo.html Cycles:165 Average:4769.06 Median:4883.71 stddev:513.64 (10.5%) stddev-sans-first:485.35 12:16:31 INFO - PROCESS | 3410 | Values: 2568.9 2600.4 2627.9 2636.5 2641.8 4717.3 4768.8 4791.7 4807.5 4819.1 4788.3 4802.5 4803.5 4809.2 4809.8 4392.3 4409.8 4436.7 4448.3 4473.4 4361.6 4522.2 4533.5 4554.2 4558.8 4378.0 4438.4 4438.6 4450.4 4477.4 4477.1 4492.3 4494.4 4504.8 4553.0 4323.0 4417.6 4465.5 4472.8 4487.0 4826.2 4842.3 4852.5 4860.3 4871.1 5148.8 5157.1 5176.8 5198.0 5207.4 5076.4 5214.8 5234.1 5241.0 5244.3 4747.1 4770.1 4772.5 4789.2 4854.3 5261.8 5262.3 5264.5 5264.9 5265.7 5045.9 5046.4 5049.8 5053.0 5085.8 5049.4 5094.7 5114.7 5117.0 5141.3 5162.3 5163.7 5164.7 5203.5 5211.8 5147.7 5153.5 5162.4 5164.1 5168.6 4290.1 4326.9 4331.8 4380.7 4392.8 3785.1 3812.8 3825.2 3840.7 3850.2 4280.4 4335.7 4396.6 4400.7 4405.2 5104.5 5150.9 5153.6 5165.2 5167.3 4413.8 4428.1 4430.3 4433.2 4462.5 4577.2 4605.6 4641.1 4665.5 4684.1 4879.4 4890.8 4896.2 4898.2 4899.5 5234.4 5238.4 5246.7 5248.6 5250.4 4876.4 4882.2 4882.6 4883.7 4887.6 5112.8 5137.2 5139.3 5140.9 5141.1 5092.4 5114.4 5125.4 5173.8 5227.2 5047.9 5053.4 5055.5 5067.7 5093.1 5033.2 5037.5 5039.9 5060.2 5061.5 4636.7 4818.2 4860.6 4949.5 4995.4 5078.9 5085.5 5086.3 5088.7 5142.7 4964.6 4967.1 4970.1 5001.9 5002.4 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | [#1] ext.html Cycles:180 Average:45565.94 Median:35440.15 stddev:77349.38 (218.3%) stddev-sans-first:77492.46 12:16:31 INFO - PROCESS | 3410 | Values: 534.2 547.2 550.3 555.2 568.0 29703.4 30423.4 32554.5 32667.7 32668.0 33623.2 42150.6 43296.6 43430.3 47912.5 7845.5 8033.4 8035.3 8035.9 8038.8 7872.8 7973.6 7973.6 7976.8 7988.1 7691.8 7900.2 10224.2 10508.4 10516.5 10308.8 10365.0 10385.7 10389.8 10396.2 10176.9 10275.8 10285.8 10290.4 10291.9 40431.2 40670.0 40794.0 40852.6 40857.4 3688.3 3754.5 3763.6 3779.8 3819.4 29606.6 29666.3 29924.0 30024.7 31362.2 3484.8 3488.7 3489.7 3495.6 3540.7 256792.0 257913.0 258571.0 259267.0 259401.0 55063.7 55382.7 55395.0 55417.4 55439.4 51743.0 51775.0 51776.0 51782.0 51879.2 12981.3 13288.2 13300.0 13305.3 13312.9 9847.3 9863.6 9884.0 9895.1 9896.7 14489.1 15907.2 16489.7 16489.7 16560.6 5206.9 6194.2 6471.4 6575.4 6616.3 814.7 832.3 838.3 852.8 868.2 27900.2 28254.6 28363.4 28405.6 28694.9 900.8 978.7 980.5 984.7 1019.1 14784.7 15182.8 15328.4 15476.9 15601.8 340976.5 341862.0 342986.0 343168.0 343463.0 52224.2 52514.5 52543.5 52964.5 53084.0 1210.9 1250.4 1259.6 1264.7 1271.5 25168.5 26181.0 27040.7 27680.6 27741.3 43378.0 43916.8 44004.1 44009.3 44061.0 278780.0 278824.0 278838.0 279111.0 280062.0 35819.3 36412.6 36529.1 36600.0 36778.0 40644.1 40852.8 40923.4 41901.8 42136.8 34835.7 35417.0 35671.0 35756.5 35952.3 35193.0 35463.3 35516.6 35791.3 35929.3 34689.7 35076.0 35319.6 35510.1 35837.6 29189.4 31648.4 31755.0 31787.7 31964.7 24154.0 24156.3 24277.6 24348.0 24496.0 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | [#2] jquery.html Cycles:180 Average:28055.71 Median:478395.50 stddev:79505.53 (16.6%) stddev-sans-first:79701.38 12:16:31 INFO - PROCESS | 3410 | Values: 366.7 391.9 392.6 394.8 424.7 12699.5 14553.4 15230.4 18773.2 18868.5 11675.5 16862.2 17276.7 17342.5 17469.7 4835.3 5005.5 5016.7 5017.8 5020.4 4920.0 4934.2 4963.3 5409.6 5667.1 5741.3 5753.5 5757.2 5760.9 5761.9 5413.4 5638.4 5684.4 5745.6 5745.9 5624.2 5634.0 5639.7 5640.0 5640.9 13484.1 17135.2 22645.0 22851.0 22955.2 2440.4 2476.1 2477.3 2546.4 2546.5 9720.8 9753.3 9957.7 10172.7 10290.2 1723.3 1753.7 1761.7 1791.8 1816.1 79931.6 79953.0 80168.6 84205.0 86540.1 10790.5 10856.0 10944.6 11151.3 11177.3 14786.8 15203.2 15224.9 15290.5 15320.6 7162.0 7317.9 7333.9 7453.7 7496.5 6680.9 6741.7 6896.0 6900.8 6922.2 4588.7 4634.2 4682.8 4727.8 4753.3 1856.1 1925.6 1929.8 1956.9 1966.7 782.5 826.9 832.0 832.6 841.1 17418.2 18181.4 18416.0 18652.0 18689.0 790.7 806.7 812.0 821.5 826.5 9638.6 9925.4 10099.7 10278.6 10297.7 476926.0 479865.0 480745.0 481445.0 486793.0 91006.0 92206.5 92306.0 92611.5 93258.5 907.9 921.4 938.0 939.3 945.7 15413.5 15487.1 15930.5 16085.2 16328.0 38979.0 39386.6 39753.6 39885.2 40132.8 55304.4 55733.7 56009.7 56349.0 58298.0 8048.4 8197.6 8227.6 8230.7 8341.0 10945.4 12533.9 12632.1 12830.0 13162.7 8741.7 9962.0 10133.7 10651.7 11060.8 10673.4 10982.6 11165.2 11257.9 11469.7 9789.7 9813.4 10018.5 10389.2 10952.5 8117.0 8297.4 8299.5 8386.4 8394.4 9509.4 10661.1 10713.3 10966.9 11346.3 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | [#3] mootools.html Cycles:180 Average:4632.87 Median:4941.82 stddev:2446.28 (49.5%) stddev-sans-first:2440.71 12:16:31 INFO - PROCESS | 3410 | Values: 1350.9 1360.3 1368.1 1375.8 1387.4 6747.8 7107.0 7124.9 7127.3 7135.7 6155.3 6335.8 6347.5 6459.0 7004.3 1868.6 2088.3 2143.2 2160.7 2174.7 2091.8 2121.2 2123.2 2131.4 2131.8 1823.1 2141.4 2148.2 2154.0 2155.1 1826.8 2032.2 2087.0 2087.5 2103.9 2071.4 2073.9 2076.3 2081.2 2081.3 7073.6 7129.3 7133.4 7138.2 7150.2 4168.1 4213.2 4274.7 4362.4 4402.8 2702.3 2899.5 2941.9 2956.5 2958.6 1097.9 1109.3 1109.5 1127.8 1154.0 9141.6 9144.7 9151.1 9162.7 9169.0 3843.8 3998.7 4011.7 4064.4 4071.0 7669.3 7671.3 7674.4 7685.7 7705.2 4287.3 4289.7 4293.7 4298.3 4382.6 3975.7 4089.7 4102.5 4103.1 4108.5 3469.3 3492.9 3570.2 3588.5 3588.5 1172.8 1186.1 1198.1 1198.6 1204.7 691.6 694.9 706.3 711.3 737.2 4752.5 4815.0 4913.4 4933.6 4976.8 537.6 552.2 559.6 560.0 565.5 3154.2 3154.6 3304.4 3432.8 3630.0 7864.4 8197.8 8235.7 8258.5 8329.5 8069.4 8246.5 8248.6 8256.8 8260.1 6486.5 6566.0 6666.4 6670.9 6673.4 4878.8 4924.4 4950.1 5100.1 5137.9 7328.7 7633.2 7772.5 7805.4 7809.6 7845.9 7891.8 7902.3 7983.3 7990.2 5759.3 5948.3 6392.9 6627.7 6666.6 6821.8 6830.4 6833.3 6861.5 6864.6 6000.6 6002.6 6140.7 6146.0 6171.6 5882.7 5905.4 6049.1 6080.1 6111.8 5433.4 5496.2 5496.3 5504.7 5516.2 4120.7 4144.1 4155.2 4207.4 4292.3 3990.1 3996.6 4268.7 4292.4 4304.1 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | [#4] prototype.html Cycles:180 Average:4146.17 Median:4681.09 stddev:1167.86 (24.9%) stddev-sans-first:1135.99 12:16:31 INFO - PROCESS | 3410 | Values: 357.4 358.8 369.8 379.5 380.3 4717.3 4799.4 4892.8 4959.7 4981.1 4673.4 4771.6 4806.6 4808.4 4821.8 3918.1 3918.5 3942.2 3942.4 3955.1 3776.8 3881.6 3958.3 3965.0 3974.6 3800.2 3857.4 3886.3 3890.4 3896.4 3854.0 3856.2 3871.2 3872.3 3890.6 3504.5 3521.7 3523.6 3529.7 3539.4 4559.9 4698.8 4713.5 4723.9 4758.2 4447.7 4454.0 4454.2 4454.6 4475.1 5051.4 5075.6 5077.5 5090.2 5106.3 3436.9 3598.1 3628.3 3865.1 3872.4 5227.2 5237.4 5245.2 5260.9 5271.8 3695.6 3705.9 3709.1 3713.5 3716.3 3655.0 3761.0 3762.0 3772.8 3791.5 4211.4 4219.2 4243.7 4262.7 4273.5 5084.1 5088.1 5088.5 5109.6 5114.0 1179.2 1223.6 1249.7 1266.2 1271.8 845.9 858.4 858.8 862.9 863.0 4231.7 4271.6 4284.1 4289.8 4307.6 5053.2 5055.1 5085.8 5088.2 5105.3 4110.5 4246.2 4249.6 4251.5 4281.3 2646.6 2649.2 2671.5 2674.6 2686.6 4671.7 4680.4 4687.5 4712.5 4716.8 4955.0 5072.9 5103.7 5113.6 5126.7 4672.8 4677.8 4678.0 4681.8 4700.8 4946.3 4988.0 5009.3 5017.2 5028.0 4650.7 4903.6 4922.0 4935.9 4942.6 4677.9 4679.8 4693.6 4715.3 4716.7 3513.2 3554.2 3555.8 3578.6 3587.3 4705.1 4971.3 4999.8 5015.3 5033.3 4829.2 4840.6 4858.4 4860.3 4896.9 4856.2 4865.5 4874.7 4883.8 4923.0 4658.3 4796.4 4818.9 4854.7 4862.5 4889.4 4890.9 4920.5 4936.9 4973.0 4851.2 4857.5 4857.9 4880.9 4895.0 12:16:31 INFO - PROCESS | 3410 | 12:16:31 INFO - PROCESS | 3410 | [#5] yui.html Cycles:180 Average:15062.49 Median:44574.28 stddev:19029.25 (42.7%) stddev-sans-first:19051.94 12:16:31 INFO - PROCESS | 3410 | Values: 671.7 677.9 740.3 772.5 798.9 12291.3 12484.2 12537.7 12573.8 12585.7 12106.3 12110.5 12128.0 12135.6 12136.6 634.5 648.2 772.5 773.4 780.8 797.8 798.7 799.2 799.2 799.3 848.0 850.4 850.6 851.2 851.5 674.6 685.7 809.6 826.6 838.6 610.7 611.5 611.6 612.1 612.1 12754.6 12794.9 12799.9 12801.1 12815.7 12037.6 12083.6 12184.3 12255.9 12605.0 9244.2 10618.2 10696.9 11246.8 11401.3 3929.1 3963.1 4023.7 4055.7 4096.7 66748.0 67900.3 68035.0 69167.3 70203.0 9505.4 9641.4 9748.7 9821.3 9909.4 16845.0 16899.7 16922.5 17311.2 17408.8 6462.7 6515.9 6702.8 6736.3 6801.5 3584.3 3771.9 3773.3 3782.0 3782.1 2972.3 2991.0 3049.9 3055.3 3064.8 509.1 532.7 548.1 553.1 565.2 639.7 655.6 671.4 672.1 676.4 8942.2 9122.0 9173.5 9267.5 9430.2 633.3 634.3 634.7 635.4 639.8 4328.0 4491.7 4577.3 4609.4 4627.4 67073.7 68994.7 69080.0 69846.0 71451.6 60395.0 60530.0 60858.0 61503.0 63668.4 41531.0 42834.0 44625.0 54530.7 55029.7 9125.5 9249.9 9320.3 9392.5 9493.4 30446.4 34544.8 36443.6 36722.6 36777.3 43512.3 43811.0 44440.8 44523.6 45471.0 11544.8 11602.9 11686.8 11905.8 12355.7 12145.4 12278.9 12355.4 12995.4 13128.5 12058.6 12130.9 12265.3 12801.4 12879.2 11981.3 11992.3 12130.2 12580.0 12643.8 11944.6 12040.4 12316.3 12457.3 12819.9 10240.5 10314.0 10516.8 10649.0 10801.0 10010.2 10359.8 10414.2 10434.6 10449.5 12:16:31 INFO - PROCESS | 3410 | -------- Summary: end -------- 12:16:31 INFO - PROCESS | 3410 | 12:16:32 INFO - TEST-INFO | 3410: exit 0 12:16:32 INFO - TEST-OK | dromaeo_css | took 1123187ms 12:16:32 INFO - TEST-START | kraken 12:16:32 INFO - operating with platform_type : linux_ 12:16:32 INFO - Initialising browser for kraken test... 12:16:32 INFO - TEST-INFO | started process 3710 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJ8DZa/profile http://localhost:55914/getInfo.html) 12:16:36 INFO - PROCESS | 3710 | __metrics Screen width/height:1600/1200 12:16:36 INFO - PROCESS | 3710 | colorDepth:24 12:16:36 INFO - PROCESS | 3710 | Browser inner width/height: 1024/697 12:16:36 INFO - PROCESS | 3710 | __metrics 12:16:37 INFO - PROCESS | 3710 | 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" 12:16:37 INFO - PROCESS | 3710 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:16:38 INFO - PROCESS | 3710 | console.error: PushService: 12:16:38 INFO - PROCESS | 3710 | stateChangeProcessEnqueue: Error transitioning state 12:16:38 INFO - PROCESS | 3710 | UnknownError 12:16:38 INFO - TEST-INFO | 3710: exit 0 12:16:38 INFO - Browser initialized. 12:16:38 INFO - Running cycle 1/1 for kraken test... 12:16:38 INFO - TEST-INFO | started process 3793 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJ8DZa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:16:50 INFO - PROCESS | 3793 | RSS: Main: 152494080 12:16:50 INFO - PROCESS | 3793 | 12:16:53 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55914/tests/kraken/driver.html?testName=audio-beat-detection) 12:16:54 INFO - PROCESS | 3793 | RSS: Main: 205979648 12:16:54 INFO - PROCESS | 3793 | 12:16:57 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55914/tests/kraken/driver.html?testName=audio-dft) 12:16:57 INFO - PROCESS | 3793 | RSS: Main: 185163776 12:16:57 INFO - PROCESS | 3793 | 12:17:00 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55914/tests/kraken/driver.html?testName=audio-fft) 12:17:01 INFO - PROCESS | 3793 | RSS: Main: 267538432 12:17:01 INFO - PROCESS | 3793 | 12:17:03 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55914/tests/kraken/driver.html?testName=audio-oscillator) 12:17:04 INFO - PROCESS | 3793 | RSS: Main: 179392512 12:17:04 INFO - PROCESS | 3793 | 12:17:06 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55914/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:07 INFO - PROCESS | 3793 | RSS: Main: 183484416 12:17:07 INFO - PROCESS | 3793 | 12:17:10 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55914/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:10 INFO - PROCESS | 3793 | RSS: Main: 221614080 12:17:10 INFO - PROCESS | 3793 | 12:17:14 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55914/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:14 INFO - PROCESS | 3793 | RSS: Main: 221822976 12:17:14 INFO - PROCESS | 3793 | 12:17:17 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55914/tests/kraken/driver.html?testName=json-parse-financial) 12:17:18 INFO - PROCESS | 3793 | RSS: Main: 220385280 12:17:18 INFO - PROCESS | 3793 | 12:17:19 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55914/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:17:20 INFO - PROCESS | 3793 | RSS: Main: 182927360 12:17:20 INFO - PROCESS | 3793 | 12:17:22 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:17:23 INFO - PROCESS | 3793 | RSS: Main: 190160896 12:17:23 INFO - PROCESS | 3793 | 12:17:25 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:17:25 INFO - PROCESS | 3793 | RSS: Main: 198230016 12:17:25 INFO - PROCESS | 3793 | 12:17:28 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:17:29 INFO - PROCESS | 3793 | RSS: Main: 201936896 12:17:29 INFO - PROCESS | 3793 | 12:17:32 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:17:32 INFO - PROCESS | 3793 | RSS: Main: 204034048 12:17:32 INFO - PROCESS | 3793 | 12:17:34 INFO - PROCESS | 3793 | Cycle 1(1): loaded http://localhost:55914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55914/tests/kraken/driver.html?testName=ai-astar) 12:17:35 INFO - PROCESS | 3793 | RSS: Main: 255385600 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | __start_tp_report 12:17:35 INFO - PROCESS | 3793 | _x_x_mozilla_page_load 12:17:35 INFO - PROCESS | 3793 | _x_x_mozilla_page_load_details 12:17:35 INFO - PROCESS | 3793 | |i|pagename|runs| 12:17:35 INFO - PROCESS | 3793 | |0;ai-astar;106;113;113;114;113;112;113;113;112;112 12:17:35 INFO - PROCESS | 3793 | |1;audio-beat-detection;156;122;128;168;126;135;125;129;153;124 12:17:35 INFO - PROCESS | 3793 | |2;audio-dft;161;160;160;163;162;163;161;162;162;160 12:17:35 INFO - PROCESS | 3793 | |3;audio-fft;76;76;76;106;77;76;77;77;93;75 12:17:35 INFO - PROCESS | 3793 | |4;audio-oscillator;84;85;112;86;86;86;114;86;86;109 12:17:35 INFO - PROCESS | 3793 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 12:17:35 INFO - PROCESS | 3793 | |6;imaging-darkroom;150;149;148;150;149;149;149;149;150;148 12:17:35 INFO - PROCESS | 3793 | |7;imaging-desaturate;96;95;95;96;95;95;96;95;95;95 12:17:35 INFO - PROCESS | 3793 | |8;json-parse-financial;62;58;58;58;60;60;59;60;59;60 12:17:35 INFO - PROCESS | 3793 | |9;json-stringify-tinderbox;67;68;67;67;68;67;68;67;68;67 12:17:35 INFO - PROCESS | 3793 | |10;stanford-crypto-aes;85;84;84;84;84;83;84;88;84;84 12:17:35 INFO - PROCESS | 3793 | |11;stanford-crypto-ccm;141;143;135;131;140;131;140;149;212;137 12:17:35 INFO - PROCESS | 3793 | |12;stanford-crypto-pbkdf2;176;176;178;177;179;176;176;173;178;173 12:17:35 INFO - PROCESS | 3793 | |13;stanford-crypto-sha256-iterative;67;74;67;69;68;72;76;68;67;67 12:17:35 INFO - PROCESS | 3793 | __end_tp_report 12:17:35 INFO - PROCESS | 3793 | __start_cc_report 12:17:35 INFO - PROCESS | 3793 | _x_x_mozilla_cycle_collect,1833 12:17:35 INFO - PROCESS | 3793 | __end_cc_report 12:17:35 INFO - PROCESS | 3793 | __startTimestamp1484165855010__endTimestamp 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | ------- Summary: start ------- 12:17:35 INFO - PROCESS | 3793 | Number of tests: 14 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.23 (2.0%) stddev-sans-first:0.67 12:17:35 INFO - PROCESS | 3793 | Values: 106.0 113.0 113.0 114.0 113.0 112.0 113.0 113.0 112.0 112.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:132.00 stddev:16.28 (12.3%) stddev-sans-first:15.68 12:17:35 INFO - PROCESS | 3793 | Values: 156.0 122.0 128.0 168.0 126.0 135.0 125.0 129.0 153.0 124.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.24 12:17:35 INFO - PROCESS | 3793 | Values: 161.0 160.0 160.0 163.0 162.0 163.0 161.0 162.0 162.0 160.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.50 stddev:10.29 (13.5%) stddev-sans-first:10.76 12:17:35 INFO - PROCESS | 3793 | Values: 76.0 76.0 76.0 106.0 77.0 76.0 77.0 77.0 93.0 75.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:86.00 stddev:12.68 (14.7%) stddev-sans-first:12.98 12:17:35 INFO - PROCESS | 3793 | Values: 84.0 85.0 112.0 86.0 86.0 86.0 114.0 86.0 86.0 109.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 12:17:35 INFO - PROCESS | 3793 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 12:17:35 INFO - PROCESS | 3793 | Values: 150.0 149.0 148.0 150.0 149.0 149.0 149.0 149.0 150.0 148.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 12:17:35 INFO - PROCESS | 3793 | Values: 96.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:60.00 stddev:1.26 (2.1%) stddev-sans-first:0.93 12:17:35 INFO - PROCESS | 3793 | Values: 62.0 58.0 58.0 58.0 60.0 60.0 59.0 60.0 59.0 60.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 12:17:35 INFO - PROCESS | 3793 | Values: 67.0 68.0 67.0 67.0 68.0 67.0 68.0 67.0 68.0 67.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 12:17:35 INFO - PROCESS | 3793 | Values: 85.0 84.0 84.0 84.0 84.0 83.0 84.0 88.0 84.0 84.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:140.50 stddev:23.86 (17.0%) stddev-sans-first:25.24 12:17:35 INFO - PROCESS | 3793 | Values: 141.0 143.0 135.0 131.0 140.0 131.0 140.0 149.0 212.0 137.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:176.50 stddev:1.99 (1.1%) stddev-sans-first:2.11 12:17:35 INFO - PROCESS | 3793 | Values: 176.0 176.0 178.0 177.0 179.0 176.0 176.0 173.0 178.0 173.0 12:17:35 INFO - PROCESS | 3793 | 12:17:35 INFO - PROCESS | 3793 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.50 stddev:3.31 (4.8%) stddev-sans-first:3.38 12:17:35 INFO - PROCESS | 3793 | Values: 67.0 74.0 67.0 69.0 68.0 72.0 76.0 68.0 67.0 67.0 12:17:35 INFO - PROCESS | 3793 | -------- Summary: end -------- 12:17:35 INFO - PROCESS | 3793 | 12:17:36 INFO - TEST-INFO | 3793: exit 0 12:17:36 INFO - TEST-OK | kraken | took 63706ms 12:17:36 INFO - SUITE-END | took 1186s 12:17:36 INFO - Completed test suite (00:19:46) 12:17:36 INFO - javascript benchmark 12:17:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2568.8844002019905, 2600.427905860712, 2627.894884204632, 2636.4858852523757, 2641.7754490868274, 4717.29240613908, 4768.833090841821, 4791.712497250168, 4807.495213002632, 4819.1084649339855, 4788.281757736341, 4802.471728109905, 4803.471618122013, 4809.158397280479, 4809.831655892044, 4392.3191905254735, 4409.845655402061, 4436.689431739779, 4448.310511870649, 4473.396091527651, 4361.629261512775, 4522.186006518826, 4533.546645335457, 4554.225781617117, 4558.84044058458, 4378.036831896993, 4438.4451943507065, 4438.644908407319, 4450.354698568705, 4477.395551600541, 4477.104579084196, 4492.326151077332, 4494.370788089654, 4504.752238626874, 4552.9755804943625, 4322.956770432287, 4417.624501917341, 4465.531119232466, 4472.843450479218, 4486.955130448687, 4826.22780355145, 4842.322074909515, 4852.514748525119, 4860.295257187723, 4871.098846713369, 5148.794048238066, 5157.071727089123, 5176.767045482962, 5197.974010129925, 5207.375114986188, 5076.390833100015, 5214.765335559909, 5234.057869583474, 5241.030409374278, 5244.292020577212, 4747.145513807548, 4770.0936822004405, 4772.49888761682, 4789.161896668069, 4854.344663470386, 5261.842144735664, 5262.289590905255, 5264.52619264268, 5264.894702106013, 5265.7103859287345, 5045.899082018413, 5046.44489106198, 5049.798008079709, 5053.0399224147295, 5085.771140298695, 5049.394072711299, 5094.719790411563, 5114.667546609533, 5116.9744151279, 5141.2545180948555, 5162.328897243335, 5163.66436181647, 5164.715940623302, 5203.531682148625, 5211.843644690665, 5147.6911385317, 5153.484651534816, 5162.406323272871, 5164.0962831505085, 5168.61235407346, 4290.099079193342, 4326.935095973563, 4331.826726930951, 4380.7366789844145, 4392.824287028483, 3785.1104990327253, 3812.8093595320124, 3825.158465137665, 3840.6543411092575, 3850.249201405699, 4280.379344995021, 4335.696501244883, 4396.604305612446, 4400.73595584266, 4405.229084910128, 5104.540591346722, 5150.896982060338, 5153.639245252796, 5165.1735722285, 5167.250748641502, 4413.7572433515825, 4428.114377124588, 4430.269005614102, 4433.179861725591, 4462.509123996358, 4577.22187228172, 4605.585497305258, 4641.071785642885, 4665.463471700662, 4684.063187362541, 4879.365682461257, 4890.80436782539, 4896.1921282987905, 4898.21628539432, 4899.461059283547, 5234.398044224963, 5238.371395432572, 5246.658967167122, 5248.6325957182635, 5250.422453530185, 4876.439209490812, 4882.170031094794, 4882.633802464758, 4883.706977581356, 4887.60899128076, 5112.795488180431, 5137.229415587692, 5139.331886854685, 5140.897182056413, 5141.126008578476, 5092.414372347227, 5114.411842638145, 5125.410577783454, 5173.7671804769625, 5227.215917612389, 5047.924281135713, 5053.418856831511, 5055.545000949932, 5067.670601410897, 5093.108705976513, 5033.245013248042, 5037.471065538066, 5039.924401133912, 5060.240963855451, 5061.544460998528, 4636.698614590041, 4818.156822556109, 4860.562549370574, 4949.480304568113, 4995.350604421547, 5078.898422031465, 5085.542301192911, 5086.313347698014, 5088.745562721923, 5142.717150556755, 4964.553190212898, 4967.081089998362, 4970.080535101018, 5001.9245862139815, 5002.399712034579], "value": 4734.769998966844, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.156033467122, 547.1792311532704, 550.3423409026215, 555.1755642870339, 567.9943200567996, 29703.40593188135, 30423.391532169342, 32554.511682324726, 32667.67332326676, 32668.0, 33623.15942101449, 42150.57849421505, 43296.567034329724, 43430.348544771856, 47912.52087479124, 7845.529268243895, 8033.357331413473, 8035.316998055172, 8035.919640803576, 8038.839223215532, 7872.763817085496, 7973.60131993398, 7973.60131993401, 7976.840463190732, 7988.081370642392, 7691.769246922573, 7900.170482099383, 10224.182065434747, 10508.369497830117, 10516.474176291193, 10308.793824123513, 10365.0, 10385.740356491073, 10389.84415233772, 10396.220283478731, 10176.898231017743, 10275.794484110276, 10285.845712314322, 10290.3825770454, 10291.94854025725, 40431.1913761726, 40670.0, 40794.0, 40852.59147408517, 40857.387139192935, 3688.2623475305045, 3754.4743735877, 3763.5860055393887, 3779.79211143387, 3819.427085937104, 29606.555901661493, 29666.25834354137, 29923.952661656956, 30024.69975300241, 31362.21594460134, 3484.8083355415442, 3488.66857648523, 3489.7208223342072, 3495.5980062292906, 3540.716742660581, 256792.0, 257913.0, 258571.0, 259267.0, 259400.9999999981, 55063.724681376334, 55382.72308638431, 55395.0, 55417.44582554203, 55439.44560554343, 51743.0, 51775.0, 51776.0, 51782.0, 51879.221811672855, 12981.286029268293, 13288.202707837561, 13300.0, 13305.268210248336, 13312.866871331356, 9847.261455390731, 9863.605455781832, 9883.950580247052, 9895.109440150422, 9896.703098907044, 14489.130652160904, 15907.204639768197, 16489.6702065958, 16489.6702065958, 16560.585985350222, 5206.947930520646, 6194.194754681863, 6471.352864713585, 6575.416398063067, 6616.305287944697, 814.6700586262573, 832.3008672714949, 838.3377132065722, 852.8422241885271, 868.1665601022962, 27900.16299511018, 28254.576181357297, 28363.440280399227, 28405.57391639127, 28694.856525717238, 900.7793090692691, 978.7112801723508, 980.4558470049049, 984.7095106943469, 1019.1337363241333, 14784.704305914036, 15182.772258416133, 15328.386864525517, 15476.922615386851, 15601.84398156004, 340976.54140751634, 341862.0, 342986.0, 343168.0, 343463.0, 52224.216636750476, 52514.47485525172, 52543.474565254626, 52964.470355296726, 53084.0, 1210.8546974362957, 1250.4310538704867, 1259.5780413561376, 1264.652220639313, 1271.510468469646, 25168.49663006766, 26181.0, 27040.72959270382, 27680.58479122855, 27741.306467337672, 43378.0, 43916.75373563326, 44004.11991760211, 44009.33985990148, 44061.0, 278780.0, 278824.0, 278838.0, 279111.0, 280062.0, 35819.28361432809, 36412.635873640924, 36529.086772830895, 36600.0, 36778.0, 40644.051277460814, 40852.79573602113, 40923.38614920719, 41901.79049104735, 42136.789316053226, 34835.65164348324, 35417.0, 35671.0, 35756.46365304574, 35952.28095438129, 35193.0, 35463.290734185684, 35516.64483355237, 35791.28417431585, 35929.28141437104, 34689.65310346864, 35076.0, 35319.64680353266, 35510.112247194025, 35837.64162358343, 29189.41621167607, 31648.367032659677, 31755.0, 31787.6821231794, 31964.6803531971, 24154.0, 24156.275311740676, 24277.635835462836, 24348.0, 24496.0], "value": 17766.745688976607, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.73045311695915, 391.9196564704236, 392.621120618603, 394.8025987006497, 424.6687583684727, 12699.466138335565, 14553.41786328547, 15230.390784368627, 18773.155208015636, 18868.528286792836, 11675.532978680843, 16862.241199146036, 17276.654466910655, 17342.47972560822, 17469.7379539307, 4835.274708793691, 5005.54950054495, 5016.749162541876, 5017.774200160983, 5020.39755229373, 4920.040592084545, 4934.185859333224, 4963.305137280783, 5409.56723462122, 5667.149927510865, 5741.282339707536, 5753.539716822645, 5757.165211044396, 5760.913586296209, 5761.884762304751, 5413.431589683066, 5638.40796716343, 5684.403137670568, 5745.6265342752795, 5745.885082298372, 5624.21261023457, 5634.04221282383, 5639.661620302795, 5639.971800141014, 5640.858978525529, 13484.12353197039, 17135.22891469887, 22645.0, 22851.0, 22955.196568120034, 2440.389902524369, 2476.1333533263396, 2477.3311205974287, 2546.350680576441, 2546.4525127097763, 9720.75698107546, 9753.317267791257, 9957.65148219816, 10172.742804048354, 10290.176785857113, 1723.3106757297182, 1753.684336819372, 1761.7093179625347, 1791.802901680814, 1816.0556510614406, 79931.6003419985, 79953.0, 80168.59915700443, 84205.0, 86540.13459865384, 10790.514426850654, 10856.0, 10944.616938407118, 11151.275167114112, 11177.261570915016, 14786.778198327034, 15203.239838008056, 15224.87889982551, 15290.541283761506, 15320.616984575474, 7161.9641901790155, 7317.926820731831, 7333.926660733431, 7453.701851925971, 7496.512726672749, 6680.899786503206, 6741.662916854138, 6896.034555162281, 6900.827479313058, 6922.203946546111, 4588.701734387254, 4634.23535116702, 4682.839083618544, 4727.763611819395, 4753.310769938409, 1856.0905156473232, 1925.5763731978943, 1929.778075521333, 1956.863019588615, 1966.7443232379699, 782.5343920367372, 826.900771907375, 831.983360332802, 832.6461253967161, 841.1000229754104, 17418.17109203541, 18181.363652272106, 18416.027672528344, 18652.0, 18689.0, 790.727199116304, 806.7015204374302, 811.975640730779, 821.4783612406046, 826.5206180415345, 9638.566264518113, 9925.404475731335, 10099.747506312255, 10278.561001459802, 10297.691069267934, 476926.0, 479865.0, 480745.0, 481445.0, 486793.0, 91006.0, 92206.53896730607, 92306.0, 92611.53694231485, 93258.53370733239, 907.9001309855911, 921.428714197202, 937.9859302110474, 939.3049143633662, 945.6879229854131, 15413.460528881435, 15487.148206848731, 15930.522084337488, 16085.195740212708, 16328.0, 38979.0, 39386.606133938294, 39753.60246397499, 39885.202295953335, 40132.79933600313, 55304.44695553154, 55733.721331393084, 56009.71995140161, 56349.0, 58298.0, 8048.436609437517, 8197.631106600098, 8227.588620569068, 8230.711925082593, 8341.040780310099, 10945.398003109587, 12533.87466125327, 12632.11575189728, 12829.986027338637, 13162.670933226364, 8741.694040708544, 9961.950190249003, 10133.695989120339, 10651.680449586525, 11060.834087488854, 10673.35959842412, 10982.560697572328, 11165.162612804235, 11257.947139030106, 11469.655910322703, 9789.657361992297, 9813.362131461432, 10018.549165287433, 10389.168866490814, 10952.507137179005, 8117.0, 8297.419180657538, 8299.543525106177, 8386.412951093365, 8394.405063038002, 9509.381890177116, 10661.147108231167, 10713.250072494851, 10966.890331096587, 11346.262492937933], "value": 8623.472973356907, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.9256990865501, 1360.2994457854206, 1368.1312366647182, 1375.77299745542, 1387.4242189491358, 6747.831304217397, 7106.96446517768, 7124.893126603105, 7127.28727127287, 7135.678894449749, 6155.26136863577, 6335.809925702236, 6347.49220062395, 6459.0, 7004.299570042993, 1868.6169335286233, 2088.33173384517, 2143.1748776720997, 2160.686700428437, 2174.7281589801273, 2091.8221951134174, 2121.1939463004037, 2123.1719629344566, 2131.4138611881704, 2131.7761635028255, 1823.1157888424104, 2141.379000089972, 2148.1729534129395, 2153.9784602154014, 2155.1271734947295, 1826.8173182681758, 2032.1566549881782, 2086.968695469569, 2087.4676957375773, 2103.8842863642494, 2071.3785864240817, 2073.8963051847345, 2076.3355726167633, 2081.2299449203892, 2081.323569839808, 7073.610951397671, 7129.322714342129, 7133.429325653935, 7138.179109402433, 7150.1777295611155, 4168.145530166309, 4213.157368526307, 4274.7435153890865, 4362.411074504933, 4402.801873915681, 2702.27038699552, 2899.5070837957596, 2941.911742647724, 2956.5121754910415, 2958.6449626045014, 1097.8902109789037, 1109.2512554026, 1109.5339957217989, 1127.7688073944903, 1154.0248490025915, 9141.634334626608, 9144.725658230263, 9151.130642588943, 9162.725118246462, 9168.954155229248, 3843.8270277837564, 3998.7400818946676, 4011.6590089842107, 4064.3700226464352, 4070.9796451018146, 7669.271419215165, 7671.2712292332135, 7674.386049116058, 7685.730999414994, 7705.22947705225, 4287.2711639021445, 4289.678274129453, 4293.677974151951, 4298.290782020931, 4382.627476664456, 3975.741576797499, 4089.734167279117, 4102.487189101363, 4103.076807718299, 4108.465899433055, 3469.3061387722555, 3492.860285588599, 3570.232400034005, 3588.4617307404096, 3588.515550400715, 1172.8475298211179, 1186.1044911092242, 1198.1253684809963, 1198.5864876617552, 1204.6626944455566, 691.640347019547, 694.8506071194718, 706.3148745716646, 711.295817141037, 737.1743647115159, 4752.453467851172, 4814.975925120422, 4913.361263035854, 4933.555979961749, 4976.825811096594, 537.5672583570185, 552.1882832236606, 559.6194587680335, 559.9832005039774, 565.4967079299427, 3154.227214332503, 3154.6214454265637, 3304.3721692878275, 3432.828358582061, 3629.9836045907186, 7864.449488535862, 8197.754067377988, 8235.670573177125, 8258.545779982158, 8329.5418751968, 8069.368077137635, 8246.505209687439, 8248.587570621565, 8256.793580160544, 8260.132686067996, 6486.513511486657, 6566.0, 6666.366695163952, 6670.899936500957, 6673.432758215509, 4878.829240976549, 4924.384451943507, 4950.084234416644, 5100.081985242692, 5137.922931155961, 7328.706851725871, 7633.160352361347, 7772.494787838772, 7805.414593905593, 7809.570473624005, 7845.921540784748, 7891.763247102596, 7902.328302094386, 7983.32141767933, 7990.200979901963, 5759.337676167294, 5948.256467941507, 6392.904106438315, 6627.668616569249, 6666.566673166229, 6821.795346139624, 6830.419414349836, 6833.3166683331265, 6861.519693621399, 6864.5881247125335, 6000.639961602318, 6002.609830361012, 6140.723667435065, 6145.9692701536205, 6171.56799024064, 5882.705864706833, 5905.379935106752, 6049.062395328731, 6080.118382834553, 6111.786087487095, 5433.402325744244, 5496.203050557727, 5496.285482887199, 5504.697241651747, 5516.227728117987, 4120.7321524100835, 4144.067584793397, 4155.210510003089, 4207.4319966805315, 4292.291771857734, 3990.0822810753107, 3996.620321069494, 4268.701190916606, 4292.3561465780385, 4304.117655880599], "value": 3785.6499052428007, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.3603250182174, 358.80983078968154, 369.7744375930683, 379.4876916163178, 380.26039353457537, 4717.292406139072, 4799.400074990626, 4892.779824907879, 4959.6528243023, 4981.128302547053, 4673.392458980328, 4771.642126840484, 4806.567408933195, 4808.374911261531, 4821.783019764111, 3918.0810296329273, 3918.4710064141404, 3942.231264903345, 3942.369220924653, 3955.1298714282957, 3776.848926042955, 3881.6312450317173, 3958.3270843956598, 3965.0, 3974.5627980922077, 3800.163963927945, 3857.4406711026886, 3886.300465916134, 3890.4164375343785, 3896.3960586109197, 3853.97666225234, 3856.151646637735, 3871.2257548490416, 3872.341701910682, 3890.5525864525657, 3504.456809194579, 3521.7358698097487, 3523.6476352364816, 3529.699975502085, 3539.3983022885914, 4559.863204103882, 4698.7650617469335, 4713.481517033124, 4723.881902952419, 4758.214894542396, 4447.666425018137, 4454.0, 4454.175977444183, 4454.57681520255, 4475.127350166719, 5051.368578927634, 5075.593952483792, 5077.49225077493, 5090.2364645303505, 5106.259592359124, 3436.879709210191, 3598.1004748812798, 3628.3468975584383, 3865.1303456722294, 3872.419137129425, 5227.163653815372, 5237.423883372826, 5245.186996015624, 5260.9478104378895, 5271.759502211583, 3695.6304369562827, 3705.94441083384, 3709.1283548366114, 3713.4986776785327, 3716.275326311343, 3654.9634503655157, 3760.9623903761158, 3762.0, 3772.8490860365278, 3791.450239715226, 4211.410402543646, 4219.177260434247, 4243.7029407941145, 4262.680298977589, 4273.487181538236, 5084.144287335096, 5088.084144853962, 5088.516590923857, 5109.56568691658, 5114.0, 1179.1804695736553, 1223.62067758995, 1249.6563445052502, 1266.1959655618564, 1271.815586739936, 845.940784145104, 858.3991206155716, 858.8196478739535, 862.9266512346395, 862.9697960571475, 4231.682623803165, 4271.6369108625495, 4284.078923031501, 4289.806958686804, 4307.56924307573, 5053.166227572483, 5055.143843728281, 5085.821996230115, 5088.211327244283, 5105.285260063594, 4110.486189226347, 4246.171996460661, 4249.553796851348, 4251.511076226212, 4281.266087234675, 2646.5765477523887, 2649.191996441104, 2671.465706858597, 2674.558697814839, 2686.6238726578295, 4671.7430541320555, 4680.391549098664, 4687.460941991646, 4712.528747125329, 4716.8113275468595, 4954.984228233204, 5072.873178170576, 5103.744812759347, 5113.64204505681, 5126.666766660155, 4672.789724462407, 4677.76611169447, 4677.965085187954, 4681.789319480564, 4700.764961751899, 4946.318251794407, 4987.9750601248215, 5009.323741294878, 5017.222330538772, 5027.963674711747, 4650.720956742606, 4903.6077113831525, 4922.040202160688, 4935.876603084952, 4942.55517003457, 4677.906441871211, 4679.836205732783, 4693.647976401852, 4715.316279139574, 4716.693414928019, 3513.2446523997464, 3554.240650877832, 3555.8222088894936, 3578.5526809148855, 3587.336342776595, 4705.106029854317, 4971.328870602422, 4999.77501012448, 5015.272785446017, 5033.320501732365, 4829.227323628203, 4840.588549973291, 4858.352198052141, 4860.3195552623365, 4896.853094407174, 4856.174450343379, 4865.537773911473, 4874.683145595525, 4883.753473402133, 4922.950770492249, 4658.324542941323, 4796.37647105874, 4818.927716084191, 4854.713500922187, 4862.489438609036, 4889.413270407432, 4890.92663610039, 4920.507949205194, 4936.925946110883, 4972.950270497394, 4851.199552073868, 4857.489963553776, 4857.854264372075, 4880.92678609814, 4894.975525122351], "value": 3791.0568225510265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.7480944645757, 677.9491787729019, 740.333699670297, 772.5441989226357, 798.940079494038, 12291.323977181251, 12484.18852774569, 12537.749245015106, 12573.811392829091, 12585.685357866047, 12106.273623582569, 12110.455029523671, 12128.0, 12135.635930922064, 12136.575219867307, 634.4892361648871, 648.1509222917969, 772.4631381190076, 773.4237992695436, 780.8047988003, 797.7726347990824, 798.6845196147518, 799.1768478467188, 799.2087833045301, 799.3085980626774, 848.0247715127591, 850.3537311643165, 850.5832142250284, 851.2423942691009, 851.4550687559966, 674.6087269383746, 685.6811582614058, 809.6397103289039, 826.6445428465757, 838.5723281126608, 610.6702380714408, 611.5352332227494, 611.5871786544064, 612.1124369664005, 612.13077430049, 12754.61736147917, 12794.936025319814, 12799.87200127996, 12801.103922725413, 12815.679608009781, 12037.578684745991, 12083.637490875288, 12184.329861857594, 12255.877441225563, 12605.0, 9244.168024877794, 10618.203634727426, 10696.893031069667, 11246.831297530543, 11401.315921044681, 3929.057026313693, 3963.0686788604653, 4023.698222633285, 4055.695822813271, 4096.713230073896, 66747.99512029246, 67900.32099678989, 68035.0, 69167.3083269166, 70203.0, 9505.42967421957, 9641.37331073478, 9748.709053372451, 9821.263405244636, 9909.35589186715, 16845.0, 16899.746503802453, 16922.49232523026, 17311.220995055253, 17408.82591174072, 6462.676866156673, 6515.869682606416, 6702.83242918931, 6736.292689267556, 6801.523893327519, 3584.318979393907, 3771.905702357463, 3773.2830762155104, 3782.0, 3782.130110074698, 2972.301509145392, 2990.9551356729667, 3049.9390012199633, 3055.297281625194, 3064.8161110333454, 509.06078285563075, 532.6537750462153, 548.0792268988138, 553.0736017171222, 565.1861319699619, 639.7409049334934, 655.6459511863541, 671.4091599392503, 672.0644543714072, 676.4284179868004, 8942.239909607757, 9121.954390228138, 9173.541322933826, 9267.536623168815, 9430.19843313326, 633.3002032753833, 634.3466229783238, 634.7492740367529, 635.4312889963456, 639.7628002308242, 4327.978360108243, 4491.73049617024, 4577.3362862384765, 4609.446866375989, 4627.421572303462, 67073.6646316775, 68994.65502672455, 69080.0, 69846.0, 71451.64274178595, 60395.0, 60530.0, 60858.0, 61503.0, 63668.36331636717, 41531.0, 42834.0, 44625.0, 54530.72734636301, 55029.72485137549, 9125.5437228137, 9249.907500924905, 9320.254379649747, 9392.49336962416, 9493.41368407486, 30446.391072178874, 34544.82727586447, 36443.63556364403, 36722.63277367192, 36777.264454711294, 43512.34731479094, 43811.0, 44440.777796110815, 44523.55476445194, 45471.0, 11544.826827597424, 11602.94198529002, 11686.824697629712, 11905.821412678644, 12355.69110772238, 12145.392730363623, 12278.938605307274, 12355.382230888239, 12995.350232488527, 13128.540501082416, 12058.638240852788, 12130.939345303217, 12265.26408415498, 12801.359932003548, 12879.227246364873, 11981.341026243641, 11992.340421276565, 12130.150889437655, 12580.0, 12643.7814521928, 11944.581939632419, 12040.397980100784, 12316.322602256603, 12457.314847683465, 12819.871801282241, 10240.48797560134, 10313.98877218935, 10516.78966420652, 10649.041586257274, 10801.0, 10010.249231307535, 10359.792804143724, 10414.166866650801, 10434.582616695247, 10449.529771160163], "value": 6429.367662197141, "unit": "score"}], "name": "dromaeo_css", "value": 6372.14131992698, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 112.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 128.0, 168.0, 126.0, 135.0, 125.0, 129.0, 153.0, 124.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 163.0, 162.0, 163.0, 161.0, 162.0, 162.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 106.0, 77.0, 76.0, 77.0, 77.0, 93.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 112.0, 86.0, 86.0, 86.0, 114.0, 86.0, 86.0, 109.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 148.0, 150.0, 149.0, 149.0, 149.0, 149.0, 150.0, 148.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 58.0, 58.0, 58.0, 60.0, 60.0, 59.0, 60.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 84.0, 84.0, 83.0, 84.0, 88.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 135.0, 131.0, 140.0, 131.0, 140.0, 149.0, 212.0, 137.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 178.0, 177.0, 179.0, 176.0, 176.0, 173.0, 178.0, 173.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 67.0, 69.0, 68.0, 72.0, 76.0, 68.0, 67.0, 67.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1548.6000000000001, "alertThreshold": 2.0}]} 12:17:36 INFO - Return code: 0 12:17:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:17:36 INFO - # TBPL SUCCESS # 12:17:36 INFO - Running post-action listener: _resource_record_post_action 12:17:36 INFO - [mozharness: 2017-01-11 20:17:36.291404Z] Finished run-tests step (success) 12:17:36 INFO - Running post-run listener: _resource_record_post_run 12:17:36 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8331264; Write bytes: 417005568; Read time: 1432; Write time: 367456 12:17:36 INFO - TinderboxPrint: CPU usage
13.2% 12:17:36 INFO - TinderboxPrint: I/O read bytes / time
8,331,264 / 1,432 12:17:36 INFO - TinderboxPrint: I/O write bytes / time
417,005,568 / 367,456 12:17:36 INFO - TinderboxPrint: CPU idle
8,338.6 (86.8%) 12:17:36 INFO - TinderboxPrint: CPU user
1,216.5 (12.7%) 12:17:36 INFO - TinderboxPrint: Swap in / out
0 / 0 12:17:36 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 102047744; Read time: 232; Write time: 98840 12:17:36 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 6111232; Write bytes: 314957824; Read time: 1084; Write time: 268616 12:17:36 INFO - Running post-run listener: _upload_blobber_files 12:17:36 INFO - Blob upload gear active. 12:17:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:17:36 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 12:17:36 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'] 12:17:36 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 12:17:37 INFO - (blobuploader) - INFO - Open directory for files ... 12:17:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:17:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:17:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:38 INFO - (blobuploader) - INFO - Done attempting. 12:17:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:17:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:17:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:39 INFO - (blobuploader) - INFO - Done attempting. 12:17:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:17:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:17:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:42 INFO - (blobuploader) - INFO - Done attempting. 12:17:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:17:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:17:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:43 INFO - (blobuploader) - INFO - Done attempting. 12:17:43 INFO - (blobuploader) - INFO - Iteration through files over. 12:17:43 INFO - Return code: 0 12:17:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:17:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:17:43 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/960e596579494b5946ecacab180ff4c97a6315d8c78452467dee8a6be1a392ae25fde8b5edc570812771678cc9b0976bca6b9ac147161c9712729373a7a9b6d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d7da8d773bd826faaaaf85a65f09af3640492585fadb1ca25887a00fef70b156203b0bbb17cb8e2edb1230162b01a98d10e94d3d7cbd8e84aaa9f6cca721e408", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c30b8a98f3ad499b73af7e8cf58a45797873982eb65ae04f600b255e374708597942ebe0c54de1c8270e181f2c1f941a7e3717837b48ad60588c5d295352b8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40da77d405a6171aa40c3ccff34598112ccfe876f1d545d11579a035f079572fc3df4d8262fb2c0cbb98c02f6013667278827ff735e466a152eab920bc683c9b"} 12:17:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:17:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:17:43 INFO - Contents: 12:17:43 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/960e596579494b5946ecacab180ff4c97a6315d8c78452467dee8a6be1a392ae25fde8b5edc570812771678cc9b0976bca6b9ac147161c9712729373a7a9b6d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d7da8d773bd826faaaaf85a65f09af3640492585fadb1ca25887a00fef70b156203b0bbb17cb8e2edb1230162b01a98d10e94d3d7cbd8e84aaa9f6cca721e408", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c30b8a98f3ad499b73af7e8cf58a45797873982eb65ae04f600b255e374708597942ebe0c54de1c8270e181f2c1f941a7e3717837b48ad60588c5d295352b8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40da77d405a6171aa40c3ccff34598112ccfe876f1d545d11579a035f079572fc3df4d8262fb2c0cbb98c02f6013667278827ff735e466a152eab920bc683c9b"} 12:17:43 INFO - Running post-run listener: copy_logs_to_upload_dir 12:17:43 INFO - Copying logs to upload dir... 12:17:43 INFO - mkdir: /builds/slave/test/build/upload/logs 12:17:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.682181 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-01-11 12:17:43.341687) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:43.345632) ========= 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=920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/960e596579494b5946ecacab180ff4c97a6315d8c78452467dee8a6be1a392ae25fde8b5edc570812771678cc9b0976bca6b9ac147161c9712729373a7a9b6d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d7da8d773bd826faaaaf85a65f09af3640492585fadb1ca25887a00fef70b156203b0bbb17cb8e2edb1230162b01a98d10e94d3d7cbd8e84aaa9f6cca721e408", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c30b8a98f3ad499b73af7e8cf58a45797873982eb65ae04f600b255e374708597942ebe0c54de1c8270e181f2c1f941a7e3717837b48ad60588c5d295352b8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40da77d405a6171aa40c3ccff34598112ccfe876f1d545d11579a035f079572fc3df4d8262fb2c0cbb98c02f6013667278827ff735e466a152eab920bc683c9b"} build_url:https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/XJGsc4VgRHqPXNBC9-1d3Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/960e596579494b5946ecacab180ff4c97a6315d8c78452467dee8a6be1a392ae25fde8b5edc570812771678cc9b0976bca6b9ac147161c9712729373a7a9b6d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d7da8d773bd826faaaaf85a65f09af3640492585fadb1ca25887a00fef70b156203b0bbb17cb8e2edb1230162b01a98d10e94d3d7cbd8e84aaa9f6cca721e408", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c30b8a98f3ad499b73af7e8cf58a45797873982eb65ae04f600b255e374708597942ebe0c54de1c8270e181f2c1f941a7e3717837b48ad60588c5d295352b8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40da77d405a6171aa40c3ccff34598112ccfe876f1d545d11579a035f079572fc3df4d8262fb2c0cbb98c02f6013667278827ff735e466a152eab920bc683c9b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:43.376649) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:43.377369) ========= 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=920470bf432669ad7e3a5387000001ca-1484164602.503487-1090947658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 12:17:43.461302) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:17:43.461703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 12:17:43.462267) ========= ========= Total master_lag: 0.16 =========