builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1475535270.17 results: success (0) buildid: 20161003124557 builduid: bd71a37d814747ebbd20543dddf2a01c revision: d09ba632630b5917b6757c6b647cae5125fe6449 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.169283) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.169856) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.170204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.186562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.187091) ========= 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=8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-03 15:54:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 97.3M=0s 2016-10-03 15:54:30 (97.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.304572) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.305515) ========= 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=8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014422 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.359913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:30.360638) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d09ba632630b5917b6757c6b647cae5125fe6449 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d09ba632630b5917b6757c6b647cae5125fe6449 --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=8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-03 15:54:30,894 truncating revision to first 12 chars 2016-10-03 15:54:30,894 Setting DEBUG logging. 2016-10-03 15:54:30,894 attempt 1/10 2016-10-03 15:54:30,894 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d09ba632630b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 15:54:31,191 unpacking tar archive at: mozilla-aurora-d09ba632630b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.640817 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:31.017696) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:31.018099) ========= script_repo_revision: d09ba632630b5917b6757c6b647cae5125fe6449 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:31.018607) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:31.018942) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 15:54:31.030203) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-03 15:54:31.031010) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:54:31 INFO - MultiFileLogger online at 20161003 15:54:31 in /builds/slave/test 15:54:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:54:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:54:31 INFO - {'append_to_log': False, 15:54:31 INFO - 'base_work_dir': '/builds/slave/test', 15:54:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:54:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:54:31 INFO - 'branch': 'Mozilla-Aurora', 15:54:31 INFO - 'buildbot_json_path': 'buildprops.json', 15:54:31 INFO - 'config_files': ('talos/linux_config.py',), 15:54:31 INFO - 'default_actions': ('clobber', 15:54:31 INFO - 'read-buildbot-config', 15:54:31 INFO - 'download-and-extract', 15:54:31 INFO - 'populate-webroot', 15:54:31 INFO - 'create-virtualenv', 15:54:31 INFO - 'install', 15:54:31 INFO - 'run-tests'), 15:54:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:54:31 INFO - 'download_minidump_stackwalk': True, 15:54:31 INFO - 'download_symbols': 'ondemand', 15:54:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:54:31 INFO - 'tooltool.py': '/tools/tooltool.py', 15:54:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:54:31 INFO - '/tools/misc-python/virtualenv.py')}, 15:54:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:54:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:54:31 INFO - 'installer_path': 'installer.exe', 15:54:31 INFO - 'log_level': 'info', 15:54:31 INFO - 'log_name': 'talos', 15:54:31 INFO - 'log_to_console': True, 15:54:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:54:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:54:31 INFO - 'opt_config_files': (), 15:54:31 INFO - 'pip_index': False, 15:54:31 INFO - 'sps_profile': False, 15:54:31 INFO - 'sps_profile_interval': 0, 15:54:31 INFO - 'suite': 'dromaeojs', 15:54:31 INFO - 'system_bits': '32', 15:54:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:54:31 INFO - 'title': 'talos-linux64-ix-038', 15:54:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:54:31 INFO - 'use_talos_json': True, 15:54:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:54:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:54:31 INFO - 'work_dir': 'build'} 15:54:31 INFO - [mozharness: 2016-10-03 22:54:31.658386Z] Running clobber step. 15:54:31 INFO - Running pre-action listener: _resource_record_pre_action 15:54:31 INFO - Running main action method: clobber 15:54:31 INFO - rmtree: /builds/slave/test/build 15:54:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:54:32 INFO - Running post-action listener: _resource_record_post_action 15:54:32 INFO - [mozharness: 2016-10-03 22:54:32.674538Z] Finished clobber step (success) 15:54:32 INFO - [mozharness: 2016-10-03 22:54:32.674639Z] Running read-buildbot-config step. 15:54:32 INFO - Running pre-action listener: _resource_record_pre_action 15:54:32 INFO - Running main action method: read_buildbot_config 15:54:32 INFO - Using buildbot properties: 15:54:32 INFO - { 15:54:32 INFO - "project": "", 15:54:32 INFO - "product": "firefox", 15:54:32 INFO - "script_repo_revision": "production", 15:54:32 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:54:32 INFO - "repository": "", 15:54:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:54:32 INFO - "buildid": "20161003124557", 15:54:32 INFO - "pgo_build": "True", 15:54:32 INFO - "basedir": "/builds/slave/test", 15:54:32 INFO - "buildnumber": 500, 15:54:32 INFO - "slavename": "talos-linux64-ix-038", 15:54:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:54:32 INFO - "platform": "ubuntu64_hw", 15:54:32 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:54:32 INFO - "branch": "mozilla-aurora", 15:54:32 INFO - "repo_path": "releases/mozilla-aurora", 15:54:32 INFO - "revision": "d09ba632630b5917b6757c6b647cae5125fe6449", 15:54:32 INFO - "stage_platform": "linux64-pgo", 15:54:32 INFO - "builduid": "bd71a37d814747ebbd20543dddf2a01c", 15:54:32 INFO - "slavebuilddir": "test" 15:54:32 INFO - } 15:54:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 15:54:32 INFO - Running post-action listener: _resource_record_post_action 15:54:32 INFO - [mozharness: 2016-10-03 22:54:32.677076Z] Finished read-buildbot-config step (success) 15:54:32 INFO - [mozharness: 2016-10-03 22:54:32.677220Z] Running download-and-extract step. 15:54:32 INFO - Running pre-action listener: _resource_record_pre_action 15:54:32 INFO - Running main action method: download_and_extract 15:54:32 INFO - mkdir: /builds/slave/test/build/tests 15:54: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')]} 15:54:32 INFO - trying https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:54:32 INFO - Downloading https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:54:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:54:33 INFO - Downloaded 1361 bytes. 15:54:33 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:54:33 INFO - Using the following test package requirements: 15:54:33 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 15:54:33 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:54:33 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:54:33 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'jsshell-linux-x86_64.zip'], 15:54:33 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:54:33 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 15:54:33 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:54:33 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:54:33 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:54:33 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:54:33 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:54:33 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:54:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 15:54:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:54:33 INFO - Fetch https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 15:54:33 INFO - Expected file size: 21902243 15:54:38 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:54:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 15:54:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:54:38 INFO - Fetch https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 15:54:38 INFO - Expected file size: 21902243 15:54:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 15:54:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:54:43 INFO - Fetch https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 15:54:44 INFO - Expected file size: 14414059 15:54:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:44 INFO - trying https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:54:44 INFO - Downloading https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:54:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:54:45 INFO - Downloaded 63908229 bytes. 15:54:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:54:45 INFO - mkdir: /builds/slave/test/properties 15:54:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:54:45 INFO - Writing to file /builds/slave/test/properties/build_url 15:54:45 INFO - Contents: 15:54:45 INFO - build_url:https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:54:46 INFO - Running post-action listener: _resource_record_post_action 15:54:46 INFO - Running post-action listener: set_extra_try_arguments 15:54:46 INFO - [mozharness: 2016-10-03 22:54:46.386179Z] Finished download-and-extract step (success) 15:54:46 INFO - [mozharness: 2016-10-03 22:54:46.386406Z] Running populate-webroot step. 15:54:46 INFO - Running pre-action listener: _resource_record_pre_action 15:54:46 INFO - Running main action method: populate_webroot 15:54:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:54:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:54:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:54:46 INFO - '--tppagecycles', 15:54:46 INFO - '7'], 15:54:46 INFO - 'tests': ['tsvgm']}}, 15:54:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:54:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:54:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:54:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:54:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['damp', 'tps']}, 15:54:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'tests': ['damp', 'tps']}, 15:54:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['dromaeo_dom']}, 15:54:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:54:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['basic_compositor_video']}, 15:54:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:54:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['a11yr', 15:54:46 INFO - 'ts_paint', 15:54:46 INFO - 'tpaint', 15:54:46 INFO - 'sessionrestore', 15:54:46 INFO - 'sessionrestore_no_auto_restore', 15:54:46 INFO - 'tabpaint']}, 15:54:46 INFO - 'other-e10s': {'tests': ['a11yr', 15:54:46 INFO - 'ts_paint', 15:54:46 INFO - 'tpaint', 15:54:46 INFO - 'sessionrestore', 15:54:46 INFO - 'sessionrestore_no_auto_restore', 15:54:46 INFO - 'tabpaint']}, 15:54:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:54:46 INFO - 'ts_paint', 15:54:46 INFO - 'tpaint', 15:54:46 INFO - 'sessionrestore', 15:54:46 INFO - 'sessionrestore_no_auto_restore', 15:54:46 INFO - 'tabpaint']}, 15:54:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:54:46 INFO - 'ts_paint', 15:54:46 INFO - 'tpaint', 15:54:46 INFO - 'sessionrestore', 15:54:46 INFO - 'sessionrestore_no_auto_restore', 15:54:46 INFO - 'tabpaint']}, 15:54:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['a11yr', 15:54:46 INFO - 'ts_paint', 15:54:46 INFO - 'tpaint', 15:54:46 INFO - 'sessionrestore', 15:54:46 INFO - 'sessionrestore_no_auto_restore', 15:54:46 INFO - 'tabpaint']}, 15:54:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['a11yr', 15:54:46 INFO - 'ts_paint', 15:54:46 INFO - 'tpaint', 15:54:46 INFO - 'sessionrestore', 15:54:46 INFO - 'sessionrestore_no_auto_restore', 15:54:46 INFO - 'tabpaint']}, 15:54:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['tsvgx', 15:54:46 INFO - 'tsvgr_opacity', 15:54:46 INFO - 'tart', 15:54:46 INFO - 'tscrollx', 15:54:46 INFO - 'cart']}, 15:54:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:54:46 INFO - 'tsvgr_opacity', 15:54:46 INFO - 'tart', 15:54:46 INFO - 'tscrollx', 15:54:46 INFO - 'cart']}, 15:54:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'talos_options': ['--disable-e10s'], 15:54:46 INFO - 'tests': ['tp5o']}, 15:54:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'tests': ['tp5o']}, 15:54:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'talos_options': ['--disable-e10s', 15:54:46 INFO - '--xperf_path', 15:54:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:46 INFO - 'tests': ['tp5n']}, 15:54:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:46 INFO - 'talos_options': ['--xperf_path', 15:54:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:46 INFO - 'tests': ['tp5n']}}, 15:54:46 INFO - 'talos.zip': {'path': '', 15:54:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:54:46 INFO - Running post-action listener: _resource_record_post_action 15:54:46 INFO - [mozharness: 2016-10-03 22:54:46.404011Z] Finished populate-webroot step (success) 15:54:46 INFO - [mozharness: 2016-10-03 22:54:46.404099Z] Running create-virtualenv step. 15:54:46 INFO - Running pre-action listener: _resource_record_pre_action 15:54:46 INFO - Running main action method: create_virtualenv 15:54:46 INFO - Creating virtualenv /builds/slave/test/build/venv 15:54:46 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 15:54:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:54:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:54:46 INFO - Using real prefix '/usr' 15:54:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:54:47 INFO - Installing distribute.............................................................................................................................................................................................done. 15:54:48 INFO - Installing pip.................done. 15:54:49 INFO - Return code: 0 15:54:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:49 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:54:49 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 15:54:49 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 15:54:49 INFO - Using env: {'DISPLAY': ':0', 15:54:49 INFO - 'HOME': '/home/cltbld', 15:54:49 INFO - 'LANG': 'en_US.UTF-8', 15:54:49 INFO - 'LANGUAGE': 'en_US:en', 15:54:49 INFO - 'LOGNAME': 'cltbld', 15:54:49 INFO - 'MAIL': '/var/mail/cltbld', 15:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:49 INFO - 'MOZ_NO_REMOTE': '1', 15:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:49 INFO - 'NO_EM_RESTART': '1', 15:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:49 INFO - 'PWD': '/builds/slave/test', 15:54:49 INFO - 'SHELL': '/bin/bash', 15:54:49 INFO - 'SHLVL': '1', 15:54:49 INFO - 'TERM': 'linux', 15:54:49 INFO - 'TMOUT': '86400', 15:54:49 INFO - 'USER': 'cltbld', 15:54:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:49 INFO - '_': '/tools/buildbot/bin/python'} 15:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:49 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 15:54:49 INFO - Cleaning up... 15:54:49 INFO - Return code: 0 15:54:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:49 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:54:49 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 15:54:49 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 15:54:49 INFO - Using env: {'DISPLAY': ':0', 15:54:49 INFO - 'HOME': '/home/cltbld', 15:54:49 INFO - 'LANG': 'en_US.UTF-8', 15:54:49 INFO - 'LANGUAGE': 'en_US:en', 15:54:49 INFO - 'LOGNAME': 'cltbld', 15:54:49 INFO - 'MAIL': '/var/mail/cltbld', 15:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:49 INFO - 'MOZ_NO_REMOTE': '1', 15:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:49 INFO - 'NO_EM_RESTART': '1', 15:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:49 INFO - 'PWD': '/builds/slave/test', 15:54:49 INFO - 'SHELL': '/bin/bash', 15:54:49 INFO - 'SHLVL': '1', 15:54:49 INFO - 'TERM': 'linux', 15:54:49 INFO - 'TMOUT': '86400', 15:54:49 INFO - 'USER': 'cltbld', 15:54:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:49 INFO - '_': '/tools/buildbot/bin/python'} 15:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:49 INFO - Downloading/unpacking psutil>=3.1.1 15:54:49 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 15:54:49 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 15:54:49 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 15:54:49 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 15:54:49 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 15:54:49 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 15:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:54:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:51 INFO - Installing collected packages: psutil 15:54:51 INFO - Running setup.py install for psutil 15:54:51 INFO - building 'psutil._psutil_linux' extension 15:54:51 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 15:54:51 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 15:54:51 INFO - building 'psutil._psutil_posix' extension 15:54:51 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 15:54:51 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 15:54:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:51 INFO - Successfully installed psutil 15:54:51 INFO - Cleaning up... 15:54:51 INFO - Return code: 0 15:54:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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')]} 15:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:54:51 INFO - Using env: {'DISPLAY': ':0', 15:54:51 INFO - 'HOME': '/home/cltbld', 15:54:51 INFO - 'LANG': 'en_US.UTF-8', 15:54:51 INFO - 'LANGUAGE': 'en_US:en', 15:54:51 INFO - 'LOGNAME': 'cltbld', 15:54:51 INFO - 'MAIL': '/var/mail/cltbld', 15:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:51 INFO - 'MOZ_NO_REMOTE': '1', 15:54:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:51 INFO - 'NO_EM_RESTART': '1', 15:54:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:51 INFO - 'PWD': '/builds/slave/test', 15:54:51 INFO - 'SHELL': '/bin/bash', 15:54:51 INFO - 'SHLVL': '1', 15:54:51 INFO - 'TERM': 'linux', 15:54:51 INFO - 'TMOUT': '86400', 15:54:51 INFO - 'USER': 'cltbld', 15:54:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:51 INFO - '_': '/tools/buildbot/bin/python'} 15:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:54:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:54:54 INFO - Installing collected packages: mozsystemmonitor 15:54:54 INFO - Running setup.py install for mozsystemmonitor 15:54:54 INFO - Successfully installed mozsystemmonitor 15:54:54 INFO - Cleaning up... 15:54:54 INFO - Return code: 0 15:54:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:54:54 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')]} 15:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:54 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')]} 15:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:54 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:54:54 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 15:54:54 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 15:54:54 INFO - Using env: {'DISPLAY': ':0', 15:54:54 INFO - 'HOME': '/home/cltbld', 15:54:54 INFO - 'LANG': 'en_US.UTF-8', 15:54:54 INFO - 'LANGUAGE': 'en_US:en', 15:54:54 INFO - 'LOGNAME': 'cltbld', 15:54:54 INFO - 'MAIL': '/var/mail/cltbld', 15:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:54 INFO - 'MOZ_NO_REMOTE': '1', 15:54:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:54 INFO - 'NO_EM_RESTART': '1', 15:54:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:54 INFO - 'PWD': '/builds/slave/test', 15:54:54 INFO - 'SHELL': '/bin/bash', 15:54:54 INFO - 'SHLVL': '1', 15:54:54 INFO - 'TERM': 'linux', 15:54:54 INFO - 'TMOUT': '86400', 15:54:54 INFO - 'USER': 'cltbld', 15:54:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:54 INFO - '_': '/tools/buildbot/bin/python'} 15:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:54 INFO - Downloading/unpacking jsonschema==2.5.1 15:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:56 INFO - warning: no files found matching '*.txt' 15:54:56 INFO - no previously-included directories found matching 'build' 15:54:56 INFO - no previously-included directories found matching 'dist' 15:54:56 INFO - no previously-included directories found matching '.git*' 15:54:56 INFO - Installing collected packages: jsonschema, functools32 15:54:56 INFO - Running setup.py install for functools32 15:54:56 INFO - warning: no files found matching '*.txt' 15:54:56 INFO - no previously-included directories found matching 'build' 15:54:56 INFO - no previously-included directories found matching 'dist' 15:54:56 INFO - no previously-included directories found matching '.git*' 15:54:56 INFO - Successfully installed jsonschema functools32 15:54:56 INFO - Cleaning up... 15:54:56 INFO - Return code: 0 15:54:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:54:56 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')]} 15:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:56 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')]} 15:54:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:56 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:54:56 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 15:54:56 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 15:54:56 INFO - Using env: {'DISPLAY': ':0', 15:54:56 INFO - 'HOME': '/home/cltbld', 15:54:56 INFO - 'LANG': 'en_US.UTF-8', 15:54:56 INFO - 'LANGUAGE': 'en_US:en', 15:54:56 INFO - 'LOGNAME': 'cltbld', 15:54:56 INFO - 'MAIL': '/var/mail/cltbld', 15:54:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:56 INFO - 'MOZ_NO_REMOTE': '1', 15:54:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:56 INFO - 'NO_EM_RESTART': '1', 15:54:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:56 INFO - 'PWD': '/builds/slave/test', 15:54:56 INFO - 'SHELL': '/bin/bash', 15:54:56 INFO - 'SHLVL': '1', 15:54:56 INFO - 'TERM': 'linux', 15:54:56 INFO - 'TMOUT': '86400', 15:54:56 INFO - 'USER': 'cltbld', 15:54:56 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:54:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:56 INFO - '_': '/tools/buildbot/bin/python'} 15:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:54:57 INFO - Cleaning up... 15:54:57 INFO - Return code: 0 15:54:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:57 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:54:57 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 15:54:57 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 15:54:57 INFO - Using env: {'DISPLAY': ':0', 15:54:57 INFO - 'HOME': '/home/cltbld', 15:54:57 INFO - 'LANG': 'en_US.UTF-8', 15:54:57 INFO - 'LANGUAGE': 'en_US:en', 15:54:57 INFO - 'LOGNAME': 'cltbld', 15:54:57 INFO - 'MAIL': '/var/mail/cltbld', 15:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:57 INFO - 'MOZ_NO_REMOTE': '1', 15:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:57 INFO - 'NO_EM_RESTART': '1', 15:54:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:57 INFO - 'PWD': '/builds/slave/test', 15:54:57 INFO - 'SHELL': '/bin/bash', 15:54:57 INFO - 'SHLVL': '1', 15:54:57 INFO - 'TERM': 'linux', 15:54:57 INFO - 'TMOUT': '86400', 15:54:57 INFO - 'USER': 'cltbld', 15:54:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:57 INFO - '_': '/tools/buildbot/bin/python'} 15:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:54:57 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 15:54:57 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 15:54:57 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 15:54:57 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 15:54:57 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 15:54:57 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 15:54:59 INFO - Downloading blobuploader-1.2.4.tar.gz 15:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:54:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:54:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 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 15:54:59 INFO - Downloading docopt-0.6.1.tar.gz 15:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:54:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:54:59 INFO - Running setup.py install for blobuploader 15:55:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:55:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:55:00 INFO - Running setup.py install for requests 15:55:00 INFO - Running setup.py install for docopt 15:55:00 INFO - Successfully installed blobuploader requests docopt 15:55:00 INFO - Cleaning up... 15:55:00 INFO - Return code: 0 15:55:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:00 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')]} 15:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:00 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')]} 15:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:00 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:55:00 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 15:55:00 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 15:55:00 INFO - Using env: {'DISPLAY': ':0', 15:55:00 INFO - 'HOME': '/home/cltbld', 15:55:00 INFO - 'LANG': 'en_US.UTF-8', 15:55:00 INFO - 'LANGUAGE': 'en_US:en', 15:55:00 INFO - 'LOGNAME': 'cltbld', 15:55:00 INFO - 'MAIL': '/var/mail/cltbld', 15:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:00 INFO - 'MOZ_NO_REMOTE': '1', 15:55:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:00 INFO - 'NO_EM_RESTART': '1', 15:55:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:00 INFO - 'PWD': '/builds/slave/test', 15:55:00 INFO - 'SHELL': '/bin/bash', 15:55:00 INFO - 'SHLVL': '1', 15:55:00 INFO - 'TERM': 'linux', 15:55:00 INFO - 'TMOUT': '86400', 15:55:00 INFO - 'USER': 'cltbld', 15:55:00 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:00 INFO - '_': '/tools/buildbot/bin/python'} 15:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:00 INFO - Running setup.py (path:/tmp/pip-lQbbLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:00 INFO - Running setup.py (path:/tmp/pip-B_jRda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:00 INFO - Running setup.py (path:/tmp/pip-hwWVem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:01 INFO - Running setup.py (path:/tmp/pip-XYybP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:01 INFO - Running setup.py (path:/tmp/pip-79ErxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:01 INFO - Running setup.py (path:/tmp/pip-8GgcOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:01 INFO - Running setup.py (path:/tmp/pip-sFwuOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:01 INFO - Running setup.py (path:/tmp/pip-4Tf0db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:01 INFO - Running setup.py (path:/tmp/pip-mKkNC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:01 INFO - Running setup.py (path:/tmp/pip-s9ulFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:01 INFO - Running setup.py (path:/tmp/pip-_lwqLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:01 INFO - Running setup.py (path:/tmp/pip-dWtgcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:01 INFO - Running setup.py (path:/tmp/pip-QLBZe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:01 INFO - Running setup.py (path:/tmp/pip-5zDIsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:01 INFO - Running setup.py (path:/tmp/pip-dg6q14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:01 INFO - Running setup.py (path:/tmp/pip-TrIyLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:01 INFO - Running setup.py (path:/tmp/pip-OaHb91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:55:02 INFO - Running setup.py install for manifestparser 15:55:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Running setup.py install for mozcrash 15:55:02 INFO - Running setup.py install for mozdebug 15:55:02 INFO - Running setup.py install for mozdevice 15:55:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Running setup.py install for mozfile 15:55:02 INFO - Running setup.py install for mozhttpd 15:55:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Running setup.py install for mozinfo 15:55:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Running setup.py install for mozInstall 15:55:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Running setup.py install for mozleak 15:55:02 INFO - Running setup.py install for mozlog 15:55:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:55:03 INFO - Running setup.py install for moznetwork 15:55:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:55:03 INFO - Running setup.py install for mozprocess 15:55:03 INFO - Running setup.py install for mozprofile 15:55:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:55:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:55:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:55:03 INFO - Running setup.py install for mozrunner 15:55:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:55:03 INFO - Running setup.py install for mozscreenshot 15:55:03 INFO - Running setup.py install for moztest 15:55:03 INFO - Running setup.py install for mozversion 15:55:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:55:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:55:03 INFO - Cleaning up... 15:55:03 INFO - Return code: 0 15:55:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:03 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:55:03 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 15:55:03 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 15:55:03 INFO - Using env: {'DISPLAY': ':0', 15:55:03 INFO - 'HOME': '/home/cltbld', 15:55:03 INFO - 'LANG': 'en_US.UTF-8', 15:55:03 INFO - 'LANGUAGE': 'en_US:en', 15:55:03 INFO - 'LOGNAME': 'cltbld', 15:55:03 INFO - 'MAIL': '/var/mail/cltbld', 15:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:03 INFO - 'MOZ_NO_REMOTE': '1', 15:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:03 INFO - 'NO_EM_RESTART': '1', 15:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:03 INFO - 'PWD': '/builds/slave/test', 15:55:03 INFO - 'SHELL': '/bin/bash', 15:55:03 INFO - 'SHLVL': '1', 15:55:03 INFO - 'TERM': 'linux', 15:55:03 INFO - 'TMOUT': '86400', 15:55:03 INFO - 'USER': 'cltbld', 15:55:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:03 INFO - '_': '/tools/buildbot/bin/python'} 15:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:04 INFO - Running setup.py (path:/tmp/pip-65oaqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:04 INFO - Running setup.py (path:/tmp/pip-rz7N8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:04 INFO - Running setup.py (path:/tmp/pip-vqhyKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:04 INFO - Running setup.py (path:/tmp/pip-9kP5Cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:04 INFO - Running setup.py (path:/tmp/pip-zGcgBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:04 INFO - Running setup.py (path:/tmp/pip-g_wNzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:04 INFO - Running setup.py (path:/tmp/pip-smfVwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:04 INFO - Running setup.py (path:/tmp/pip-vhT9UT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:04 INFO - Running setup.py (path:/tmp/pip-Gi6u0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:04 INFO - Running setup.py (path:/tmp/pip-KQPvHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:04 INFO - Running setup.py (path:/tmp/pip-UlaKxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:04 INFO - Running setup.py (path:/tmp/pip-BfExeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:04 INFO - Running setup.py (path:/tmp/pip-ifVd24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:05 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)) 15:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:05 INFO - Running setup.py (path:/tmp/pip-wiY3q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 15:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:05 INFO - Running setup.py (path:/tmp/pip-3un4g7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:05 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)) 15:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:05 INFO - Running setup.py (path:/tmp/pip-F035Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 15:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:05 INFO - Running setup.py (path:/tmp/pip-Osp0kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:05 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)) 15:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:55:05 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)) 15:55:05 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:55:05 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:55:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:55:05 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 15:55:05 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 15:55:05 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 15:55:05 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 15:55:05 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 15:55:05 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 15:55:07 INFO - Downloading blessings-1.6.tar.gz 15:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:55:07 INFO - Installing collected packages: blessings 15:55:07 INFO - Running setup.py install for blessings 15:55:07 INFO - Successfully installed blessings 15:55:07 INFO - Cleaning up... 15:55:07 INFO - Return code: 0 15:55:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:55:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:07 INFO - Reading from file tmpfile_stdout 15:55:07 INFO - Current package versions: 15:55:07 INFO - argparse == 1.2.1 15:55:07 INFO - blessings == 1.6 15:55:07 INFO - blobuploader == 1.2.4 15:55:07 INFO - docopt == 0.6.1 15:55:07 INFO - functools32 == 3.2.3-2 15:55:07 INFO - jsonschema == 2.5.1 15:55:07 INFO - manifestparser == 1.1 15:55:07 INFO - mozInstall == 1.12 15:55:07 INFO - mozcrash == 0.17 15:55:07 INFO - mozdebug == 0.1 15:55:07 INFO - mozdevice == 0.48 15:55:07 INFO - mozfile == 1.2 15:55:07 INFO - mozhttpd == 0.7 15:55:07 INFO - mozinfo == 0.9 15:55:07 INFO - mozleak == 0.1 15:55:07 INFO - mozlog == 3.4 15:55:07 INFO - moznetwork == 0.27 15:55:07 INFO - mozprocess == 0.23 15:55:07 INFO - mozprofile == 0.28 15:55:07 INFO - mozrunner == 6.12 15:55:07 INFO - mozscreenshot == 0.1 15:55:07 INFO - mozsystemmonitor == 0.3 15:55:07 INFO - moztest == 0.7 15:55:07 INFO - mozversion == 1.4 15:55:07 INFO - psutil == 3.1.1 15:55:07 INFO - requests == 1.2.3 15:55:07 INFO - wsgiref == 0.1.2 15:55:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:07 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')]} 15:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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')]} 15:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:55:07 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 15:55:07 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 15:55:07 INFO - Using env: {'DISPLAY': ':0', 15:55:07 INFO - 'HOME': '/home/cltbld', 15:55:07 INFO - 'LANG': 'en_US.UTF-8', 15:55:07 INFO - 'LANGUAGE': 'en_US:en', 15:55:07 INFO - 'LOGNAME': 'cltbld', 15:55:07 INFO - 'MAIL': '/var/mail/cltbld', 15:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:07 INFO - 'MOZ_NO_REMOTE': '1', 15:55:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:07 INFO - 'NO_EM_RESTART': '1', 15:55:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:07 INFO - 'PWD': '/builds/slave/test', 15:55:07 INFO - 'SHELL': '/bin/bash', 15:55:07 INFO - 'SHLVL': '1', 15:55:07 INFO - 'TERM': 'linux', 15:55:07 INFO - 'TMOUT': '86400', 15:55:07 INFO - 'USER': 'cltbld', 15:55:07 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:07 INFO - '_': '/tools/buildbot/bin/python'} 15:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 INFO - Cleaning up... 15:55:07 INFO - Return code: 0 15:55:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:55:07 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')]} 15:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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')]} 15:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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 0x7f1c4df5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, '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': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', '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 15:55:07 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 15:55:07 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 15:55:07 INFO - Using env: {'DISPLAY': ':0', 15:55:07 INFO - 'HOME': '/home/cltbld', 15:55:07 INFO - 'LANG': 'en_US.UTF-8', 15:55:07 INFO - 'LANGUAGE': 'en_US:en', 15:55:07 INFO - 'LOGNAME': 'cltbld', 15:55:07 INFO - 'MAIL': '/var/mail/cltbld', 15:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:07 INFO - 'MOZ_NO_REMOTE': '1', 15:55:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:07 INFO - 'NO_EM_RESTART': '1', 15:55:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:07 INFO - 'PWD': '/builds/slave/test', 15:55:07 INFO - 'SHELL': '/bin/bash', 15:55:07 INFO - 'SHLVL': '1', 15:55:07 INFO - 'TERM': 'linux', 15:55:07 INFO - 'TMOUT': '86400', 15:55:07 INFO - 'USER': 'cltbld', 15:55:07 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:07 INFO - '_': '/tools/buildbot/bin/python'} 15:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:55:08 INFO - Cleaning up... 15:55:08 INFO - Return code: 0 15:55:08 INFO - Running post-action listener: _resource_record_post_action 15:55:08 INFO - Running post-action listener: _start_resource_monitoring 15:55:08 INFO - Starting resource monitoring. 15:55:08 INFO - [mozharness: 2016-10-03 22:55:08.132295Z] Finished create-virtualenv step (success) 15:55:08 INFO - [mozharness: 2016-10-03 22:55:08.132747Z] Running install step. 15:55:08 INFO - Running pre-action listener: _resource_record_pre_action 15:55:08 INFO - Running main action method: install 15:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:08 INFO - Reading from file tmpfile_stdout 15:55:08 INFO - Detecting whether we're running mozinstall >=1.0... 15:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:55:08 INFO - Reading from file tmpfile_stdout 15:55:08 INFO - Output received: 15:55:08 INFO - Usage: mozinstall [options] installer 15:55:08 INFO - Options: 15:55:08 INFO - -h, --help show this help message and exit 15:55:08 INFO - -d DEST, --destination=DEST 15:55:08 INFO - Directory to install application into. [default: 15:55:08 INFO - "/builds/slave/test"] 15:55:08 INFO - --app=APP Application being installed. [default: firefox] 15:55:08 INFO - mkdir: /builds/slave/test/build/application 15:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:55:24 INFO - Reading from file tmpfile_stdout 15:55:24 INFO - Output received: 15:55:24 INFO - /builds/slave/test/build/application/firefox/firefox 15:55:24 INFO - Running post-action listener: _resource_record_post_action 15:55:24 INFO - [mozharness: 2016-10-03 22:55:24.374813Z] Finished install step (success) 15:55:24 INFO - [mozharness: 2016-10-03 22:55:24.375057Z] Running run-tests step. 15:55:24 INFO - Running pre-action listener: _resource_record_pre_action 15:55:24 INFO - Running main action method: run_tests 15:55:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:55:24 INFO - Python 2.7.3 15:55:24 INFO - Return code: 0 15:55:24 INFO - grabbing minidump binary from tooltool 15:55: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')]} 15:55:24 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 0x1f9ef60>, '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 0x1f9fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fa01d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:55:24 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 15:55:24 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 15:55:24 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 15:55:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:55:24 INFO - Return code: 0 15:55:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:55:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:55:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:55:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:55:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:55:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 15:55:24 INFO - Using env: {'DISPLAY': ':0', 15:55:24 INFO - 'HOME': '/home/cltbld', 15:55:24 INFO - 'LANG': 'en_US.UTF-8', 15:55:24 INFO - 'LANGUAGE': 'en_US:en', 15:55:24 INFO - 'LOGNAME': 'cltbld', 15:55:24 INFO - 'MAIL': '/var/mail/cltbld', 15:55:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:55:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:24 INFO - 'MOZ_NO_REMOTE': '1', 15:55:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:55:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:24 INFO - 'NO_EM_RESTART': '1', 15:55:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:24 INFO - 'PWD': '/builds/slave/test', 15:55:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:55:24 INFO - 'SHELL': '/bin/bash', 15:55:24 INFO - 'SHLVL': '1', 15:55:24 INFO - 'TERM': 'linux', 15:55:24 INFO - 'TMOUT': '86400', 15:55:24 INFO - 'USER': 'cltbld', 15:55:24 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396', 15:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:24 INFO - '_': '/tools/buildbot/bin/python'} 15:55:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:55:24 INFO - mozversion application_buildid: 20161003124557 15:55:24 INFO - mozversion application_changeset: d09ba632630b5917b6757c6b647cae5125fe6449 15:55:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:55:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:55:24 INFO - mozversion application_name: Firefox 15:55:24 INFO - mozversion application_remotingname: firefox-dev 15:55:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:55:24 INFO - mozversion application_vendor: Mozilla 15:55:24 INFO - mozversion application_version: 51.0a2 15:55:24 INFO - mozversion platform_buildid: 20161003124557 15:55:24 INFO - mozversion platform_changeset: d09ba632630b5917b6757c6b647cae5125fe6449 15:55:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:55:24 INFO - mozversion platform_version: 51.0a2 15:55:24 INFO - using testdate: 1475535324 15:55:24 INFO - actual date: 1475535324 15:55:24 INFO - starting webserver on 'localhost:45279' 15:55:24 INFO - SUITE-START | Running 2 tests 15:55:24 INFO - TEST-START | dromaeo_css 15:55:24 INFO - operating with platform_type : linux_ 15:55:24 INFO - Initialising browser for dromaeo_css test... 15:55:24 INFO - TEST-INFO | started process 29765 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE_RrR2/profile http://localhost:45279/getInfo.html) 15:55:28 INFO - PROCESS | 29765 | __metrics Screen width/height:1600/1200 15:55:28 INFO - PROCESS | 29765 | colorDepth:24 15:55:28 INFO - PROCESS | 29765 | Browser inner width/height: 1024/697 15:55:28 INFO - PROCESS | 29765 | __metrics 15:55:29 INFO - PROCESS | 29765 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:55:29 INFO - PROCESS | 29765 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:55:30 INFO - PROCESS | 29765 | console.error: PushService: 15:55:30 INFO - PROCESS | 29765 | stateChangeProcessEnqueue: Error transitioning state 15:55:30 INFO - PROCESS | 29765 | UnknownError 15:55:30 INFO - TEST-INFO | 29765: exit 0 15:55:30 INFO - Browser initialized. 15:55:30 INFO - Running cycle 1/1 for dromaeo_css test... 15:55:30 INFO - TEST-INFO | started process 29856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE_RrR2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:55:42 INFO - PROCESS | 29856 | RSS: Main: 144371712 15:55:42 INFO - PROCESS | 29856 | 15:56:33 INFO - PROCESS | 29856 | 1475535393651 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:58:43 INFO - PROCESS | 29856 | Cycle 1(1): loaded http://localhost:45279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45279/tests/dromaeo/cssquery-ext.html) 15:58:44 INFO - PROCESS | 29856 | RSS: Main: 245563392 15:58:44 INFO - PROCESS | 29856 | 16:01:46 INFO - PROCESS | 29856 | Cycle 1(1): loaded http://localhost:45279/tests/dromaeo/cssquery-ext.html (next: http://localhost:45279/tests/dromaeo/cssquery-jquery.html) 16:01:47 INFO - PROCESS | 29856 | RSS: Main: 232738816 16:01:47 INFO - PROCESS | 29856 | 16:04:49 INFO - PROCESS | 29856 | Cycle 1(1): loaded http://localhost:45279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45279/tests/dromaeo/cssquery-mootools.html) 16:04:49 INFO - PROCESS | 29856 | RSS: Main: 172482560 16:04:49 INFO - PROCESS | 29856 | 16:07:51 INFO - PROCESS | 29856 | Cycle 1(1): loaded http://localhost:45279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45279/tests/dromaeo/cssquery-prototype.html) 16:07:52 INFO - PROCESS | 29856 | RSS: Main: 200024064 16:07:52 INFO - PROCESS | 29856 | 16:10:55 INFO - PROCESS | 29856 | Cycle 1(1): loaded http://localhost:45279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45279/tests/dromaeo/cssquery-yui.html) 16:10:55 INFO - PROCESS | 29856 | RSS: Main: 176672768 16:10:55 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | Cycle 1(1): loaded http://localhost:45279/tests/dromaeo/cssquery-yui.html (next: http://localhost:45279/tests/dromaeo/cssquery-dojo.html) 16:13:58 INFO - PROCESS | 29856 | RSS: Main: 261234688 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | __start_tp_report 16:13:58 INFO - PROCESS | 29856 | _x_x_mozilla_page_load 16:13:58 INFO - PROCESS | 29856 | _x_x_mozilla_page_load_details 16:13:58 INFO - PROCESS | 29856 | |i|pagename|runs| 16:13:58 INFO - PROCESS | 29856 | |0;dojo.html;3083.414151311251;3124.0627811656495;3143.0570828751356;3163.114327988164;3172.080096771936;5587.05020146575;5665.575081868861;5679.318481782192;5702.45826646468;5730.65616063036;5679.687617181054;5768.913466297999;5772.393898640646;5785;5815.621984571;5179.19722443022;5194.948050519503;5208.47915208478;5253.212018197282;5282.7358632068435;5131.486851314858;5281.735913204344;5299.099153143956;5301.496357845998;5328.893422131555;5082.0598189334905;5098.617603679721;5099.796008159669;5121.257417674435;5138.075146473633;5097.413797413308;5117.744112794364;5139.049275883974;5181.171012637959;5189.792408303663;5048.848534543933;5092.770825312869;5153.3558305211845;5168.198929165986;5190.558802501754;5456.863578410532;5474.343078830566;5489.615726899118;5547.750351234205;5624.409437009096;5878.970605146989;5887.61730487517;5900.64596124234;5998.340182579913;5998.940010599882;6061.3029501607;6065.120557519135;6070.96964515179;6071.817845464599;6086.45221930024;5537.640053396516;5574.498295153415;5592.049351610236;5653.660780353192;5661.745221465044;6242.500599951993;6242.563020588515;6275.96862015687;6281.78013769525;6282.120503129566;6001.189839371629;6013.51891848651;6016.398360163948;6016.398360163948;6058.636481811105;5878.647281163058;5886.470217680429;5900.4984576310635;5909.734061967221;5924.7333869975955;5957.255343082114;5973.1338955852025;5973.522118230531;5977.850553736192;6005.489533389711;5914.497267732205;5983.521318294526;5993.910091348634;5995.250593675791;6029.487493563009;4508.684392092522;4535.931961020588;4547.386102876136;4601.401817763736;4619.86140415788;4085.305498065336;4110.095778928631;4117.07365842683;4126.442930204384;4149.2738770715605;5687.459691329317;5722.37053924068;5724.427557244242;5725.742341594639;5734.426557344315;5888.057910734262;5926.318473375617;5944.851378715567;6034.758609655652;6055.697215139226;5588.161775733673;5711.086226203871;5723.313202415737;5733.942660573341;5745.482906538432;5072.898542029138;5077.339945807095;5084.034033533617;5084.161113416319;5084.466131056186;6070.271567412027;6140.785072522441;6179.721912513947;6249.437550620466;6265.498760099181;5984.072468767348;5985.042393217239;6009.038553831366;6115.422885572139;6120.050983828623;6296.590721603081;6297.496200303873;6308.653024083719;6311;6322.589031712924;5681.261436013292;5993.460588547052;6002.279726432856;6012.128241405059;6013.7293821779;5699.800506982236;5958.672273024852;5971.343152253162;5979.461848433488;6110.680268050674;5922.466977972003;5977.9103313451205;5979.790707325223;5982.970085149721;6009.579329446897;5791.102379131242;5986.551008674454;5992.370801065826;6003.219581454383;6008.639481631116;5721.914171287517;5809.622374545641;5834.416558344132;5873.236479257669;5989.1914591529585;5992.820215393545;6004.189434426296;6016.608920420334;6019.428154325332;6020.638761674139;5608.270924779753;5664.773409063591;5850.853728656818;5855.268091488564;5866.325372582208 16:13:58 INFO - PROCESS | 29856 | |1;ext.html;596.5585466754603;629.3046183966717;646.1632186318717;649.5096202367212;668.8996650502427;34598.48102278465;35239.82380088099;38173;46818.531814681846;47853.28220076702;47971;48328;48360.274595881005;48374.51625483744;48374.75812620932;9405.717828465156;9486.430814151174;9503.382280151802;9523.57143928525;9559.426434413923;8471.576421178916;8678.088800675932;11001.779964400706;11248.831267530994;11261.380624065672;11540.422978851066;11630.941845290761;11634.6509604712;11635.534578616887;11648.126390520703;8619.267362274215;11319.830202546967;11397.145214108976;11401.315921044681;11434.885651143506;8398.496090234545;10736.516856741466;11082.501287442004;11093.16801239902;11473.827892581618;45243.77378113121;45614;45647.31529027067;45653.77173114147;45721;3888.844446222176;4014.558398576154;4236;4268.167707297078;4549.385832912581;35052.07867901869;35233.11917202065;35688.46467302993;36079.4588081179;36669.08327291812;4656.837010704609;4708.223143181371;4812.7352995585225;4854.781534830906;4857.17428037231;366508;366797.9999999973;367732;368190;369290;39016.414753778714;66452.00965956543;66775.33224667788;66820.33179668238;66865;39049.609503905165;41563.376549351786;41651.7917410411;41707.37438938418;41733.1653366931;17604.207810648553;17646.1176941155;17788.55528611769;17795.82204177942;17798.288068477374;13119.147255428366;13445.46218151263;14799.334029968677;14859.479918202811;14912.403503859941;9293.953530232306;9333.20667743236;9646.18007469373;9655.275854310814;9757.316987810786;3607.873724419685;3640.8167345612783;3662.249238906018;3724;3755.7746535207975;1038.1850247555633;1057.6245432871374;1083.4853444613746;1134.2910680824484;1151.7811615792973;32787.83606081954;35198.824005879804;35228.64771352305;35335.36267436014;35621.28757424837;1146.856642919635;1162.0703437250165;1193.427154965604;1274.025041869355;1315.7631626307166;18328.175232114587;18469.261229550666;18497.445076647724;18538.536536586424;18610.813891860907;542197;544035;545744;545992;550998;65399;65806.67096664582;66020;66049.33950660528;66071.66964165149;1309.4696647857634;1370.3011464153353;1428;1434.8995570310187;1474.2039298778968;31166.22084447907;35646.28707425785;36276.45585316275;36377.45433818442;37152.44271335878;58350.124748127964;58794.70602647131;59098;59290;59357;468309;474409;474606;475379;475904;46500;47355.763221183675;48213.75893120512;51370.486295138064;51564.48435515597;52698.47301526936;52894.735526322125;52907.20639190338;53085;53257.20114198213;43990.56009439864;44121.55878441175;44838;45911.54088459073;49073;46581.58751106001;47150.528494714614;47266.52733472621;47273.29090063721;47916.520834791205;43737;44514.77742611266;44630.55369446394;45649.77175114103;47364;38574.80712596419;40114;40855.38716919189;40958.59041409548;41186;28713.85643071771;29009.56485652759;29189.562156568092;29505.557416639196;29687.406251875273 16:13:58 INFO - PROCESS | 29856 | |2;jquery.html;394.9407588861672;430.8576118178088;433.20290665176077;434.50249464363304;447.8790726503846;16897.746533801987;19895;20759.688604670904;21490.892545537252;21536.246231381905;19741.407757767254;20451.181952721912;20571.177152913904;20673.483162920933;20778.584428311424;5914.2015827863115;5992.34084250732;6285.182926219585;6288.025356069817;6297.244330680325;5991.910121348162;6047.183630209909;6071.848203794896;6082.118092876531;6092.786752463665;5700.600957932947;5921.141434491996;6070.2412198475195;6071.817845464621;6072.02847544393;6108.511319094484;6136.631802091889;6139.2325959255095;6150.200473938382;6155.045967874987;5889.734961926723;5905.911411328833;5958.225430694026;5979.133025711248;6002.51979841616;14649.70700585993;14899.851001489955;17270.654586908193;24373.390665233335;24640.87679561609;2462.4582591829767;2613.516499447608;2646.7882569394596;2902.593636890816;2996.8801247950055;10648.307859989076;10957.56169753209;11180.552777888875;11211.103111751041;11288.774224515546;1997.530580313625;2003.8697484663596;2043.7751847296786;2051.507638166844;2076.781937896529;86251;86267;90114;90503;95066;10408.323458975143;11298.7175320616;11458.255213411101;11586.420678966018;11907.166498345032;15012.699746005019;15113;15946.362145514282;15993.840061599236;16636.417725379553;8820.955895220482;8858.202761751474;8859.689910853089;9029;9100.590473428712;8793.91206087931;8861.158189971942;8912.68805591814;9019.458832470073;9223.216026637676;4857.975710121497;4983.152864013054;5004.124278251292;5012.548870601663;5070.163423035158;1936.2835750772024;1997.4159304396444;2061.4228016155503;2061.4537147655883;2085.9478513037297;919.6827094652334;973.0804389851663;973.6154219083564;1022.8158931392421;1026.4200726589454;20931.581368372546;21009.26467573658;21039.762646668532;21183.364499065054;21427.89286053591;1012.4583347908833;1038.678009816959;1074.2426589254712;1105.5964572930823;1128.995194277094;11012.614558490415;11646.359450230319;11811.114166437552;11825.645230643095;11897.405129743478;512622;515510;527880;547496;551966;96750;97194;98262.50868745754;98828.50585747026;104221;1067.4395942130443;1076.7039064257392;1106.651404807483;1112.1380929779486;1116.3859877067744;18862.622747545247;19082.904585476983;19135.712964305825;19187;19300.32448864283;31126.844365778932;41021;42355.7882210587;42409.787951061284;45090.97090630995;62139.37860621336;62365.68817155885;62522.37477625348;62915.37084629095;64200.357996419436;9093.363464557417;9534.61861525559;9561.90438095638;9647.89049259316;9707.70876873695;12422.813657794959;13759.587212383645;13944.744972952483;14091.859081409464;14110.365033573718;12186;12195.390230488618;12438.564650237198;12750.489980401051;12836.871631283568;11883.049356051322;12209.511619535475;12352.135350525377;12572.434240459019;12797;12575.87424125747;12582.748345033231;12633.557825476064;12857.485700571873;13203.801942970671;7736.883946740916;8448.450850694686;8562.186592273723;8694.565271736514;8978.236849867835;11660.41697915084;11675.824862626898;11810.350430726048;11906.702332441759;12285.631431057083 16:13:58 INFO - PROCESS | 29856 | |3;mootools.html;1520.1259275916348;1530.15841287292;1648.571371443425;1794.9730754038683;1820.7724034495689;9015.504147271906;9719.882213545445;9725.610975560987;9728.70813875584;9735.221182305417;8416;8677.045524992262;9095.272378209744;9365.391249568791;9390.530473476316;2439.1219161102035;2463.0763463701114;2473.468204336067;2477.8017758579363;2484;2427.9028838846425;2486.2292689266296;2493.227099599121;2494.5759220932487;2497.0760818497183;2429.671994280776;2440.2923152285816;2473.7402572729875;2475.665785119013;2475.851448913062;2407.9157229496973;2419.600765873629;2432.1122790181635;2462.839915405511;2462.8645424501647;2405.0980882169188;2420.213430635051;2425.0905910283645;2426.7451917548756;2428.0530593068715;9448.858267126;9533.094356036165;9556.952215238949;9605.135537801561;9614;5324.733763311819;5394.352677678665;5427.62006659534;5445.673259604437;5449.4005659377435;3469.6877281044576;3551.449525323553;3631.2737452509605;3752.0807402186365;3769.905752356213;1325.9326242374884;1384.7715127004033;1385.7090011097682;1403.964900877476;1416.178616402484;12770.680732981657;12906.677333066655;12973.870261297361;12976.740465190738;12998.090133690646;4778.880527986828;4797.2564252540915;4817.470078291385;4856.490068542823;4958.876028099255;10888.782224355627;10891.074258687942;10895.128389728803;10900.945495272474;10931.726706832234;5490.3411590609385;5512.91730624041;5519.586031047687;5520.06158952971;5525.309336332958;4573.108243892409;4735.976320118378;4738.691985020962;5028.798848046037;5074.213496907985;4406.2949928012;4406.669499787528;4416;4485.798139083749;4526.524714904954;1372.8558501357213;1392.470861072786;1398.0563119894032;1398.7412328719015;1432.8495507971722;949.4493193947494;1055.382601178304;1070.652038087609;1073.592035026685;1078.196743426137;6360.618362898241;6386.690728400667;6434.388733070351;6626.602403855784;6639;490.5094905094905;501.7315736081252;504.41487874065905;508.42547920849205;509.3683832048234;4466.665000125004;4496.348029535699;4546.931796023062;4586.197415452282;4680.321353403738;11448.313101213955;11962;11977.341246231541;11984.161108722481;11990.460429280529;11480.138989575647;11564.13269004828;11723.824142637868;11760.294382336917;11762.176647634755;8747.08155643661;8810;8822.2501087407;8886.289096872364;8892.466452012899;6404.455621272244;6514.218293804584;6533.803985880431;6614.239362473185;6675.666216689243;10777.191710621579;10787.946060269649;10946.72633184145;10968.945155274172;11021.944890275498;10728.946355268174;10833.29583577065;10978.451077446256;11002.284851484628;11051.005409513182;7514.098308202832;7703.884441733267;7734.961325193338;8509.957450212709;8544.700935467228;8675.522846243517;8744.300455963636;8835.381523293308;9082.954585227297;9132.269418446642;8123.7156699515235;8159.918400815915;8182.877256841271;8312.501249925024;8321.500709957421;8151.103378628228;8268.214519620626;8283.25450709439;8300.83398332042;8349.499030058216;7608.581528016012;7612.8477430449975;7628.313451789366;7674.884876726966;7708.306252437307;5387.461253874581;5404.513593776579;5463.934532766231;5512.476314750093;5521.089020311764;5810.5061069809535;5853.326867410302;5889.852753681193;5909.084091965752;5926.4666180044005 16:13:58 INFO - PROCESS | 29856 | |4;prototype.html;335.4326396495071;336.36595018390335;341.85471174750734;352.7706990456203;356.5558099229778;5291.28567643368;5434.83695489136;5507.889842203153;5542.889142217154;5564.527015203707;5288.233206185101;5394.703291318986;5443.156310771837;5462.7268636568115;5489.231507588931;4364.279893817532;4373.343998400233;4374.781260936957;4376.49670287917;4379.693421460499;4221.219074471217;4329.696921215516;4370.868873933771;4377.759223242736;4384.618845063811;4298.419713338706;4319.3952846601505;4332.566743325674;4335.371371151197;4336.566343365654;4294.871153865389;4302.849400270976;4305.870823875289;4319.244132276839;4350.978245108786;3591.3894637911617;3737.402015677506;3745.756525825813;3747.9812600937094;3777.58446570877;5308.309919710451;5316.627836051478;5322.281491998569;5330.333708286465;5340.732963351817;4886.633502487328;4898.93816364772;4904.4605093439695;4909.263610458424;4942.752862356868;5529.281193444826;5700.201971723962;5711.7715291388295;5727.599068065237;5728.169415434781;4105.712600117993;4119.487112861152;4125.814338354781;4126.081372161929;4129.442525259112;5798.651011741236;5986.191864098335;6000.33996260411;6002.939970600281;6043.879122417571;4161.2301724181125;4175.958240417618;4187.120704651997;4192.098698779777;4197.811098500575;4218.346156345771;4226.5139508956245;4234.830606775756;4260.4248426462655;4287.206866729603;4911.803527858846;4925.113479573711;4929.260610908321;4939.753012349368;4940.505949405031;5464.699441530674;5676.864627074601;5796.420357964254;5817.127430885316;5845.824625261249;1399.1605036977733;1425.6934759026858;1460.6567456647679;1462.8098347214793;1469.1038466535406;954.6801821389773;975.9072888075764;983.3853841349156;992.3004281981185;993.3295025857517;5455.427180146027;5484.61607687458;5505;5509.559235261169;5544.63959842609;5832.154337621021;5841.795537156179;5850.239468869105;5876.265466816648;5892.734826932712;5386.703731294817;5597.80407685729;5608.355039170466;5643.5767317451355;5675.290588676416;3140.0893740815363;3140.387624413263;3140.780145389801;3154.242981684379;3158.6417840328418;5858.5898987070495;6096.237970253718;6111.236095488064;6143.938560614337;6161.537884658668;6086.087086936906;6125.418085281891;6129.478994285536;6130.63216207029;6135.447809697149;6162.890679677701;6171.907421388682;6176.938230617726;6201.162843016135;6224.626522408579;5684.857878553069;5932.614380065282;5952.571382867994;5970.731317090655;5971.611845230046;5890.381509941394;5893.88016276906;5896.557758168069;5914.260717410324;5915.142304365929;5844.620099693507;6115.908261375994;6135.8466038347615;6141.969290153521;6148.938510614837;4276.358546218092;4305.763183024964;4308.741475511354;4308.784560771886;4308.935365969575;5723.399043100415;5909.113632955091;5970.164177015135;5991.460768530853;6030.698465076816;5738.225339579103;5789.53683705311;5803.506701930214;5804.6807425592;5817.894600025981;5792.13289421739;5852.590318677822;5854.677992710442;5862.208601838697;5910.876933382644;5675.914861277167;5775.884482310415;5804.6201057566195;5807.448292412214;5813.09896965971;5854.824355269348;5857.150713146485;5875.970620146872;5885.735141918538;5896.17453556511;5608.9158662619275;5673.77304907799;5744.339400968942;5752.223449834386;5756.30924289088 16:13:58 INFO - PROCESS | 29856 | |5;yui.html;815.1074573342192;827.3463963468864;858.128999065948;867.3364875869956;913.7715571107224;16378.590535236624;16528.504144875642;16534.917325413386;16559.172041397913;17010.574735631617;14897.10617362959;15034.248287585631;15071.246437678126;15363.385464581403;16033.599160020976;714.2393351081108;734.6253410760497;734.6290123487631;736.6095969136367;842.5450256861287;844.6748002019236;859.5186695450568;860.0152825015385;860.2988564320091;860.4149178558578;916.1754421021101;916.6058594804232;917.2478567574558;917.5595714057257;917.5824999625155;756.6405957170327;771.5563550958221;872.0930232558131;876.7851876290285;916.7341470973442;650.443870490733;651.0559688451716;651.1957732200729;651.2965996723527;651.316118076023;16509.58726031847;16519.504414867573;16549.25528351215;16562.83437165625;16575.336986520524;15158.09051456905;15326.693466130615;15368;15383.230838458145;15467;14320.498782542561;14522.564323070324;14610.926945365312;14678.926605366905;14740;4822.252550854623;4846.442659094214;4891.8777030574165;4946.3817022872145;4976.477469865685;91285;91873.08126918713;91906;92574.53712731393;96313;11874.643760687193;12205.511779528719;12287;12289.201201921847;12381.876181238253;21931.12275508994;21932.561348773255;22153.1138754448;22180;22190;9159.771005724777;9222.723318300328;9312.813743725088;9644.228461723185;9676.661316854017;4908.361912951294;5013;5098.439171691111;5118.846434606968;5256.027634887558;3750.099976005739;3895.415687646819;3900.9219815604097;3901.2392583445953;3902.941455878164;659.0937460991137;669.8794217040881;677.9457643388517;684.0662495693442;691.3432239372617;767.286423626022;777.9066512018594;779.2635959018786;789.7433334166419;791.1179035375515;10181.847272290921;10540.789184216426;10587.470626468647;10619.628313009007;10634.20243481742;683.8632273545311;722.5267449820376;733.3693023999463;739.548875186136;781.2460975158999;4917.188663870493;4959.925601115986;4967.595094884181;4998.600391890276;5025.748712564357;71789.2821071793;72248.2775172252;72583;72999;115635;109986;112066.43966780114;113531;113977.16137031805;116740;57930.8413831721;60684.696576517716;85768;91658.54170729103;97494.51252743833;10021.448820314952;10330.328528645614;10457.9477102614;10800.837987430037;11330.94334528322;52735.736321318145;53443.465565343846;54993.1751023743;55089.72455137859;55263.44736552744;78089.2191078082;78549;79528.20471795207;80696.19303806887;80721.59639201766;13276.734465310446;13531.188128712309;13562;14076.648083797987;14399.568012959628;15193.392264309305;15294.77057844111;15378.231088445757;16057.116858572423;16176.433824816075;14521.27393630293;14550.781738273723;14563.563093107225;14698.11811291326;14905.254737262876;12991;13083.672908177372;13145;13812.86187138156;14066.296685165906;13446.731065378834;13468.259245741187;13727.107737996997;14390.856091439371;14648.340824662702;11650;11682.357470338873;11947.641570752892;12111.878881211074;12231.87768122343;11128.944355278172;11874.881251187378;11923.224990375598;12826.74346513046;12847.550335738204 16:13:58 INFO - PROCESS | 29856 | __end_tp_report 16:13:58 INFO - PROCESS | 29856 | __start_cc_report 16:13:58 INFO - PROCESS | 29856 | _x_x_mozilla_cycle_collect,871 16:13:58 INFO - PROCESS | 29856 | __end_cc_report 16:13:58 INFO - PROCESS | 29856 | __startTimestamp1475536438330__endTimestamp 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | ------- Summary: start ------- 16:13:58 INFO - PROCESS | 29856 | Number of tests: 6 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | [#0] dojo.html Cycles:165 Average:5601.40 Median:5834.42 stddev:652.48 (11.2%) stddev-sans-first:623.86 16:13:58 INFO - PROCESS | 29856 | Values: 3083.4 3124.1 3143.1 3163.1 3172.1 5587.1 5665.6 5679.3 5702.5 5730.7 5679.7 5768.9 5772.4 5785.0 5815.6 5179.2 5194.9 5208.5 5253.2 5282.7 5131.5 5281.7 5299.1 5301.5 5328.9 5082.1 5098.6 5099.8 5121.3 5138.1 5097.4 5117.7 5139.0 5181.2 5189.8 5048.8 5092.8 5153.4 5168.2 5190.6 5456.9 5474.3 5489.6 5547.8 5624.4 5879.0 5887.6 5900.6 5998.3 5998.9 6061.3 6065.1 6071.0 6071.8 6086.5 5537.6 5574.5 5592.0 5653.7 5661.7 6242.5 6242.6 6276.0 6281.8 6282.1 6001.2 6013.5 6016.4 6016.4 6058.6 5878.6 5886.5 5900.5 5909.7 5924.7 5957.3 5973.1 5973.5 5977.9 6005.5 5914.5 5983.5 5993.9 5995.3 6029.5 4508.7 4535.9 4547.4 4601.4 4619.9 4085.3 4110.1 4117.1 4126.4 4149.3 5687.5 5722.4 5724.4 5725.7 5734.4 5888.1 5926.3 5944.9 6034.8 6055.7 5588.2 5711.1 5723.3 5733.9 5745.5 5072.9 5077.3 5084.0 5084.2 5084.5 6070.3 6140.8 6179.7 6249.4 6265.5 5984.1 5985.0 6009.0 6115.4 6120.1 6296.6 6297.5 6308.7 6311.0 6322.6 5681.3 5993.5 6002.3 6012.1 6013.7 5699.8 5958.7 5971.3 5979.5 6110.7 5922.5 5977.9 5979.8 5983.0 6009.6 5791.1 5986.6 5992.4 6003.2 6008.6 5721.9 5809.6 5834.4 5873.2 5989.2 5992.8 6004.2 6016.6 6019.4 6020.6 5608.3 5664.8 5850.9 5855.3 5866.3 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | [#1] ext.html Cycles:180 Average:63717.75 Median:41720.27 stddev:123969.78 (297.1%) stddev-sans-first:124226.96 16:13:58 INFO - PROCESS | 29856 | Values: 596.6 629.3 646.2 649.5 668.9 34598.5 35239.8 38173.0 46818.5 47853.3 47971.0 48328.0 48360.3 48374.5 48374.8 9405.7 9486.4 9503.4 9523.6 9559.4 8471.6 8678.1 11001.8 11248.8 11261.4 11540.4 11630.9 11634.7 11635.5 11648.1 8619.3 11319.8 11397.1 11401.3 11434.9 8398.5 10736.5 11082.5 11093.2 11473.8 45243.8 45614.0 45647.3 45653.8 45721.0 3888.8 4014.6 4236.0 4268.2 4549.4 35052.1 35233.1 35688.5 36079.5 36669.1 4656.8 4708.2 4812.7 4854.8 4857.2 366508.0 366798.0 367732.0 368190.0 369290.0 39016.4 66452.0 66775.3 66820.3 66865.0 39049.6 41563.4 41651.8 41707.4 41733.2 17604.2 17646.1 17788.6 17795.8 17798.3 13119.1 13445.5 14799.3 14859.5 14912.4 9294.0 9333.2 9646.2 9655.3 9757.3 3607.9 3640.8 3662.2 3724.0 3755.8 1038.2 1057.6 1083.5 1134.3 1151.8 32787.8 35198.8 35228.6 35335.4 35621.3 1146.9 1162.1 1193.4 1274.0 1315.8 18328.2 18469.3 18497.4 18538.5 18610.8 542197.0 544035.0 545744.0 545992.0 550998.0 65399.0 65806.7 66020.0 66049.3 66071.7 1309.5 1370.3 1428.0 1434.9 1474.2 31166.2 35646.3 36276.5 36377.5 37152.4 58350.1 58794.7 59098.0 59290.0 59357.0 468309.0 474409.0 474606.0 475379.0 475904.0 46500.0 47355.8 48213.8 51370.5 51564.5 52698.5 52894.7 52907.2 53085.0 53257.2 43990.6 44121.6 44838.0 45911.5 49073.0 46581.6 47150.5 47266.5 47273.3 47916.5 43737.0 44514.8 44630.6 45649.8 47364.0 38574.8 40114.0 40855.4 40958.6 41186.0 28713.9 29009.6 29189.6 29505.6 29687.4 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | [#2] jquery.html Cycles:180 Average:31038.80 Median:22954.82 stddev:87662.98 (381.9%) stddev-sans-first:87878.70 16:13:58 INFO - PROCESS | 29856 | Values: 394.9 430.9 433.2 434.5 447.9 16897.7 19895.0 20759.7 21490.9 21536.2 19741.4 20451.2 20571.2 20673.5 20778.6 5914.2 5992.3 6285.2 6288.0 6297.2 5991.9 6047.2 6071.8 6082.1 6092.8 5700.6 5921.1 6070.2 6071.8 6072.0 6108.5 6136.6 6139.2 6150.2 6155.0 5889.7 5905.9 5958.2 5979.1 6002.5 14649.7 14899.9 17270.7 24373.4 24640.9 2462.5 2613.5 2646.8 2902.6 2996.9 10648.3 10957.6 11180.6 11211.1 11288.8 1997.5 2003.9 2043.8 2051.5 2076.8 86251.0 86267.0 90114.0 90503.0 95066.0 10408.3 11298.7 11458.3 11586.4 11907.2 15012.7 15113.0 15946.4 15993.8 16636.4 8821.0 8858.2 8859.7 9029.0 9100.6 8793.9 8861.2 8912.7 9019.5 9223.2 4858.0 4983.2 5004.1 5012.5 5070.2 1936.3 1997.4 2061.4 2061.5 2085.9 919.7 973.1 973.6 1022.8 1026.4 20931.6 21009.3 21039.8 21183.4 21427.9 1012.5 1038.7 1074.2 1105.6 1129.0 11012.6 11646.4 11811.1 11825.6 11897.4 512622.0 515510.0 527880.0 547496.0 551966.0 96750.0 97194.0 98262.5 98828.5 104221.0 1067.4 1076.7 1106.7 1112.1 1116.4 18862.6 19082.9 19135.7 19187.0 19300.3 31126.8 41021.0 42355.8 42409.8 45091.0 62139.4 62365.7 62522.4 62915.4 64200.4 9093.4 9534.6 9561.9 9647.9 9707.7 12422.8 13759.6 13944.7 14091.9 14110.4 12186.0 12195.4 12438.6 12750.5 12836.9 11883.0 12209.5 12352.1 12572.4 12797.0 12575.9 12582.7 12633.6 12857.5 13203.8 7736.9 8448.5 8562.2 8694.6 8978.2 11660.4 11675.8 11810.4 11906.7 12285.6 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | [#3] mootools.html Cycles:180 Average:6195.30 Median:4737.33 stddev:3532.75 (74.6%) stddev-sans-first:3525.19 16:13:58 INFO - PROCESS | 29856 | Values: 1520.1 1530.2 1648.6 1795.0 1820.8 9015.5 9719.9 9725.6 9728.7 9735.2 8416.0 8677.0 9095.3 9365.4 9390.5 2439.1 2463.1 2473.5 2477.8 2484.0 2427.9 2486.2 2493.2 2494.6 2497.1 2429.7 2440.3 2473.7 2475.7 2475.9 2407.9 2419.6 2432.1 2462.8 2462.9 2405.1 2420.2 2425.1 2426.7 2428.1 9448.9 9533.1 9557.0 9605.1 9614.0 5324.7 5394.4 5427.6 5445.7 5449.4 3469.7 3551.4 3631.3 3752.1 3769.9 1325.9 1384.8 1385.7 1404.0 1416.2 12770.7 12906.7 12973.9 12976.7 12998.1 4778.9 4797.3 4817.5 4856.5 4958.9 10888.8 10891.1 10895.1 10900.9 10931.7 5490.3 5512.9 5519.6 5520.1 5525.3 4573.1 4736.0 4738.7 5028.8 5074.2 4406.3 4406.7 4416.0 4485.8 4526.5 1372.9 1392.5 1398.1 1398.7 1432.8 949.4 1055.4 1070.7 1073.6 1078.2 6360.6 6386.7 6434.4 6626.6 6639.0 490.5 501.7 504.4 508.4 509.4 4466.7 4496.3 4546.9 4586.2 4680.3 11448.3 11962.0 11977.3 11984.2 11990.5 11480.1 11564.1 11723.8 11760.3 11762.2 8747.1 8810.0 8822.3 8886.3 8892.5 6404.5 6514.2 6533.8 6614.2 6675.7 10777.2 10787.9 10946.7 10968.9 11021.9 10728.9 10833.3 10978.5 11002.3 11051.0 7514.1 7703.9 7735.0 8510.0 8544.7 8675.5 8744.3 8835.4 9083.0 9132.3 8123.7 8159.9 8182.9 8312.5 8321.5 8151.1 8268.2 8283.3 8300.8 8349.5 7608.6 7612.8 7628.3 7674.9 7708.3 5387.5 5404.5 5463.9 5512.5 5521.1 5810.5 5853.3 5889.9 5909.1 5926.5 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | [#4] prototype.html Cycles:180 Average:4871.67 Median:5543.76 stddev:1435.62 (25.9%) stddev-sans-first:1398.69 16:13:58 INFO - PROCESS | 29856 | Values: 335.4 336.4 341.9 352.8 356.6 5291.3 5434.8 5507.9 5542.9 5564.5 5288.2 5394.7 5443.2 5462.7 5489.2 4364.3 4373.3 4374.8 4376.5 4379.7 4221.2 4329.7 4370.9 4377.8 4384.6 4298.4 4319.4 4332.6 4335.4 4336.6 4294.9 4302.8 4305.9 4319.2 4351.0 3591.4 3737.4 3745.8 3748.0 3777.6 5308.3 5316.6 5322.3 5330.3 5340.7 4886.6 4898.9 4904.5 4909.3 4942.8 5529.3 5700.2 5711.8 5727.6 5728.2 4105.7 4119.5 4125.8 4126.1 4129.4 5798.7 5986.2 6000.3 6002.9 6043.9 4161.2 4176.0 4187.1 4192.1 4197.8 4218.3 4226.5 4234.8 4260.4 4287.2 4911.8 4925.1 4929.3 4939.8 4940.5 5464.7 5676.9 5796.4 5817.1 5845.8 1399.2 1425.7 1460.7 1462.8 1469.1 954.7 975.9 983.4 992.3 993.3 5455.4 5484.6 5505.0 5509.6 5544.6 5832.2 5841.8 5850.2 5876.3 5892.7 5386.7 5597.8 5608.4 5643.6 5675.3 3140.1 3140.4 3140.8 3154.2 3158.6 5858.6 6096.2 6111.2 6143.9 6161.5 6086.1 6125.4 6129.5 6130.6 6135.4 6162.9 6171.9 6176.9 6201.2 6224.6 5684.9 5932.6 5952.6 5970.7 5971.6 5890.4 5893.9 5896.6 5914.3 5915.1 5844.6 6115.9 6135.8 6142.0 6148.9 4276.4 4305.8 4308.7 4308.8 4308.9 5723.4 5909.1 5970.2 5991.5 6030.7 5738.2 5789.5 5803.5 5804.7 5817.9 5792.1 5852.6 5854.7 5862.2 5910.9 5675.9 5775.9 5804.6 5807.4 5813.1 5854.8 5857.2 5876.0 5885.7 5896.2 5608.9 5673.8 5744.3 5752.2 5756.3 16:13:58 INFO - PROCESS | 29856 | 16:13:58 INFO - PROCESS | 29856 | [#5] yui.html Cycles:180 Average:21224.46 Median:4362.60 stddev:29624.42 (679.1%) stddev-sans-first:29667.89 16:13:58 INFO - PROCESS | 29856 | Values: 815.1 827.3 858.1 867.3 913.8 16378.6 16528.5 16534.9 16559.2 17010.6 14897.1 15034.2 15071.2 15363.4 16033.6 714.2 734.6 734.6 736.6 842.5 844.7 859.5 860.0 860.3 860.4 916.2 916.6 917.2 917.6 917.6 756.6 771.6 872.1 876.8 916.7 650.4 651.1 651.2 651.3 651.3 16509.6 16519.5 16549.3 16562.8 16575.3 15158.1 15326.7 15368.0 15383.2 15467.0 14320.5 14522.6 14610.9 14678.9 14740.0 4822.3 4846.4 4891.9 4946.4 4976.5 91285.0 91873.1 91906.0 92574.5 96313.0 11874.6 12205.5 12287.0 12289.2 12381.9 21931.1 21932.6 22153.1 22180.0 22190.0 9159.8 9222.7 9312.8 9644.2 9676.7 4908.4 5013.0 5098.4 5118.8 5256.0 3750.1 3895.4 3900.9 3901.2 3902.9 659.1 669.9 677.9 684.1 691.3 767.3 777.9 779.3 789.7 791.1 10181.8 10540.8 10587.5 10619.6 10634.2 683.9 722.5 733.4 739.5 781.2 4917.2 4959.9 4967.6 4998.6 5025.7 71789.3 72248.3 72583.0 72999.0 115635.0 109986.0 112066.4 113531.0 113977.2 116740.0 57930.8 60684.7 85768.0 91658.5 97494.5 10021.4 10330.3 10457.9 10800.8 11330.9 52735.7 53443.5 54993.2 55089.7 55263.4 78089.2 78549.0 79528.2 80696.2 80721.6 13276.7 13531.2 13562.0 14076.6 14399.6 15193.4 15294.8 15378.2 16057.1 16176.4 14521.3 14550.8 14563.6 14698.1 14905.3 12991.0 13083.7 13145.0 13812.9 14066.3 13446.7 13468.3 13727.1 14390.9 14648.3 11650.0 11682.4 11947.6 12111.9 12231.9 11128.9 11874.9 11923.2 12826.7 12847.6 16:13:58 INFO - PROCESS | 29856 | -------- Summary: end -------- 16:13:58 INFO - PROCESS | 29856 | 16:13:59 INFO - TEST-INFO | 29856: exit 0 16:13:59 INFO - TEST-OK | dromaeo_css | took 1114462ms 16:13:59 INFO - TEST-START | kraken 16:13:59 INFO - operating with platform_type : linux_ 16:13:59 INFO - Initialising browser for kraken test... 16:13:59 INFO - TEST-INFO | started process 30173 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZGpz83/profile http://localhost:45279/getInfo.html) 16:14:03 INFO - PROCESS | 30173 | __metrics Screen width/height:1600/1200 16:14:03 INFO - PROCESS | 30173 | colorDepth:24 16:14:03 INFO - PROCESS | 30173 | Browser inner width/height: 1024/697 16:14:03 INFO - PROCESS | 30173 | __metrics 16:14:04 INFO - PROCESS | 30173 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:14:04 INFO - PROCESS | 30173 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:14:04 INFO - PROCESS | 30173 | console.error: PushService: 16:14:04 INFO - PROCESS | 30173 | stateChangeProcessEnqueue: Error transitioning state 16:14:04 INFO - PROCESS | 30173 | UnknownError 16:14:05 INFO - TEST-INFO | 30173: exit 0 16:14:05 INFO - Browser initialized. 16:14:05 INFO - Running cycle 1/1 for kraken test... 16:14:05 INFO - TEST-INFO | started process 30267 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZGpz83/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:16 INFO - PROCESS | 30267 | RSS: Main: 147394560 16:14:16 INFO - PROCESS | 30267 | 16:14:19 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45279/tests/kraken/driver.html?testName=audio-beat-detection) 16:14:20 INFO - PROCESS | 30267 | RSS: Main: 188616704 16:14:20 INFO - PROCESS | 30267 | 16:14:22 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45279/tests/kraken/driver.html?testName=audio-dft) 16:14:23 INFO - PROCESS | 30267 | RSS: Main: 178167808 16:14:23 INFO - PROCESS | 30267 | 16:14:26 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45279/tests/kraken/driver.html?testName=audio-fft) 16:14:26 INFO - PROCESS | 30267 | RSS: Main: 269676544 16:14:26 INFO - PROCESS | 30267 | 16:14:28 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45279/tests/kraken/driver.html?testName=audio-oscillator) 16:14:29 INFO - PROCESS | 30267 | RSS: Main: 176267264 16:14:29 INFO - PROCESS | 30267 | 16:14:31 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:14:32 INFO - PROCESS | 30267 | RSS: Main: 177991680 16:14:32 INFO - PROCESS | 30267 | 16:14:35 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45279/tests/kraken/driver.html?testName=imaging-darkroom) 16:14:35 INFO - PROCESS | 30267 | RSS: Main: 213692416 16:14:35 INFO - PROCESS | 30267 | 16:14:39 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45279/tests/kraken/driver.html?testName=imaging-desaturate) 16:14:39 INFO - PROCESS | 30267 | RSS: Main: 212951040 16:14:39 INFO - PROCESS | 30267 | 16:14:42 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45279/tests/kraken/driver.html?testName=json-parse-financial) 16:14:42 INFO - PROCESS | 30267 | RSS: Main: 212119552 16:14:42 INFO - PROCESS | 30267 | 16:14:44 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:14:45 INFO - PROCESS | 30267 | RSS: Main: 174219264 16:14:45 INFO - PROCESS | 30267 | 16:14:46 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:14:47 INFO - PROCESS | 30267 | RSS: Main: 181936128 16:14:47 INFO - PROCESS | 30267 | 16:14:49 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:14:50 INFO - PROCESS | 30267 | RSS: Main: 183676928 16:14:50 INFO - PROCESS | 30267 | 16:14:52 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:14:53 INFO - PROCESS | 30267 | RSS: Main: 178171904 16:14:53 INFO - PROCESS | 30267 | 16:14:55 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:14:56 INFO - PROCESS | 30267 | RSS: Main: 182087680 16:14:56 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | Cycle 1(1): loaded http://localhost:45279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45279/tests/kraken/driver.html?testName=ai-astar) 16:14:58 INFO - PROCESS | 30267 | RSS: Main: 233496576 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | __start_tp_report 16:14:58 INFO - PROCESS | 30267 | _x_x_mozilla_page_load 16:14:58 INFO - PROCESS | 30267 | _x_x_mozilla_page_load_details 16:14:58 INFO - PROCESS | 30267 | |i|pagename|runs| 16:14:58 INFO - PROCESS | 30267 | |0;ai-astar;110;111;111;109;110;111;109;111;111;110 16:14:58 INFO - PROCESS | 30267 | |1;audio-beat-detection;146;116;116;161;118;116;117;117;142;119 16:14:58 INFO - PROCESS | 30267 | |2;audio-dft;161;160;164;161;162;160;161;159;162;163 16:14:58 INFO - PROCESS | 30267 | |3;audio-fft;75;78;77;113;77;77;77;75;98;75 16:14:58 INFO - PROCESS | 30267 | |4;audio-oscillator;81;82;116;82;83;82;99;81;82;112 16:14:58 INFO - PROCESS | 30267 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;117;116;116 16:14:58 INFO - PROCESS | 30267 | |6;imaging-darkroom;146;145;146;145;145;145;145;144;145;145 16:14:58 INFO - PROCESS | 30267 | |7;imaging-desaturate;95;94;96;95;96;95;95;95;95;94 16:14:58 INFO - PROCESS | 30267 | |8;json-parse-financial;55;49;48;50;50;49;51;49;49;50 16:14:58 INFO - PROCESS | 30267 | |9;json-stringify-tinderbox;58;58;57;58;58;57;58;57;57;58 16:14:58 INFO - PROCESS | 30267 | |10;stanford-crypto-aes;74;72;74;74;73;83;74;78;73;73 16:14:58 INFO - PROCESS | 30267 | |11;stanford-crypto-ccm;117;120;118;117;116;117;123;119;198;132 16:14:58 INFO - PROCESS | 30267 | |12;stanford-crypto-pbkdf2;158;157;159;158;156;158;157;159;155;158 16:14:58 INFO - PROCESS | 30267 | |13;stanford-crypto-sha256-iterative;63;64;63;62;63;62;64;66;62;62 16:14:58 INFO - PROCESS | 30267 | __end_tp_report 16:14:58 INFO - PROCESS | 30267 | __start_cc_report 16:14:58 INFO - PROCESS | 30267 | _x_x_mozilla_cycle_collect,1565 16:14:58 INFO - PROCESS | 30267 | __end_cc_report 16:14:58 INFO - PROCESS | 30267 | __startTimestamp1475536498296__endTimestamp 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | ------- Summary: start ------- 16:14:58 INFO - PROCESS | 30267 | Number of tests: 14 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 16:14:58 INFO - PROCESS | 30267 | Values: 110.0 111.0 111.0 109.0 110.0 111.0 109.0 111.0 111.0 110.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#1] audio-beat-detection Cycles:10 Average:126.80 Median:118.50 stddev:16.50 (13.9%) stddev-sans-first:15.97 16:14:58 INFO - PROCESS | 30267 | Values: 146.0 116.0 116.0 161.0 118.0 116.0 117.0 117.0 142.0 119.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.49 (0.9%) stddev-sans-first:1.58 16:14:58 INFO - PROCESS | 30267 | Values: 161.0 160.0 164.0 161.0 162.0 160.0 161.0 159.0 162.0 163.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#3] audio-fft Cycles:10 Average:82.20 Median:77.00 stddev:12.82 (16.7%) stddev-sans-first:13.33 16:14:58 INFO - PROCESS | 30267 | Values: 75.0 78.0 77.0 113.0 77.0 77.0 77.0 75.0 98.0 75.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:82.00 stddev:13.78 (16.8%) stddev-sans-first:14.22 16:14:58 INFO - PROCESS | 30267 | Values: 81.0 82.0 116.0 82.0 83.0 82.0 99.0 81.0 82.0 112.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:14:58 INFO - PROCESS | 30267 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 116.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 16:14:58 INFO - PROCESS | 30267 | Values: 146.0 145.0 146.0 145.0 145.0 145.0 145.0 144.0 145.0 145.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 16:14:58 INFO - PROCESS | 30267 | Values: 95.0 94.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:1.94 (3.9%) stddev-sans-first:0.88 16:14:58 INFO - PROCESS | 30267 | Values: 55.0 49.0 48.0 50.0 50.0 49.0 51.0 49.0 49.0 50.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:58.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 16:14:58 INFO - PROCESS | 30267 | Values: 58.0 58.0 57.0 58.0 58.0 57.0 58.0 57.0 57.0 58.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:74.00 stddev:3.29 (4.5%) stddev-sans-first:3.48 16:14:58 INFO - PROCESS | 30267 | Values: 74.0 72.0 74.0 74.0 73.0 83.0 74.0 78.0 73.0 73.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:119.50 stddev:25.15 (21.0%) stddev-sans-first:26.37 16:14:58 INFO - PROCESS | 30267 | Values: 117.0 120.0 118.0 117.0 116.0 117.0 123.0 119.0 198.0 132.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:158.00 stddev:1.27 (0.8%) stddev-sans-first:1.33 16:14:58 INFO - PROCESS | 30267 | Values: 158.0 157.0 159.0 158.0 156.0 158.0 157.0 159.0 155.0 158.0 16:14:58 INFO - PROCESS | 30267 | 16:14:58 INFO - PROCESS | 30267 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.29 (2.0%) stddev-sans-first:1.36 16:14:58 INFO - PROCESS | 30267 | Values: 63.0 64.0 63.0 62.0 63.0 62.0 64.0 66.0 62.0 62.0 16:14:58 INFO - PROCESS | 30267 | -------- Summary: end -------- 16:14:58 INFO - PROCESS | 30267 | 16:14:59 INFO - TEST-INFO | 30267: exit 0 16:14:59 INFO - TEST-OK | kraken | took 60381ms 16:14:59 INFO - SUITE-END | took 1174s 16:14:59 INFO - Completed test suite (00:19:35) 16:14:59 INFO - javascript benchmark 16:15:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3083.414151311251, 3124.0627811656495, 3143.0570828751356, 3163.114327988164, 3172.080096771936, 5587.05020146575, 5665.575081868861, 5679.318481782192, 5702.45826646468, 5730.65616063036, 5679.687617181054, 5768.913466297999, 5772.393898640646, 5785.0, 5815.621984571, 5179.19722443022, 5194.948050519503, 5208.47915208478, 5253.212018197282, 5282.7358632068435, 5131.486851314858, 5281.735913204344, 5299.099153143956, 5301.496357845998, 5328.893422131555, 5082.0598189334905, 5098.617603679721, 5099.796008159669, 5121.257417674435, 5138.075146473633, 5097.413797413308, 5117.744112794364, 5139.049275883974, 5181.171012637959, 5189.792408303663, 5048.848534543933, 5092.770825312869, 5153.3558305211845, 5168.198929165986, 5190.558802501754, 5456.863578410532, 5474.343078830566, 5489.615726899118, 5547.750351234205, 5624.409437009096, 5878.970605146989, 5887.61730487517, 5900.64596124234, 5998.340182579913, 5998.940010599882, 6061.3029501607, 6065.120557519135, 6070.96964515179, 6071.817845464599, 6086.45221930024, 5537.640053396516, 5574.498295153415, 5592.049351610236, 5653.660780353192, 5661.745221465044, 6242.500599951993, 6242.563020588515, 6275.96862015687, 6281.78013769525, 6282.120503129566, 6001.189839371629, 6013.51891848651, 6016.398360163948, 6016.398360163948, 6058.636481811105, 5878.647281163058, 5886.470217680429, 5900.4984576310635, 5909.734061967221, 5924.7333869975955, 5957.255343082114, 5973.1338955852025, 5973.522118230531, 5977.850553736192, 6005.489533389711, 5914.497267732205, 5983.521318294526, 5993.910091348634, 5995.250593675791, 6029.487493563009, 4508.684392092522, 4535.931961020588, 4547.386102876136, 4601.401817763736, 4619.86140415788, 4085.305498065336, 4110.095778928631, 4117.07365842683, 4126.442930204384, 4149.2738770715605, 5687.459691329317, 5722.37053924068, 5724.427557244242, 5725.742341594639, 5734.426557344315, 5888.057910734262, 5926.318473375617, 5944.851378715567, 6034.758609655652, 6055.697215139226, 5588.161775733673, 5711.086226203871, 5723.313202415737, 5733.942660573341, 5745.482906538432, 5072.898542029138, 5077.339945807095, 5084.034033533617, 5084.161113416319, 5084.466131056186, 6070.271567412027, 6140.785072522441, 6179.721912513947, 6249.437550620466, 6265.498760099181, 5984.072468767348, 5985.042393217239, 6009.038553831366, 6115.422885572139, 6120.050983828623, 6296.590721603081, 6297.496200303873, 6308.653024083719, 6311.0, 6322.589031712924, 5681.261436013292, 5993.460588547052, 6002.279726432856, 6012.128241405059, 6013.7293821779, 5699.800506982236, 5958.672273024852, 5971.343152253162, 5979.461848433488, 6110.680268050674, 5922.466977972003, 5977.9103313451205, 5979.790707325223, 5982.970085149721, 6009.579329446897, 5791.102379131242, 5986.551008674454, 5992.370801065826, 6003.219581454383, 6008.639481631116, 5721.914171287517, 5809.622374545641, 5834.416558344132, 5873.236479257669, 5989.1914591529585, 5992.820215393545, 6004.189434426296, 6016.608920420334, 6019.428154325332, 6020.638761674139, 5608.270924779753, 5664.773409063591, 5850.853728656818, 5855.268091488564, 5866.325372582208], "value": 5555.38092538727, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.5585466754603, 629.3046183966717, 646.1632186318717, 649.5096202367212, 668.8996650502427, 34598.48102278465, 35239.82380088099, 38173.0, 46818.531814681846, 47853.28220076702, 47971.0, 48328.0, 48360.274595881005, 48374.51625483744, 48374.75812620932, 9405.717828465156, 9486.430814151174, 9503.382280151802, 9523.57143928525, 9559.426434413923, 8471.576421178916, 8678.088800675932, 11001.779964400706, 11248.831267530994, 11261.380624065672, 11540.422978851066, 11630.941845290761, 11634.6509604712, 11635.534578616887, 11648.126390520703, 8619.267362274215, 11319.830202546967, 11397.145214108976, 11401.315921044681, 11434.885651143506, 8398.496090234545, 10736.516856741466, 11082.501287442004, 11093.16801239902, 11473.827892581618, 45243.77378113121, 45614.0, 45647.31529027067, 45653.77173114147, 45721.0, 3888.844446222176, 4014.558398576154, 4236.0, 4268.167707297078, 4549.385832912581, 35052.07867901869, 35233.11917202065, 35688.46467302993, 36079.4588081179, 36669.08327291812, 4656.837010704609, 4708.223143181371, 4812.7352995585225, 4854.781534830906, 4857.17428037231, 366508.0, 366797.9999999973, 367732.0, 368190.0, 369290.0, 39016.414753778714, 66452.00965956543, 66775.33224667788, 66820.33179668238, 66865.0, 39049.609503905165, 41563.376549351786, 41651.7917410411, 41707.37438938418, 41733.1653366931, 17604.207810648553, 17646.1176941155, 17788.55528611769, 17795.82204177942, 17798.288068477374, 13119.147255428366, 13445.46218151263, 14799.334029968677, 14859.479918202811, 14912.403503859941, 9293.953530232306, 9333.20667743236, 9646.18007469373, 9655.275854310814, 9757.316987810786, 3607.873724419685, 3640.8167345612783, 3662.249238906018, 3724.0, 3755.7746535207975, 1038.1850247555633, 1057.6245432871374, 1083.4853444613746, 1134.2910680824484, 1151.7811615792973, 32787.83606081954, 35198.824005879804, 35228.64771352305, 35335.36267436014, 35621.28757424837, 1146.856642919635, 1162.0703437250165, 1193.427154965604, 1274.025041869355, 1315.7631626307166, 18328.175232114587, 18469.261229550666, 18497.445076647724, 18538.536536586424, 18610.813891860907, 542197.0, 544035.0, 545744.0, 545992.0, 550998.0, 65399.0, 65806.67096664582, 66020.0, 66049.33950660528, 66071.66964165149, 1309.4696647857634, 1370.3011464153353, 1428.0, 1434.8995570310187, 1474.2039298778968, 31166.22084447907, 35646.28707425785, 36276.45585316275, 36377.45433818442, 37152.44271335878, 58350.124748127964, 58794.70602647131, 59098.0, 59290.0, 59357.0, 468309.0, 474409.0, 474606.0, 475379.0, 475904.0, 46500.0, 47355.763221183675, 48213.75893120512, 51370.486295138064, 51564.48435515597, 52698.47301526936, 52894.735526322125, 52907.20639190338, 53085.0, 53257.20114198213, 43990.56009439864, 44121.55878441175, 44838.0, 45911.54088459073, 49073.0, 46581.58751106001, 47150.528494714614, 47266.52733472621, 47273.29090063721, 47916.520834791205, 43737.0, 44514.77742611266, 44630.55369446394, 45649.77175114103, 47364.0, 38574.80712596419, 40114.0, 40855.38716919189, 40958.59041409548, 41186.0, 28713.85643071771, 29009.56485652759, 29189.562156568092, 29505.557416639196, 29687.406251875273], "value": 21183.657150146017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.9407588861672, 430.8576118178088, 433.20290665176077, 434.50249464363304, 447.8790726503846, 16897.746533801987, 19895.0, 20759.688604670904, 21490.892545537252, 21536.246231381905, 19741.407757767254, 20451.181952721912, 20571.177152913904, 20673.483162920933, 20778.584428311424, 5914.2015827863115, 5992.34084250732, 6285.182926219585, 6288.025356069817, 6297.244330680325, 5991.910121348162, 6047.183630209909, 6071.848203794896, 6082.118092876531, 6092.786752463665, 5700.600957932947, 5921.141434491996, 6070.2412198475195, 6071.817845464621, 6072.02847544393, 6108.511319094484, 6136.631802091889, 6139.2325959255095, 6150.200473938382, 6155.045967874987, 5889.734961926723, 5905.911411328833, 5958.225430694026, 5979.133025711248, 6002.51979841616, 14649.70700585993, 14899.851001489955, 17270.654586908193, 24373.390665233335, 24640.87679561609, 2462.4582591829767, 2613.516499447608, 2646.7882569394596, 2902.593636890816, 2996.8801247950055, 10648.307859989076, 10957.56169753209, 11180.552777888875, 11211.103111751041, 11288.774224515546, 1997.530580313625, 2003.8697484663596, 2043.7751847296786, 2051.507638166844, 2076.781937896529, 86251.0, 86267.0, 90114.0, 90503.0, 95066.0, 10408.323458975143, 11298.7175320616, 11458.255213411101, 11586.420678966018, 11907.166498345032, 15012.699746005019, 15113.0, 15946.362145514282, 15993.840061599236, 16636.417725379553, 8820.955895220482, 8858.202761751474, 8859.689910853089, 9029.0, 9100.590473428712, 8793.91206087931, 8861.158189971942, 8912.68805591814, 9019.458832470073, 9223.216026637676, 4857.975710121497, 4983.152864013054, 5004.124278251292, 5012.548870601663, 5070.163423035158, 1936.2835750772024, 1997.4159304396444, 2061.4228016155503, 2061.4537147655883, 2085.9478513037297, 919.6827094652334, 973.0804389851663, 973.6154219083564, 1022.8158931392421, 1026.4200726589454, 20931.581368372546, 21009.26467573658, 21039.762646668532, 21183.364499065054, 21427.89286053591, 1012.4583347908833, 1038.678009816959, 1074.2426589254712, 1105.5964572930823, 1128.995194277094, 11012.614558490415, 11646.359450230319, 11811.114166437552, 11825.645230643095, 11897.405129743478, 512622.0, 515510.0, 527880.0, 547496.0, 551966.0, 96750.0, 97194.0, 98262.50868745754, 98828.50585747026, 104221.0, 1067.4395942130443, 1076.7039064257392, 1106.651404807483, 1112.1380929779486, 1116.3859877067744, 18862.622747545247, 19082.904585476983, 19135.712964305825, 19187.0, 19300.32448864283, 31126.844365778932, 41021.0, 42355.7882210587, 42409.787951061284, 45090.97090630995, 62139.37860621336, 62365.68817155885, 62522.37477625348, 62915.37084629095, 64200.357996419436, 9093.363464557417, 9534.61861525559, 9561.90438095638, 9647.89049259316, 9707.70876873695, 12422.813657794959, 13759.587212383645, 13944.744972952483, 14091.859081409464, 14110.365033573718, 12186.0, 12195.390230488618, 12438.564650237198, 12750.489980401051, 12836.871631283568, 11883.049356051322, 12209.511619535475, 12352.135350525377, 12572.434240459019, 12797.0, 12575.87424125747, 12582.748345033231, 12633.557825476064, 12857.485700571873, 13203.801942970671, 7736.883946740916, 8448.450850694686, 8562.186592273723, 8694.565271736514, 8978.236849867835, 11660.41697915084, 11675.824862626898, 11810.350430726048, 11906.702332441759, 12285.631431057083], "value": 9754.43996986462, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.1259275916348, 1530.15841287292, 1648.571371443425, 1794.9730754038683, 1820.7724034495689, 9015.504147271906, 9719.882213545445, 9725.610975560987, 9728.70813875584, 9735.221182305417, 8416.0, 8677.045524992262, 9095.272378209744, 9365.391249568791, 9390.530473476316, 2439.1219161102035, 2463.0763463701114, 2473.468204336067, 2477.8017758579363, 2484.0, 2427.9028838846425, 2486.2292689266296, 2493.227099599121, 2494.5759220932487, 2497.0760818497183, 2429.671994280776, 2440.2923152285816, 2473.7402572729875, 2475.665785119013, 2475.851448913062, 2407.9157229496973, 2419.600765873629, 2432.1122790181635, 2462.839915405511, 2462.8645424501647, 2405.0980882169188, 2420.213430635051, 2425.0905910283645, 2426.7451917548756, 2428.0530593068715, 9448.858267126, 9533.094356036165, 9556.952215238949, 9605.135537801561, 9614.0, 5324.733763311819, 5394.352677678665, 5427.62006659534, 5445.673259604437, 5449.4005659377435, 3469.6877281044576, 3551.449525323553, 3631.2737452509605, 3752.0807402186365, 3769.905752356213, 1325.9326242374884, 1384.7715127004033, 1385.7090011097682, 1403.964900877476, 1416.178616402484, 12770.680732981657, 12906.677333066655, 12973.870261297361, 12976.740465190738, 12998.090133690646, 4778.880527986828, 4797.2564252540915, 4817.470078291385, 4856.490068542823, 4958.876028099255, 10888.782224355627, 10891.074258687942, 10895.128389728803, 10900.945495272474, 10931.726706832234, 5490.3411590609385, 5512.91730624041, 5519.586031047687, 5520.06158952971, 5525.309336332958, 4573.108243892409, 4735.976320118378, 4738.691985020962, 5028.798848046037, 5074.213496907985, 4406.2949928012, 4406.669499787528, 4416.0, 4485.798139083749, 4526.524714904954, 1372.8558501357213, 1392.470861072786, 1398.0563119894032, 1398.7412328719015, 1432.8495507971722, 949.4493193947494, 1055.382601178304, 1070.652038087609, 1073.592035026685, 1078.196743426137, 6360.618362898241, 6386.690728400667, 6434.388733070351, 6626.602403855784, 6639.0, 490.5094905094905, 501.7315736081252, 504.41487874065905, 508.42547920849205, 509.3683832048234, 4466.665000125004, 4496.348029535699, 4546.931796023062, 4586.197415452282, 4680.321353403738, 11448.313101213955, 11962.0, 11977.341246231541, 11984.161108722481, 11990.460429280529, 11480.138989575647, 11564.13269004828, 11723.824142637868, 11760.294382336917, 11762.176647634755, 8747.08155643661, 8810.0, 8822.2501087407, 8886.289096872364, 8892.466452012899, 6404.455621272244, 6514.218293804584, 6533.803985880431, 6614.239362473185, 6675.666216689243, 10777.191710621579, 10787.946060269649, 10946.72633184145, 10968.945155274172, 11021.944890275498, 10728.946355268174, 10833.29583577065, 10978.451077446256, 11002.284851484628, 11051.005409513182, 7514.098308202832, 7703.884441733267, 7734.961325193338, 8509.957450212709, 8544.700935467228, 8675.522846243517, 8744.300455963636, 8835.381523293308, 9082.954585227297, 9132.269418446642, 8123.7156699515235, 8159.918400815915, 8182.877256841271, 8312.501249925024, 8321.500709957421, 8151.103378628228, 8268.214519620626, 8283.25450709439, 8300.83398332042, 8349.499030058216, 7608.581528016012, 7612.8477430449975, 7628.313451789366, 7674.884876726966, 7708.306252437307, 5387.461253874581, 5404.513593776579, 5463.934532766231, 5512.476314750093, 5521.089020311764, 5810.5061069809535, 5853.326867410302, 5889.852753681193, 5909.084091965752, 5926.4666180044005], "value": 4891.122980923853, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.4326396495071, 336.36595018390335, 341.85471174750734, 352.7706990456203, 356.5558099229778, 5291.28567643368, 5434.83695489136, 5507.889842203153, 5542.889142217154, 5564.527015203707, 5288.233206185101, 5394.703291318986, 5443.156310771837, 5462.7268636568115, 5489.231507588931, 4364.279893817532, 4373.343998400233, 4374.781260936957, 4376.49670287917, 4379.693421460499, 4221.219074471217, 4329.696921215516, 4370.868873933771, 4377.759223242736, 4384.618845063811, 4298.419713338706, 4319.3952846601505, 4332.566743325674, 4335.371371151197, 4336.566343365654, 4294.871153865389, 4302.849400270976, 4305.870823875289, 4319.244132276839, 4350.978245108786, 3591.3894637911617, 3737.402015677506, 3745.756525825813, 3747.9812600937094, 3777.58446570877, 5308.309919710451, 5316.627836051478, 5322.281491998569, 5330.333708286465, 5340.732963351817, 4886.633502487328, 4898.93816364772, 4904.4605093439695, 4909.263610458424, 4942.752862356868, 5529.281193444826, 5700.201971723962, 5711.7715291388295, 5727.599068065237, 5728.169415434781, 4105.712600117993, 4119.487112861152, 4125.814338354781, 4126.081372161929, 4129.442525259112, 5798.651011741236, 5986.191864098335, 6000.33996260411, 6002.939970600281, 6043.879122417571, 4161.2301724181125, 4175.958240417618, 4187.120704651997, 4192.098698779777, 4197.811098500575, 4218.346156345771, 4226.5139508956245, 4234.830606775756, 4260.4248426462655, 4287.206866729603, 4911.803527858846, 4925.113479573711, 4929.260610908321, 4939.753012349368, 4940.505949405031, 5464.699441530674, 5676.864627074601, 5796.420357964254, 5817.127430885316, 5845.824625261249, 1399.1605036977733, 1425.6934759026858, 1460.6567456647679, 1462.8098347214793, 1469.1038466535406, 954.6801821389773, 975.9072888075764, 983.3853841349156, 992.3004281981185, 993.3295025857517, 5455.427180146027, 5484.61607687458, 5505.0, 5509.559235261169, 5544.63959842609, 5832.154337621021, 5841.795537156179, 5850.239468869105, 5876.265466816648, 5892.734826932712, 5386.703731294817, 5597.80407685729, 5608.355039170466, 5643.5767317451355, 5675.290588676416, 3140.0893740815363, 3140.387624413263, 3140.780145389801, 3154.242981684379, 3158.6417840328418, 5858.5898987070495, 6096.237970253718, 6111.236095488064, 6143.938560614337, 6161.537884658668, 6086.087086936906, 6125.418085281891, 6129.478994285536, 6130.63216207029, 6135.447809697149, 6162.890679677701, 6171.907421388682, 6176.938230617726, 6201.162843016135, 6224.626522408579, 5684.857878553069, 5932.614380065282, 5952.571382867994, 5970.731317090655, 5971.611845230046, 5890.381509941394, 5893.88016276906, 5896.557758168069, 5914.260717410324, 5915.142304365929, 5844.620099693507, 6115.908261375994, 6135.8466038347615, 6141.969290153521, 6148.938510614837, 4276.358546218092, 4305.763183024964, 4308.741475511354, 4308.784560771886, 4308.935365969575, 5723.399043100415, 5909.113632955091, 5970.164177015135, 5991.460768530853, 6030.698465076816, 5738.225339579103, 5789.53683705311, 5803.506701930214, 5804.6807425592, 5817.894600025981, 5792.13289421739, 5852.590318677822, 5854.677992710442, 5862.208601838697, 5910.876933382644, 5675.914861277167, 5775.884482310415, 5804.6201057566195, 5807.448292412214, 5813.09896965971, 5854.824355269348, 5857.150713146485, 5875.970620146872, 5885.735141918538, 5896.17453556511, 5608.9158662619275, 5673.77304907799, 5744.339400968942, 5752.223449834386, 5756.30924289088], "value": 4411.719713729062, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.1074573342192, 827.3463963468864, 858.128999065948, 867.3364875869956, 913.7715571107224, 16378.590535236624, 16528.504144875642, 16534.917325413386, 16559.172041397913, 17010.574735631617, 14897.10617362959, 15034.248287585631, 15071.246437678126, 15363.385464581403, 16033.599160020976, 714.2393351081108, 734.6253410760497, 734.6290123487631, 736.6095969136367, 842.5450256861287, 844.6748002019236, 859.5186695450568, 860.0152825015385, 860.2988564320091, 860.4149178558578, 916.1754421021101, 916.6058594804232, 917.2478567574558, 917.5595714057257, 917.5824999625155, 756.6405957170327, 771.5563550958221, 872.0930232558131, 876.7851876290285, 916.7341470973442, 650.443870490733, 651.0559688451716, 651.1957732200729, 651.2965996723527, 651.316118076023, 16509.58726031847, 16519.504414867573, 16549.25528351215, 16562.83437165625, 16575.336986520524, 15158.09051456905, 15326.693466130615, 15368.0, 15383.230838458145, 15467.0, 14320.498782542561, 14522.564323070324, 14610.926945365312, 14678.926605366905, 14740.0, 4822.252550854623, 4846.442659094214, 4891.8777030574165, 4946.3817022872145, 4976.477469865685, 91285.0, 91873.08126918713, 91906.0, 92574.53712731393, 96313.0, 11874.643760687193, 12205.511779528719, 12287.0, 12289.201201921847, 12381.876181238253, 21931.12275508994, 21932.561348773255, 22153.1138754448, 22180.0, 22190.0, 9159.771005724777, 9222.723318300328, 9312.813743725088, 9644.228461723185, 9676.661316854017, 4908.361912951294, 5013.0, 5098.439171691111, 5118.846434606968, 5256.027634887558, 3750.099976005739, 3895.415687646819, 3900.9219815604097, 3901.2392583445953, 3902.941455878164, 659.0937460991137, 669.8794217040881, 677.9457643388517, 684.0662495693442, 691.3432239372617, 767.286423626022, 777.9066512018594, 779.2635959018786, 789.7433334166419, 791.1179035375515, 10181.847272290921, 10540.789184216426, 10587.470626468647, 10619.628313009007, 10634.20243481742, 683.8632273545311, 722.5267449820376, 733.3693023999463, 739.548875186136, 781.2460975158999, 4917.188663870493, 4959.925601115986, 4967.595094884181, 4998.600391890276, 5025.748712564357, 71789.2821071793, 72248.2775172252, 72583.0, 72999.0, 115635.0, 109986.0, 112066.43966780114, 113531.0, 113977.16137031805, 116740.0, 57930.8413831721, 60684.696576517716, 85768.0, 91658.54170729103, 97494.51252743833, 10021.448820314952, 10330.328528645614, 10457.9477102614, 10800.837987430037, 11330.94334528322, 52735.736321318145, 53443.465565343846, 54993.1751023743, 55089.72455137859, 55263.44736552744, 78089.2191078082, 78549.0, 79528.20471795207, 80696.19303806887, 80721.59639201766, 13276.734465310446, 13531.188128712309, 13562.0, 14076.648083797987, 14399.568012959628, 15193.392264309305, 15294.77057844111, 15378.231088445757, 16057.116858572423, 16176.433824816075, 14521.27393630293, 14550.781738273723, 14563.563093107225, 14698.11811291326, 14905.254737262876, 12991.0, 13083.672908177372, 13145.0, 13812.86187138156, 14066.296685165906, 13446.731065378834, 13468.259245741187, 13727.107737996997, 14390.856091439371, 14648.340824662702, 11650.0, 11682.357470338873, 11947.641570752892, 12111.878881211074, 12231.87768122343, 11128.944355278172, 11874.881251187378, 11923.224990375598, 12826.74346513046, 12847.550335738204], "value": 8008.604163559927, "unit": "score"}], "name": "dromaeo_css", "value": 7636.991648437044, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 111.0, 109.0, 110.0, 111.0, 109.0, 111.0, 111.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 116.0, 161.0, 118.0, 116.0, 117.0, 117.0, 142.0, 119.0], "value": 126.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 164.0, 161.0, 162.0, 160.0, 161.0, 159.0, 162.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 77.0, 113.0, 77.0, 77.0, 77.0, 75.0, 98.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 116.0, 82.0, 83.0, 82.0, 99.0, 81.0, 82.0, 112.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 144.0, 145.0, 145.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 49.0, 48.0, 50.0, 50.0, 49.0, 51.0, 49.0, 49.0, 50.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 58.0, 58.0, 57.0, 58.0, 57.0, 57.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 74.0, 74.0, 73.0, 83.0, 74.0, 78.0, 73.0, 73.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 120.0, 118.0, 117.0, 116.0, 117.0, 123.0, 119.0, 198.0, 132.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 157.0, 159.0, 158.0, 156.0, 158.0, 157.0, 159.0, 155.0, 158.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 62.0, 63.0, 62.0, 64.0, 66.0, 62.0, 62.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1457.9999999999998, "alertThreshold": 2.0}]} 16:15:00 INFO - Return code: 0 16:15:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:15:00 INFO - # TBPL SUCCESS # 16:15:00 INFO - Running post-action listener: _resource_record_post_action 16:15:00 INFO - [mozharness: 2016-10-03 23:15:00.106861Z] Finished run-tests step (success) 16:15:00 INFO - Running post-run listener: _resource_record_post_run 16:15:00 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 356081664; Read time: 4; Write time: 626068 16:15:00 INFO - TinderboxPrint: CPU usage
12.9% 16:15:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 16:15:00 INFO - TinderboxPrint: I/O write bytes / time
356,081,664 / 626,068 16:15:00 INFO - TinderboxPrint: CPU idle
8,275.1 (87.0%) 16:15:00 INFO - TinderboxPrint: CPU user
1,183.2 (12.4%) 16:15:00 INFO - TinderboxPrint: Swap in / out
0 / 0 16:15:00 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 108032000; Read time: 0; Write time: 312312 16:15:00 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 247705600; Read time: 0; Write time: 313756 16:15:00 INFO - Running post-run listener: _upload_blobber_files 16:15:00 INFO - Blob upload gear active. 16:15:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:15:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:15:00 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:15:00 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:15:01 INFO - (blobuploader) - INFO - Open directory for files ... 16:15:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:15:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:15:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:01 INFO - (blobuploader) - INFO - Done attempting. 16:15:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:15:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:15:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:05 INFO - (blobuploader) - INFO - Done attempting. 16:15:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:15:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:15:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:06 INFO - (blobuploader) - INFO - Done attempting. 16:15:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:15:06 INFO - Return code: 0 16:15:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:15:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:15:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3efb3902901d8424e1595ba4e4b68a7bea60e59a8c6e99c434a6e2ddc35b4f77a105232225c5c23c6eaf4469172bc0db446aaf35f45d408b6169663dbccf71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6fd40be3e6d6a6b2dadcc4ae25aaecd3093763c758d2f36935ba5bf3fef4d1c898c7519eaf6e6f6cc76b4677ecc70359d347122e6640abd683fbd81f5181f2b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a0cf2571f4f292c465655487dee76bc0a5048724a9a31944e73ec18b0898b49335847a69010a753e20d0db7207fa63627b48be5a359e53fb490b25478e97cad"} 16:15:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:15:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:15:06 INFO - Contents: 16:15:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3efb3902901d8424e1595ba4e4b68a7bea60e59a8c6e99c434a6e2ddc35b4f77a105232225c5c23c6eaf4469172bc0db446aaf35f45d408b6169663dbccf71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6fd40be3e6d6a6b2dadcc4ae25aaecd3093763c758d2f36935ba5bf3fef4d1c898c7519eaf6e6f6cc76b4677ecc70359d347122e6640abd683fbd81f5181f2b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a0cf2571f4f292c465655487dee76bc0a5048724a9a31944e73ec18b0898b49335847a69010a753e20d0db7207fa63627b48be5a359e53fb490b25478e97cad"} 16:15:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:15:06 INFO - Copying logs to upload dir... 16:15:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:15:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.663255 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-03 16:15:05.734119) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 16:15:05.741699) ========= 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=8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3efb3902901d8424e1595ba4e4b68a7bea60e59a8c6e99c434a6e2ddc35b4f77a105232225c5c23c6eaf4469172bc0db446aaf35f45d408b6169663dbccf71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6fd40be3e6d6a6b2dadcc4ae25aaecd3093763c758d2f36935ba5bf3fef4d1c898c7519eaf6e6f6cc76b4677ecc70359d347122e6640abd683fbd81f5181f2b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a0cf2571f4f292c465655487dee76bc0a5048724a9a31944e73ec18b0898b49335847a69010a753e20d0db7207fa63627b48be5a359e53fb490b25478e97cad"} build_url:https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011505 build_url: 'https://queue.taskcluster.net/v1/task/LWeBPjwISqCFLVMGJ5dVgg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c3efb3902901d8424e1595ba4e4b68a7bea60e59a8c6e99c434a6e2ddc35b4f77a105232225c5c23c6eaf4469172bc0db446aaf35f45d408b6169663dbccf71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6fd40be3e6d6a6b2dadcc4ae25aaecd3093763c758d2f36935ba5bf3fef4d1c898c7519eaf6e6f6cc76b4677ecc70359d347122e6640abd683fbd81f5181f2b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a0cf2571f4f292c465655487dee76bc0a5048724a9a31944e73ec18b0898b49335847a69010a753e20d0db7207fa63627b48be5a359e53fb490b25478e97cad"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 16:15:05.773065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 16:15:05.773540) ========= 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=8783db0840cc980e80bb0bd400000236-1475535269.899910-401306396 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004588 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 16:15:05.823613) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 16:15:05.823970) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 16:15:05.827384) ========= ========= Total master_lag: 0.18 =========