builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1472199291.25 results: success (0) buildid: 20160825223021 builduid: 20a68942304e46068a7fa76cdb580889 revision: e78454e242072bbcada626874f2900bbe1c3e647 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.249783) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.250235) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.250559) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.285432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.285740) ========= 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=41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 01:14:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.6M=0s 2016-08-26 01:14:51 (23.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116330 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.419363) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.419690) ========= 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=41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019003 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.471975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:51.472338) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e78454e242072bbcada626874f2900bbe1c3e647 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e78454e242072bbcada626874f2900bbe1c3e647 --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=41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 01:14:51,625 truncating revision to first 12 chars 2016-08-26 01:14:51,625 Setting DEBUG logging. 2016-08-26 01:14:51,625 attempt 1/10 2016-08-26 01:14:51,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/e78454e24207?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 01:14:51,928 unpacking tar archive at: mozilla-inbound-e78454e24207/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639404 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:52.125410) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:52.125742) ========= script_repo_revision: e78454e242072bbcada626874f2900bbe1c3e647 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:52.126447) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:52.126762) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 01:14:52.136209) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-26 01:14:52.136522) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:14:52 INFO - MultiFileLogger online at 20160826 01:14:52 in /builds/slave/test 01:14:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 01:14:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:14:52 INFO - {'append_to_log': False, 01:14:52 INFO - 'base_work_dir': '/builds/slave/test', 01:14:52 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 01:14:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:14:52 INFO - 'branch': 'Mozilla-Inbound', 01:14:52 INFO - 'buildbot_json_path': 'buildprops.json', 01:14:52 INFO - 'config_files': ('talos/linux_config.py',), 01:14:52 INFO - 'default_actions': ('clobber', 01:14:52 INFO - 'read-buildbot-config', 01:14:52 INFO - 'download-and-extract', 01:14:52 INFO - 'populate-webroot', 01:14:52 INFO - 'create-virtualenv', 01:14:52 INFO - 'install', 01:14:52 INFO - 'run-tests'), 01:14:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:14:52 INFO - 'download_minidump_stackwalk': True, 01:14:52 INFO - 'download_symbols': 'ondemand', 01:14:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:14:52 INFO - 'tooltool.py': '/tools/tooltool.py', 01:14:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:14:52 INFO - '/tools/misc-python/virtualenv.py')}, 01:14:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:14:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:14:52 INFO - 'installer_path': 'installer.exe', 01:14:52 INFO - 'log_level': 'info', 01:14:52 INFO - 'log_name': 'talos', 01:14:52 INFO - 'log_to_console': True, 01:14:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:14:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:14:52 INFO - 'opt_config_files': (), 01:14:52 INFO - 'pip_index': False, 01:14:52 INFO - 'sps_profile': False, 01:14:52 INFO - 'sps_profile_interval': 0, 01:14:52 INFO - 'suite': 'dromaeojs', 01:14:52 INFO - 'system_bits': '32', 01:14:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:14:52 INFO - 'title': 'talos-linux64-ix-024', 01:14:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:14:52 INFO - 'use_talos_json': True, 01:14:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:14:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:14:52 INFO - 'work_dir': 'build'} 01:14:52 INFO - [mozharness: 2016-08-26 08:14:52.392998Z] Running clobber step. 01:14:52 INFO - Running pre-action listener: _resource_record_pre_action 01:14:52 INFO - Running main action method: clobber 01:14:52 INFO - rmtree: /builds/slave/test/build 01:14:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:14:52 INFO - Running post-action listener: _resource_record_post_action 01:14:52 INFO - [mozharness: 2016-08-26 08:14:52.853274Z] Finished clobber step (success) 01:14:52 INFO - [mozharness: 2016-08-26 08:14:52.853375Z] Running read-buildbot-config step. 01:14:52 INFO - Running pre-action listener: _resource_record_pre_action 01:14:52 INFO - Running main action method: read_buildbot_config 01:14:52 INFO - Using buildbot properties: 01:14:52 INFO - { 01:14:52 INFO - "project": "", 01:14:52 INFO - "product": "firefox", 01:14:52 INFO - "script_repo_revision": "production", 01:14:52 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 01:14:52 INFO - "repository": "", 01:14:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 01:14:52 INFO - "buildid": "20160825223021", 01:14:52 INFO - "pgo_build": "True", 01:14:52 INFO - "basedir": "/builds/slave/test", 01:14:52 INFO - "buildnumber": 1601, 01:14:52 INFO - "slavename": "talos-linux64-ix-024", 01:14:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:14:52 INFO - "platform": "ubuntu64_hw", 01:14:52 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 01:14:52 INFO - "branch": "mozilla-inbound", 01:14:52 INFO - "repo_path": "integration/mozilla-inbound", 01:14:52 INFO - "revision": "e78454e242072bbcada626874f2900bbe1c3e647", 01:14:52 INFO - "stage_platform": "linux64-pgo", 01:14:52 INFO - "builduid": "20a68942304e46068a7fa76cdb580889", 01:14:52 INFO - "slavebuilddir": "test" 01:14:52 INFO - } 01:14:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 01:14:52 INFO - Running post-action listener: _resource_record_post_action 01:14:52 INFO - [mozharness: 2016-08-26 08:14:52.855810Z] Finished read-buildbot-config step (success) 01:14:52 INFO - [mozharness: 2016-08-26 08:14:52.855898Z] Running download-and-extract step. 01:14:52 INFO - Running pre-action listener: _resource_record_pre_action 01:14:52 INFO - Running main action method: download_and_extract 01:14:52 INFO - mkdir: /builds/slave/test/build/tests 01:14:52 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')]} 01:14:52 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:14:52 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:14:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:14:53 INFO - Downloaded 1361 bytes. 01:14:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:14:53 INFO - Using the following test package requirements: 01:14:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:14:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:14:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:14:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'jsshell-linux-x86_64.zip'], 01:14:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:14:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:14:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:14:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:14:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:14:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:14:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:14:53 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')]} 01:14:53 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:14:53 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:14:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:14:54 INFO - Downloaded 21871778 bytes. 01:14:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 01:14:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:14:58 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:14:58 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:14:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:14:59 INFO - Downloaded 21871778 bytes. 01:14:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 01:15:04 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')]} 01:15:04 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:15:04 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:15:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:15:05 INFO - Downloaded 14413981 bytes. 01:15:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 01:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:05 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:15:05 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:15:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:15:06 INFO - Downloaded 59710219 bytes. 01:15:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:15:06 INFO - mkdir: /builds/slave/test/properties 01:15:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:15:06 INFO - Writing to file /builds/slave/test/properties/build_url 01:15:06 INFO - Contents: 01:15:06 INFO - build_url:https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:15:07 INFO - Running post-action listener: _resource_record_post_action 01:15:07 INFO - Running post-action listener: set_extra_try_arguments 01:15:07 INFO - [mozharness: 2016-08-26 08:15:07.481415Z] Finished download-and-extract step (success) 01:15:07 INFO - [mozharness: 2016-08-26 08:15:07.481639Z] Running populate-webroot step. 01:15:07 INFO - Running pre-action listener: _resource_record_pre_action 01:15:07 INFO - Running main action method: populate_webroot 01:15:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:15:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:15:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:15:07 INFO - '--tppagecycles', 01:15:07 INFO - '7'], 01:15:07 INFO - 'tests': ['tsvgm']}}, 01:15:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:15:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:15:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:15:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:15:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:15:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:15:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['damp', 'tps']}, 01:15:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'tests': ['damp', 'tps']}, 01:15:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['dromaeo_dom']}, 01:15:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:15:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['basic_compositor_video']}, 01:15:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:15:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['a11yr', 01:15:07 INFO - 'ts_paint', 01:15:07 INFO - 'tpaint', 01:15:07 INFO - 'sessionrestore', 01:15:07 INFO - 'sessionrestore_no_auto_restore', 01:15:07 INFO - 'tabpaint']}, 01:15:07 INFO - 'other-e10s': {'tests': ['a11yr', 01:15:07 INFO - 'ts_paint', 01:15:07 INFO - 'tpaint', 01:15:07 INFO - 'sessionrestore', 01:15:07 INFO - 'sessionrestore_no_auto_restore', 01:15:07 INFO - 'tabpaint']}, 01:15:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:15:07 INFO - 'ts_paint', 01:15:07 INFO - 'tpaint', 01:15:07 INFO - 'sessionrestore', 01:15:07 INFO - 'sessionrestore_no_auto_restore', 01:15:07 INFO - 'tabpaint']}, 01:15:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:15:07 INFO - 'ts_paint', 01:15:07 INFO - 'tpaint', 01:15:07 INFO - 'sessionrestore', 01:15:07 INFO - 'sessionrestore_no_auto_restore', 01:15:07 INFO - 'tabpaint']}, 01:15:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['a11yr', 01:15:07 INFO - 'ts_paint', 01:15:07 INFO - 'tpaint', 01:15:07 INFO - 'sessionrestore', 01:15:07 INFO - 'sessionrestore_no_auto_restore', 01:15:07 INFO - 'tabpaint']}, 01:15:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['a11yr', 01:15:07 INFO - 'ts_paint', 01:15:07 INFO - 'tpaint', 01:15:07 INFO - 'sessionrestore', 01:15:07 INFO - 'sessionrestore_no_auto_restore', 01:15:07 INFO - 'tabpaint']}, 01:15:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['tsvgx', 01:15:07 INFO - 'tsvgr_opacity', 01:15:07 INFO - 'tart', 01:15:07 INFO - 'tscrollx', 01:15:07 INFO - 'cart']}, 01:15:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:15:07 INFO - 'tsvgr_opacity', 01:15:07 INFO - 'tart', 01:15:07 INFO - 'tscrollx', 01:15:07 INFO - 'cart']}, 01:15:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'talos_options': ['--disable-e10s'], 01:15:07 INFO - 'tests': ['tp5o']}, 01:15:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'tests': ['tp5o']}, 01:15:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'talos_options': ['--disable-e10s', 01:15:07 INFO - '--xperf_path', 01:15:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:15:07 INFO - 'tests': ['tp5n']}, 01:15:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:07 INFO - 'talos_options': ['--xperf_path', 01:15:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:15:07 INFO - 'tests': ['tp5n']}}, 01:15:07 INFO - 'talos.zip': {'path': '', 01:15:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:15:07 INFO - Running post-action listener: _resource_record_post_action 01:15:07 INFO - [mozharness: 2016-08-26 08:15:07.499447Z] Finished populate-webroot step (success) 01:15:07 INFO - [mozharness: 2016-08-26 08:15:07.499535Z] Running create-virtualenv step. 01:15:07 INFO - Running pre-action listener: _resource_record_pre_action 01:15:07 INFO - Running main action method: create_virtualenv 01:15:07 INFO - Creating virtualenv /builds/slave/test/build/venv 01:15:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:15:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:15:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:15:07 INFO - Using real prefix '/usr' 01:15:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:15:08 INFO - Installing distribute.............................................................................................................................................................................................done. 01:15:10 INFO - Installing pip.................done. 01:15:10 INFO - Return code: 0 01:15:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:15:10 INFO - Using env: {'DISPLAY': ':0', 01:15:10 INFO - 'HOME': '/home/cltbld', 01:15:10 INFO - 'LANG': 'en_US.UTF-8', 01:15:10 INFO - 'LANGUAGE': 'en_US:en', 01:15:10 INFO - 'LOGNAME': 'cltbld', 01:15:10 INFO - 'MAIL': '/var/mail/cltbld', 01:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:10 INFO - 'MOZ_NO_REMOTE': '1', 01:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:10 INFO - 'NO_EM_RESTART': '1', 01:15:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:10 INFO - 'PWD': '/builds/slave/test', 01:15:10 INFO - 'SHELL': '/bin/bash', 01:15:10 INFO - 'SHLVL': '1', 01:15:10 INFO - 'TERM': 'linux', 01:15:10 INFO - 'TMOUT': '86400', 01:15:10 INFO - 'USER': 'cltbld', 01:15:10 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:10 INFO - '_': '/tools/buildbot/bin/python'} 01:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:15:10 INFO - Cleaning up... 01:15:10 INFO - Return code: 0 01:15:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:15:10 INFO - Using env: {'DISPLAY': ':0', 01:15:10 INFO - 'HOME': '/home/cltbld', 01:15:10 INFO - 'LANG': 'en_US.UTF-8', 01:15:10 INFO - 'LANGUAGE': 'en_US:en', 01:15:10 INFO - 'LOGNAME': 'cltbld', 01:15:10 INFO - 'MAIL': '/var/mail/cltbld', 01:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:10 INFO - 'MOZ_NO_REMOTE': '1', 01:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:10 INFO - 'NO_EM_RESTART': '1', 01:15:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:10 INFO - 'PWD': '/builds/slave/test', 01:15:10 INFO - 'SHELL': '/bin/bash', 01:15:10 INFO - 'SHLVL': '1', 01:15:10 INFO - 'TERM': 'linux', 01:15:10 INFO - 'TMOUT': '86400', 01:15:10 INFO - 'USER': 'cltbld', 01:15:10 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:10 INFO - '_': '/tools/buildbot/bin/python'} 01:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:10 INFO - Downloading/unpacking psutil>=3.1.1 01:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:15:12 INFO - Installing collected packages: psutil 01:15:12 INFO - Running setup.py install for psutil 01:15:12 INFO - building 'psutil._psutil_linux' extension 01:15:12 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 01:15:12 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 01:15:12 INFO - building 'psutil._psutil_posix' extension 01:15:12 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 01:15:12 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 01:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:15:12 INFO - Successfully installed psutil 01:15:12 INFO - Cleaning up... 01:15:13 INFO - Return code: 0 01:15:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:15:13 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')]} 01:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:13 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')]} 01:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:13 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 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:13 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 01:15:13 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 01:15:13 INFO - Using env: {'DISPLAY': ':0', 01:15:13 INFO - 'HOME': '/home/cltbld', 01:15:13 INFO - 'LANG': 'en_US.UTF-8', 01:15:13 INFO - 'LANGUAGE': 'en_US:en', 01:15:13 INFO - 'LOGNAME': 'cltbld', 01:15:13 INFO - 'MAIL': '/var/mail/cltbld', 01:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:13 INFO - 'MOZ_NO_REMOTE': '1', 01:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:13 INFO - 'NO_EM_RESTART': '1', 01:15:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:13 INFO - 'PWD': '/builds/slave/test', 01:15:13 INFO - 'SHELL': '/bin/bash', 01:15:13 INFO - 'SHLVL': '1', 01:15:13 INFO - 'TERM': 'linux', 01:15:13 INFO - 'TMOUT': '86400', 01:15:13 INFO - 'USER': 'cltbld', 01:15:13 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:13 INFO - '_': '/tools/buildbot/bin/python'} 01:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:15:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:15:15 INFO - Installing collected packages: mozsystemmonitor 01:15:15 INFO - Running setup.py install for mozsystemmonitor 01:15:15 INFO - Successfully installed mozsystemmonitor 01:15:15 INFO - Cleaning up... 01:15:15 INFO - Return code: 0 01:15:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:15 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 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:15 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 01:15:15 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 01:15:15 INFO - Using env: {'DISPLAY': ':0', 01:15:15 INFO - 'HOME': '/home/cltbld', 01:15:15 INFO - 'LANG': 'en_US.UTF-8', 01:15:15 INFO - 'LANGUAGE': 'en_US:en', 01:15:15 INFO - 'LOGNAME': 'cltbld', 01:15:15 INFO - 'MAIL': '/var/mail/cltbld', 01:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:15 INFO - 'MOZ_NO_REMOTE': '1', 01:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:15 INFO - 'NO_EM_RESTART': '1', 01:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:15 INFO - 'PWD': '/builds/slave/test', 01:15:15 INFO - 'SHELL': '/bin/bash', 01:15:15 INFO - 'SHLVL': '1', 01:15:15 INFO - 'TERM': 'linux', 01:15:15 INFO - 'TMOUT': '86400', 01:15:15 INFO - 'USER': 'cltbld', 01:15:15 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:15 INFO - '_': '/tools/buildbot/bin/python'} 01:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:15 INFO - Downloading/unpacking jsonschema==2.5.1 01:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:15:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:15:17 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 01:15:17 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 01:15:17 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 01:15:17 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 01:15:17 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 01:15:17 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 01:15:17 INFO - Downloading functools32-3.2.3-2.tar.gz 01:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:15:17 INFO - warning: no files found matching '*.txt' 01:15:17 INFO - no previously-included directories found matching 'build' 01:15:17 INFO - no previously-included directories found matching 'dist' 01:15:17 INFO - no previously-included directories found matching '.git*' 01:15:17 INFO - Installing collected packages: jsonschema, functools32 01:15:17 INFO - Running setup.py install for functools32 01:15:17 INFO - warning: no files found matching '*.txt' 01:15:17 INFO - no previously-included directories found matching 'build' 01:15:17 INFO - no previously-included directories found matching 'dist' 01:15:17 INFO - no previously-included directories found matching '.git*' 01:15:17 INFO - Successfully installed jsonschema functools32 01:15:17 INFO - Cleaning up... 01:15:17 INFO - Return code: 0 01:15:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:15:17 INFO - Using env: {'DISPLAY': ':0', 01:15:17 INFO - 'HOME': '/home/cltbld', 01:15:17 INFO - 'LANG': 'en_US.UTF-8', 01:15:17 INFO - 'LANGUAGE': 'en_US:en', 01:15:17 INFO - 'LOGNAME': 'cltbld', 01:15:17 INFO - 'MAIL': '/var/mail/cltbld', 01:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:17 INFO - 'MOZ_NO_REMOTE': '1', 01:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:17 INFO - 'NO_EM_RESTART': '1', 01:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:17 INFO - 'PWD': '/builds/slave/test', 01:15:17 INFO - 'SHELL': '/bin/bash', 01:15:17 INFO - 'SHLVL': '1', 01:15:17 INFO - 'TERM': 'linux', 01:15:17 INFO - 'TMOUT': '86400', 01:15:17 INFO - 'USER': 'cltbld', 01:15:17 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:17 INFO - '_': '/tools/buildbot/bin/python'} 01:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:15:18 INFO - Cleaning up... 01:15:18 INFO - Return code: 0 01:15:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:18 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 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:18 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 01:15:18 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 01:15:18 INFO - Using env: {'DISPLAY': ':0', 01:15:18 INFO - 'HOME': '/home/cltbld', 01:15:18 INFO - 'LANG': 'en_US.UTF-8', 01:15:18 INFO - 'LANGUAGE': 'en_US:en', 01:15:18 INFO - 'LOGNAME': 'cltbld', 01:15:18 INFO - 'MAIL': '/var/mail/cltbld', 01:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:18 INFO - 'MOZ_NO_REMOTE': '1', 01:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:18 INFO - 'NO_EM_RESTART': '1', 01:15:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:18 INFO - 'PWD': '/builds/slave/test', 01:15:18 INFO - 'SHELL': '/bin/bash', 01:15:18 INFO - 'SHLVL': '1', 01:15:18 INFO - 'TERM': 'linux', 01:15:18 INFO - 'TMOUT': '86400', 01:15:18 INFO - 'USER': 'cltbld', 01:15:18 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:18 INFO - '_': '/tools/buildbot/bin/python'} 01:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:18 INFO - Downloading/unpacking blobuploader==1.2.4 01:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:20 INFO - Downloading blobuploader-1.2.4.tar.gz 01:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:15:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:15:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:20 INFO - Downloading docopt-0.6.1.tar.gz 01:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:15:20 INFO - Installing collected packages: blobuploader, requests, docopt 01:15:20 INFO - Running setup.py install for blobuploader 01:15:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:15:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:15:21 INFO - Running setup.py install for requests 01:15:21 INFO - Running setup.py install for docopt 01:15:21 INFO - Successfully installed blobuploader requests docopt 01:15:21 INFO - Cleaning up... 01:15:21 INFO - Return code: 0 01:15:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:15:21 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')]} 01:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:21 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')]} 01:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:21 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 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:21 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 01:15:21 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 01:15:21 INFO - Using env: {'DISPLAY': ':0', 01:15:21 INFO - 'HOME': '/home/cltbld', 01:15:21 INFO - 'LANG': 'en_US.UTF-8', 01:15:21 INFO - 'LANGUAGE': 'en_US:en', 01:15:21 INFO - 'LOGNAME': 'cltbld', 01:15:21 INFO - 'MAIL': '/var/mail/cltbld', 01:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:21 INFO - 'MOZ_NO_REMOTE': '1', 01:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:21 INFO - 'NO_EM_RESTART': '1', 01:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:21 INFO - 'PWD': '/builds/slave/test', 01:15:21 INFO - 'SHELL': '/bin/bash', 01:15:21 INFO - 'SHLVL': '1', 01:15:21 INFO - 'TERM': 'linux', 01:15:21 INFO - 'TMOUT': '86400', 01:15:21 INFO - 'USER': 'cltbld', 01:15:21 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:21 INFO - '_': '/tools/buildbot/bin/python'} 01:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:15:21 INFO - Running setup.py (path:/tmp/pip-YNj0Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:15:21 INFO - Running setup.py (path:/tmp/pip-MDZWU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:15:21 INFO - Running setup.py (path:/tmp/pip-kXHrcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:15:21 INFO - Running setup.py (path:/tmp/pip-TzDeeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:15:22 INFO - Running setup.py (path:/tmp/pip-F47r97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:15:22 INFO - Running setup.py (path:/tmp/pip-r3dqzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:15:22 INFO - Running setup.py (path:/tmp/pip-5kjNpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:15:22 INFO - Running setup.py (path:/tmp/pip-H325v0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:15:22 INFO - Running setup.py (path:/tmp/pip-pEvpvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:15:22 INFO - Running setup.py (path:/tmp/pip-j25oKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:15:22 INFO - Running setup.py (path:/tmp/pip-NnO38g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:15:22 INFO - Running setup.py (path:/tmp/pip-UOJ_XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:15:22 INFO - Running setup.py (path:/tmp/pip-zQ5qCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:15:22 INFO - Running setup.py (path:/tmp/pip-4C3re_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:15:22 INFO - Running setup.py (path:/tmp/pip-OOfN8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:15:22 INFO - Running setup.py (path:/tmp/pip-xvCfp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:15:22 INFO - Running setup.py (path:/tmp/pip-2Lpu3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:15:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:15:22 INFO - Running setup.py install for manifestparser 01:15:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Running setup.py install for mozcrash 01:15:23 INFO - Running setup.py install for mozdebug 01:15:23 INFO - Running setup.py install for mozdevice 01:15:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Running setup.py install for mozfile 01:15:23 INFO - Running setup.py install for mozhttpd 01:15:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Running setup.py install for mozinfo 01:15:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Running setup.py install for mozInstall 01:15:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:15:23 INFO - Running setup.py install for mozleak 01:15:23 INFO - Running setup.py install for mozlog 01:15:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:15:24 INFO - Running setup.py install for moznetwork 01:15:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:15:24 INFO - Running setup.py install for mozprocess 01:15:24 INFO - Running setup.py install for mozprofile 01:15:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:15:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:15:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:15:24 INFO - Running setup.py install for mozrunner 01:15:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:15:24 INFO - Running setup.py install for mozscreenshot 01:15:24 INFO - Running setup.py install for moztest 01:15:24 INFO - Running setup.py install for mozversion 01:15:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:15:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:15:24 INFO - Cleaning up... 01:15:24 INFO - Return code: 0 01:15:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:15: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')]} 01:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15: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')]} 01:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:24 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 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:24 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 01:15:24 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 01:15:24 INFO - Using env: {'DISPLAY': ':0', 01:15:24 INFO - 'HOME': '/home/cltbld', 01:15:24 INFO - 'LANG': 'en_US.UTF-8', 01:15:24 INFO - 'LANGUAGE': 'en_US:en', 01:15:24 INFO - 'LOGNAME': 'cltbld', 01:15:24 INFO - 'MAIL': '/var/mail/cltbld', 01:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:24 INFO - 'MOZ_NO_REMOTE': '1', 01:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:24 INFO - 'NO_EM_RESTART': '1', 01:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:24 INFO - 'PWD': '/builds/slave/test', 01:15:24 INFO - 'SHELL': '/bin/bash', 01:15:24 INFO - 'SHLVL': '1', 01:15:24 INFO - 'TERM': 'linux', 01:15:24 INFO - 'TMOUT': '86400', 01:15:24 INFO - 'USER': 'cltbld', 01:15:24 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:24 INFO - '_': '/tools/buildbot/bin/python'} 01:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:15:25 INFO - Running setup.py (path:/tmp/pip-350Z5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:15:25 INFO - Running setup.py (path:/tmp/pip-rQgnfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:15:25 INFO - Running setup.py (path:/tmp/pip-b6lLlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:15:25 INFO - Running setup.py (path:/tmp/pip-nwaFq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:15:25 INFO - Running setup.py (path:/tmp/pip-Yto5LT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:15:25 INFO - Running setup.py (path:/tmp/pip-lJVNga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:15:25 INFO - Running setup.py (path:/tmp/pip-HNfcSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:15:25 INFO - Running setup.py (path:/tmp/pip-yWT9Mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:15:25 INFO - Running setup.py (path:/tmp/pip-RYTd7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:15:25 INFO - Running setup.py (path:/tmp/pip-YIDBph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:15:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:15:25 INFO - Running setup.py (path:/tmp/pip-9BvqV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:15:25 INFO - Running setup.py (path:/tmp/pip-WYJnMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:15:25 INFO - Running setup.py (path:/tmp/pip-whK1dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:15:25 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)) 01:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:15:25 INFO - Running setup.py (path:/tmp/pip-TwkorF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:15:26 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)) 01:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:15:26 INFO - Running setup.py (path:/tmp/pip-zc8DYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:15:26 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)) 01:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:15:26 INFO - Running setup.py (path:/tmp/pip-q1FodA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:15:26 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)) 01:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:15:26 INFO - Running setup.py (path:/tmp/pip-NvOxMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:15:26 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)) 01:15:26 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)) 01:15:26 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)) 01:15:26 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)) 01:15:26 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)) 01:15:26 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)) 01:15:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:15:26 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 01:15:26 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 01:15:26 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 01:15:26 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 01:15:26 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 01:15:26 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 01:15:28 INFO - Downloading blessings-1.6.tar.gz 01:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:15:28 INFO - Installing collected packages: blessings 01:15:28 INFO - Running setup.py install for blessings 01:15:28 INFO - Successfully installed blessings 01:15:28 INFO - Cleaning up... 01:15:28 INFO - Return code: 0 01:15:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:15:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:15:28 INFO - Reading from file tmpfile_stdout 01:15:28 INFO - Current package versions: 01:15:28 INFO - argparse == 1.2.1 01:15:28 INFO - blessings == 1.6 01:15:28 INFO - blobuploader == 1.2.4 01:15:28 INFO - docopt == 0.6.1 01:15:28 INFO - functools32 == 3.2.3-2 01:15:28 INFO - jsonschema == 2.5.1 01:15:28 INFO - manifestparser == 1.1 01:15:28 INFO - mozInstall == 1.12 01:15:28 INFO - mozcrash == 0.17 01:15:28 INFO - mozdebug == 0.1 01:15:28 INFO - mozdevice == 0.48 01:15:28 INFO - mozfile == 1.2 01:15:28 INFO - mozhttpd == 0.7 01:15:28 INFO - mozinfo == 0.9 01:15:28 INFO - mozleak == 0.1 01:15:28 INFO - mozlog == 3.3 01:15:28 INFO - moznetwork == 0.27 01:15:28 INFO - mozprocess == 0.23 01:15:28 INFO - mozprofile == 0.28 01:15:28 INFO - mozrunner == 6.12 01:15:28 INFO - mozscreenshot == 0.1 01:15:28 INFO - mozsystemmonitor == 0.3 01:15:28 INFO - moztest == 0.7 01:15:28 INFO - mozversion == 1.4 01:15:28 INFO - psutil == 3.1.1 01:15:28 INFO - requests == 1.2.3 01:15:28 INFO - wsgiref == 0.1.2 01:15:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:28 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 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:28 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 01:15:28 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 01:15:28 INFO - Using env: {'DISPLAY': ':0', 01:15:28 INFO - 'HOME': '/home/cltbld', 01:15:28 INFO - 'LANG': 'en_US.UTF-8', 01:15:28 INFO - 'LANGUAGE': 'en_US:en', 01:15:28 INFO - 'LOGNAME': 'cltbld', 01:15:28 INFO - 'MAIL': '/var/mail/cltbld', 01:15:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:28 INFO - 'MOZ_NO_REMOTE': '1', 01:15:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:28 INFO - 'NO_EM_RESTART': '1', 01:15:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:28 INFO - 'PWD': '/builds/slave/test', 01:15:28 INFO - 'SHELL': '/bin/bash', 01:15:28 INFO - 'SHLVL': '1', 01:15:28 INFO - 'TERM': 'linux', 01:15:28 INFO - 'TMOUT': '86400', 01:15:28 INFO - 'USER': 'cltbld', 01:15:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:28 INFO - '_': '/tools/buildbot/bin/python'} 01:15:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 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)) 01:15:28 INFO - Cleaning up... 01:15:28 INFO - Return code: 0 01:15:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f304f0bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, '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': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', '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 01:15:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:15:28 INFO - Using env: {'DISPLAY': ':0', 01:15:28 INFO - 'HOME': '/home/cltbld', 01:15:28 INFO - 'LANG': 'en_US.UTF-8', 01:15:28 INFO - 'LANGUAGE': 'en_US:en', 01:15:28 INFO - 'LOGNAME': 'cltbld', 01:15:28 INFO - 'MAIL': '/var/mail/cltbld', 01:15:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:28 INFO - 'MOZ_NO_REMOTE': '1', 01:15:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:28 INFO - 'NO_EM_RESTART': '1', 01:15:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:28 INFO - 'PWD': '/builds/slave/test', 01:15:28 INFO - 'SHELL': '/bin/bash', 01:15:28 INFO - 'SHLVL': '1', 01:15:28 INFO - 'TERM': 'linux', 01:15:28 INFO - 'TMOUT': '86400', 01:15:28 INFO - 'USER': 'cltbld', 01:15:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:28 INFO - '_': '/tools/buildbot/bin/python'} 01:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:15:29 INFO - Cleaning up... 01:15:29 INFO - Return code: 0 01:15:29 INFO - Running post-action listener: _resource_record_post_action 01:15:29 INFO - Running post-action listener: _start_resource_monitoring 01:15:29 INFO - Starting resource monitoring. 01:15:29 INFO - [mozharness: 2016-08-26 08:15:29.151111Z] Finished create-virtualenv step (success) 01:15:29 INFO - [mozharness: 2016-08-26 08:15:29.151574Z] Running install step. 01:15:29 INFO - Running pre-action listener: _resource_record_pre_action 01:15:29 INFO - Running main action method: install 01:15:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:15:29 INFO - Reading from file tmpfile_stdout 01:15:29 INFO - Detecting whether we're running mozinstall >=1.0... 01:15:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:15:29 INFO - Reading from file tmpfile_stdout 01:15:29 INFO - Output received: 01:15:29 INFO - Usage: mozinstall [options] installer 01:15:29 INFO - Options: 01:15:29 INFO - -h, --help show this help message and exit 01:15:29 INFO - -d DEST, --destination=DEST 01:15:29 INFO - Directory to install application into. [default: 01:15:29 INFO - "/builds/slave/test"] 01:15:29 INFO - --app=APP Application being installed. [default: firefox] 01:15:29 INFO - mkdir: /builds/slave/test/build/application 01:15:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:15:46 INFO - Reading from file tmpfile_stdout 01:15:46 INFO - Output received: 01:15:46 INFO - /builds/slave/test/build/application/firefox/firefox 01:15:46 INFO - Running post-action listener: _resource_record_post_action 01:15:46 INFO - [mozharness: 2016-08-26 08:15:46.445161Z] Finished install step (success) 01:15:46 INFO - [mozharness: 2016-08-26 08:15:46.445409Z] Running run-tests step. 01:15:46 INFO - Running pre-action listener: _resource_record_pre_action 01:15:46 INFO - Running main action method: run_tests 01:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:15:46 INFO - Python 2.7.3 01:15:46 INFO - Return code: 0 01:15:46 INFO - grabbing minidump binary from tooltool 01:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:46 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 0x2c44090>, '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 0x2c479b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c47e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:15:46 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 01:15:46 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 01:15:46 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 01:15:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:15:46 INFO - Return code: 0 01:15:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:15:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:15:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:15:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:15:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 01:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 01:15:46 INFO - Using env: {'DISPLAY': ':0', 01:15:46 INFO - 'HOME': '/home/cltbld', 01:15:46 INFO - 'LANG': 'en_US.UTF-8', 01:15:46 INFO - 'LANGUAGE': 'en_US:en', 01:15:46 INFO - 'LOGNAME': 'cltbld', 01:15:46 INFO - 'MAIL': '/var/mail/cltbld', 01:15:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:15:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:46 INFO - 'MOZ_NO_REMOTE': '1', 01:15:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:15:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:46 INFO - 'NO_EM_RESTART': '1', 01:15:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:46 INFO - 'PWD': '/builds/slave/test', 01:15:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:15:46 INFO - 'SHELL': '/bin/bash', 01:15:46 INFO - 'SHLVL': '1', 01:15:46 INFO - 'TERM': 'linux', 01:15:46 INFO - 'TMOUT': '86400', 01:15:46 INFO - 'USER': 'cltbld', 01:15:46 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358', 01:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:46 INFO - '_': '/tools/buildbot/bin/python'} 01:15:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 01:15:46 INFO - mozversion application_buildid: 20160825223021 01:15:46 INFO - mozversion application_changeset: e78454e242072bbcada626874f2900bbe1c3e647 01:15:46 INFO - mozversion application_display_name: Nightly 01:15:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:15:46 INFO - mozversion application_name: Firefox 01:15:46 INFO - mozversion application_remotingname: firefox 01:15:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:15:46 INFO - mozversion application_vendor: Mozilla 01:15:46 INFO - mozversion application_version: 51.0a1 01:15:46 INFO - mozversion platform_buildid: 20160825223021 01:15:46 INFO - mozversion platform_changeset: e78454e242072bbcada626874f2900bbe1c3e647 01:15:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:15:46 INFO - mozversion platform_version: 51.0a1 01:15:46 INFO - using testdate: 1472199346 01:15:46 INFO - actual date: 1472199346 01:15:46 INFO - starting webserver on 'localhost:50791' 01:15:46 INFO - SUITE-START | Running 2 tests 01:15:46 INFO - TEST-START | dromaeo_css 01:15:46 INFO - operating with platform_type : linux_ 01:15:46 INFO - Initialising browser for dromaeo_css test... 01:15:46 INFO - TEST-INFO | started process 23669 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHFGjc6/profile http://localhost:50791/getInfo.html) 01:15:51 INFO - PROCESS | 23669 | __metrics Screen width/height:1600/1200 01:15:51 INFO - PROCESS | 23669 | colorDepth:24 01:15:51 INFO - PROCESS | 23669 | Browser inner width/height: 1024/697 01:15:51 INFO - PROCESS | 23669 | __metrics 01:15:51 INFO - PROCESS | 23669 | 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" 01:15:51 INFO - PROCESS | 23669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:15:52 INFO - PROCESS | 23669 | console.error: PushService: 01:15:52 INFO - PROCESS | 23669 | stateChangeProcessEnqueue: Error transitioning state 01:15:52 INFO - PROCESS | 23669 | UnknownError 01:15:52 INFO - TEST-INFO | 23669: exit 0 01:15:52 INFO - Browser initialized. 01:15:52 INFO - Running cycle 1/1 for dromaeo_css test... 01:15:52 INFO - TEST-INFO | started process 23755 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHFGjc6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:16:05 INFO - PROCESS | 23755 | RSS: Main: 179097600 01:16:05 INFO - PROCESS | 23755 | 01:16:56 INFO - PROCESS | 23755 | 1472199416636 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] 01:19:05 INFO - PROCESS | 23755 | Cycle 1(1): loaded http://localhost:50791/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50791/tests/dromaeo/cssquery-ext.html) 01:19:07 INFO - PROCESS | 23755 | RSS: Main: 311414784 01:19:07 INFO - PROCESS | 23755 | 01:22:09 INFO - PROCESS | 23755 | Cycle 1(1): loaded http://localhost:50791/tests/dromaeo/cssquery-ext.html (next: http://localhost:50791/tests/dromaeo/cssquery-jquery.html) 01:22:10 INFO - PROCESS | 23755 | RSS: Main: 266121216 01:22:10 INFO - PROCESS | 23755 | 01:25:12 INFO - PROCESS | 23755 | Cycle 1(1): loaded http://localhost:50791/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50791/tests/dromaeo/cssquery-mootools.html) 01:25:13 INFO - PROCESS | 23755 | RSS: Main: 216150016 01:25:13 INFO - PROCESS | 23755 | 01:28:15 INFO - PROCESS | 23755 | Cycle 1(1): loaded http://localhost:50791/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50791/tests/dromaeo/cssquery-prototype.html) 01:28:15 INFO - PROCESS | 23755 | RSS: Main: 251494400 01:28:15 INFO - PROCESS | 23755 | 01:31:18 INFO - PROCESS | 23755 | Cycle 1(1): loaded http://localhost:50791/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50791/tests/dromaeo/cssquery-yui.html) 01:31:19 INFO - PROCESS | 23755 | RSS: Main: 214855680 01:31:19 INFO - PROCESS | 23755 | 01:34:21 INFO - PROCESS | 23755 | Cycle 1(1): loaded http://localhost:50791/tests/dromaeo/cssquery-yui.html (next: http://localhost:50791/tests/dromaeo/cssquery-dojo.html) 01:34:22 INFO - PROCESS | 23755 | RSS: Main: 302338048 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | __start_tp_report 01:34:22 INFO - PROCESS | 23755 | _x_x_mozilla_page_load 01:34:22 INFO - PROCESS | 23755 | _x_x_mozilla_page_load_details 01:34:22 INFO - PROCESS | 23755 | |i|pagename|runs| 01:34:22 INFO - PROCESS | 23755 | |0;dojo.html;3040.3615240799436;3048.6494053183883;3058.770592205585;3095.5511450839645;3111.937761244775;5222.973885130574;5253.3170687810625;5281.339832520935;5302.681839089652;5368.00691872003;5252.133397989327;5329.813456529022;5339.599530035253;5354.330708661418;5455.208994695777;4788.784504697297;4845.830395936143;4887.535684110004;4891;5010.073136469747;4909.337239472661;4929.556339929406;4954.3063971044085;4971.229459433794;5003.699778013331;4663.953360466419;4746.264329029023;4750.311204875274;4762.404699412574;4897.04507621014;4718.9292160617615;4726.976365118152;4743.264793956942;4801.759912004386;4857.854264372075;4752.572268495852;4761.857144285677;4780.139574876521;4786.641001924835;4831.541003604652;5072.0870243356185;5156.329677141947;5207.140821764404;5276.736163191825;5299.6025298102795;5614.9157762633595;5615.494605485526;5640.605157638967;5646.237757902672;5647.915281270784;5597.188407680904;5672.971635141839;5678.205051292823;5692.544596432275;5695.288088988877;5261.657992230493;5301.840944771663;5310.840674779724;5360.597955153379;5378.838634840961;5809.68046757424;5810.448007439287;5811.215485909433;5813.157092221689;5877.764889404376;5479.315085614298;5591.384947655754;5599.60802743804;5603.075492543766;5604.3835178130375;5666.858328541819;5741.540676745851;5746.396628353963;5775.682337471395;5839.153322768259;5699.658020518782;5714.657120572779;5720.713964301768;5722.914156287659;5723.399043100498;5514.6691198528215;5690.77236910519;5750.223719797774;5755.107958266475;5771.509421699118;4368.170047690928;4392.868213953587;4414.1833760753725;4418.867433976986;4431.977840110779;3908.7068469864876;3925.823337949799;3929.3713005918917;3974.5429275633674;3976.900577485586;5301.761420736076;5431.782728690808;5435.483629055233;5436.918446223231;5449.1826226066405;5600.691961942132;5643.52030077448;5655.0386434306265;5663.32040155184;5738.426157384228;5354.223637572531;5362.463753624606;5376.247325374451;5378.91931621026;5383.031054410166;4803.5916947059195;4929.531694489017;4936.456989731127;4940.728259945666;5008.649394542347;5857.179994800731;5922.615030023035;5923.200367950271;5962.791302304398;6038.486728628028;5735.426457354231;5752.050911599539;5757.366689664217;5759.1649210865025;5781.103928891029;5720.856978575569;5954.89834380641;5957.106434034929;5961;5961.105834124743;5516.806911758069;5527.834164975057;5547.750351234285;5551.972240138935;5625.606207565431;5611.579131565068;5625.971870140623;5643.068893632505;5643.604947653625;5645.266115405136;5476.753546090516;5526.557875370041;5540.473655002768;5544.611877168721;5547.306586676666;5543.2793736815565;5588.944110559005;5602.719864006865;5606.579506536945;5669.610945318424;5581.218629391969;5588.720563971867;5590.189422533791;5591.27313449249;5592.5525957922455;5433.592480563894;5629.380768115422;5672.971635141963;5677.148427735872;5685;5313.574914006793;5458.672479651074;5530.225768392509;5538.861528461821;5592.217089607377 01:34:22 INFO - PROCESS | 23755 | |1;ext.html;550.7173942768769;584.1967294969417;601.4707057789145;609.4423602403798;625.4902254662453;32663.1834204145;43714;46318.53681463189;47765.76117119418;47932.28101578479;45456.545434545726;45902;46000.769996149975;46089.308660370036;46148.769256153755;8045.235702608243;8929.95535022324;9085.454872707625;9090.181883630472;10753.526688861566;10498.842517362207;10910.072643825244;10918.290311129751;10924.945375273113;10929.508172132233;10962.890371096306;11014.173936954721;11015.228933974626;11024.44877756113;11026.724331891726;10887.455627218607;10910;10950.726231844188;10959.520464737237;10963.287386319904;10651.201159913038;10787.946060269649;10793.730156746065;10798.73003174919;10798.946005269923;43349.349759753626;43886.34170487445;43889;43911.56088439139;43951;3595.334863050344;3817.923641527154;3914.7455415398194;4014.6220729814245;4176.206520761076;32575;32951.83524082388;33020.17449563756;33106.6689333106;33275.6672433275;3988.481497405319;4087.97956010221;4103.651189648884;4115.115250221217;4156.418101465797;378494;379762.1368687858;380079.09960450296;381818;382160;59829.401705983255;60502.69748651228;60561.09158362628;60658.69670651619;60677.39322606806;62684.37315626877;62832.37167628265;62860.68569657122;62863;62874;16913.154342282836;17582.296708131787;17595.384161554284;17614.917389456794;17630.030348330965;13554.59336219915;13634.659133521742;13646.385912633757;13646.727065458834;13648.590542283748;16434.342626295052;16717;16752.748708769377;16788.58028549296;16905.915470422817;5905.409459054146;5961.940380596226;6027.969860150672;6033.396660333931;6167.290761562388;954.6467806911487;983.0022527134963;1001.1940387987739;1050.9632162874263;1087.2280680716622;30547.694523054928;30789.38421231563;31317.843410783255;31496;32570;1075.44077079918;1083.8049151152868;1099.2854644480992;1108.6895669212633;1118.7246538945608;15826.525204243891;16509.422170223985;16663.25015374311;16703.582410439838;16916;515433;518568;520476;520946;521503;59036;59462.70268648716;59479;59484;59500.40499595035;1215.6231568213882;1288.1369482446596;1343.6439343573961;1348.4201793229006;1358.4362489566918;31300.530492043094;33998.49002265018;34013.319733605684;34049.31901362009;34508.30983380268;50192.498075020245;54823.53294101695;56617.71691141683;56659.150112749165;61008;447908;449157;449626;450040;450331;25318;27833.582496262166;28872.422551549273;29008.854955725088;29511;47957;47993.5200648003;48143.75928120337;48186.75906620585;48478.27282590693;40706.38940415837;40729;40889;41438.58561414347;42906.71279861609;40928.18143637051;42100.36849447322;42459.787701061294;42549.57450425456;42696.78651606722;40862.59137408588;40898.79550602228;41012.384814228404;41315.1736965265;41593.79203103965;28017;29650.406991860473;29935.850320748257;30525.847370763004;30669.23326916845;27774.72225277802;28091.552938368503;28198.29504262328;28276.52951271105;28399.526987912923 01:34:22 INFO - PROCESS | 23755 | |2;jquery.html;385.6163117697891;393.2174971806105;405.5863019719887;414.4053283538122;423.11779938827544;12600.345899689102;18498.90750546247;20385.28651497198;20400.693989590167;20450.590988180247;19473.805261947375;19641;19654.410367688994;19773.901130494363;20165.495862603442;5822.767089316433;5990.400959904018;5993.520518358543;5999.5200383969395;6022.2773267207995;5848.385919478457;5934.139549765281;5938.940610593903;5939.7921072762465;5941.821745347646;4310.698251122422;4939.654224204307;6586.93413065868;6675;6780.118584583997;6689.364510371532;6689.531732778707;6749.493787965897;6753.561018533805;6779.72881084756;5767.106098554731;5901.8819623607715;5905.704714764244;5908.586398952075;5910.1430292607765;23115.306540803802;23810.880945595334;23819.928103235397;24121.155759548506;24229.273121806196;2349.6710460535537;2372.5254949010096;2505.8997640094376;2635.262126604554;2686.543287641086;14219.644508887257;14415.78376324356;14473;14751.852481475154;14904.776428353583;1798.4784412520353;1911.5603411215498;1919.7504324437734;1925.0932979521704;1998.3005947918257;59776.70111649457;75723.24276757217;77557;78476;87034;9962.850557241647;10250.538725757358;10287.22845786569;10864.348139111678;11098.833517497244;15220;15258.618534536503;15268.389264429521;15270.38918443272;15289.311980960883;8200.220979006985;8366.790830229293;8511.404201705822;8640.179182977732;8672.219500245008;8250.174982501701;8284.295834854105;8306.70926517569;8455.788605284793;8513.616887240089;6504.252011018699;6605;6607.768728094494;6644.60132392058;6721.294264102296;1837.3477415517566;1935.4967708395918;1956.3371134535814;2008.658528050235;2014.476236178604;908.68650315641;1014.2899970021015;1035.3787727363522;1042.2027149230844;1066.6853278282924;19589.706154407697;19945.401637950883;20049.398518044483;20085.799142008684;20540.383788486368;988.9109980101825;1008.8587597736323;1015.3349556040805;1049.6955882793904;1061.1775873697945;10539.578416863393;11541.134414918915;11553.942230288794;11566.942165289289;11635.650930472098;443828;453134;457906;464909;466255;84447.15552844516;85626;86473;88225.55887220651;89586;1012.089119792193;1030.907218350337;1062.8193207154802;1063.7553362726462;1068.3322923173016;16440.177991100638;17021.23404446778;17593.384231551834;17703.645927081645;17877.463676089737;31255.53116703206;38749;39422.40866386949;40399.7980010098;41282.38076428916;52958;53090.46909530855;53618.463815362906;53858.46141538535;55137.72431137819;8440.788980275542;8550.871736923827;8580.785480363042;8622.223999839794;8978.012418634076;12758.234505929673;13000.934995324962;13096.803547946598;13129.146605470614;13145.408456619283;10717;10731.195160362848;10875.456227188768;11081.11351091927;11427.714307142056;11641.59254426091;11673.241239319417;11877;12038.337891416057;12433.502659893504;10535.262531622713;11016.394098324668;11102.44487775592;11107.611233606785;11181.608643697431;8557.61490732906;8710.782230444289;8713;8763.737087887373;8831.735047948572;10521.4739263035;10804.783904321712;10949.616763413242;11144.888551114385;11273.154513411362 01:34:22 INFO - PROCESS | 23755 | |3;mootools.html;1306.3729409883263;1499.1529785671096;1513.046780528267;1650.9504714858551;1823.5930466167802;8683.739487815368;9066.954665226673;9070.818583628323;9187.03536128707;9263.583138758755;8996.505192214441;9081.500517471537;9099.499527525983;9110.954445227764;9111.908880911189;2391.7249516305587;2412.9034838606435;2414.8430352027062;2415.6376543518527;2430.6718592989896;2381.261808839257;2394.8802559872024;2433.6957880264968;2439.6828412306463;2441.3652450362943;2394.616861302192;2420.1529464687305;2425.0421083672027;2426.1872272788637;2426.502566973775;2362.574736547421;2369.9289021329387;2381.38084098134;2388.36708272308;2397.2448678666165;2303.1248125712123;2323.604987152188;2338.590746619335;2340.847844890077;2343.777341152605;9055.094490550959;9107.408018478844;9172.633094676205;9173.633054677804;9174.954125229398;5214.243934629495;5437;5450.482204190595;5455.536279416255;5465.6447330923365;3562.4656301554714;3589.1206654369585;3622.4099768665533;3622.8913132606062;3635.654612811779;1159.4173952554786;1168.8305195746655;1182.408795602199;1194.8685644579089;1195.9222650527774;13034.087613867034;13087.607371778862;13110.082294239312;13124.343782810822;13131.34343282832;4346.47842258931;4476.932846007313;4608.423947006624;4629.699069560468;4648.628109751212;9977.002299769965;9978.451185184884;9985.850212246676;9987.550560224807;10023.548940297704;5398.379186393615;5403.729813509309;5404.702741349185;5408.0535906216255;5420.945790542123;4402.823887044482;4497.190505709003;4625.699329543569;4696.295555666609;5066.08810414129;3881.146147847469;3931.9606803932165;3983.24318379507;4028.8992775180855;4038.5153781545814;1171.1743221029153;1171.6426489920486;1186.463529729003;1193.9701507462382;1198.3648666206925;914.6387177065155;937.7702462896634;943.9669611563562;955.5795450001976;955.8338826631638;5767.653940763484;5819.098039803837;5852.502537284294;6100.115483254903;6113.358097399798;440.41204991336286;441.492283873548;442.69896470399794;444.0874003921907;453.9909201815945;3875.244327356195;4062.1875624875142;4096.09885825118;4112.424260603518;4149.170165966819;11071.232191952013;11334.773304533863;11433.371164586028;11474.139439542068;11475.139364547693;11360.943195283971;11384;11393.259438136494;11456;11462.025727813063;8354.206350396702;8384.413091083565;8430.367722420842;8479.19447652472;8500.362472814688;5929.377415371324;5957.046872500379;6029.487493563096;6171.691415429301;6306.432421082125;10258.897411025795;10446.947765261126;10583.259171857895;10601.204909631655;10745.623903163352;9701.272404569545;10551.894481055399;10653.520591573553;10706.73233169177;10708.89291107079;7867.9606601966625;7941.880871786812;8079;8276.213759692819;8286.295664868554;8820.470771753715;8848.46909185451;8952.820943581222;8975.461472311683;9047.954760226157;8152.3478121751305;8166.265036146775;8190.262876341158;8202.507849529047;8339.332853371594;7847.176046515034;8039.075506316848;8059.073206581318;8097.757067287991;8303.875441868498;6995.405390541861;7114.928850711427;7133.785986420416;7137.357637812622;7137.714491420493;4984.501549844987;5073.001732244494;5114.414531495248;5201.479852014768;5285.260063591023;5092.923606145837;5183.300254465599;5217.791288348423;5220.425753167225;5251.632385732962 01:34:22 INFO - PROCESS | 23755 | |4;prototype.html;320.53042293040704;337.41458569382127;338.6478062814673;338.7577881814503;345.25252827628185;5182.093133701596;5347.7326133693305;5360.490753378433;5413.4045255021965;5435.755391007404;5354.866128346794;5401.189821526773;5407.945920540794;5413.648112872661;5431.945680543194;4190.434291370671;4198.874033778991;4208.852690155845;4224.091820258644;4253.468316460442;4105.384192371153;4229.344451610005;4230.407742915994;4235.322348424253;4235.512916014652;4200.264953633117;4200.810963506649;4207.368894665794;4214.473190851144;4218.346156345771;4206.831726730927;4216.072464057917;4230.428892099589;4238.131183107486;4238.639715624176;3581.5494724636565;3675.8529658813613;3676.2647470506004;3698.9445158322646;3767.1147280389087;5235.005348983681;5241.266222728821;5261.316028916216;5263.894722105537;5294.179402192666;4789.664723469358;4792.568668819823;4797.784099715486;4805.1590971579835;4811.879703007453;5452.700101494416;5588.497035266845;5614.691191984439;5624.971875140639;5670.5747068969995;4020.0954785173394;4023.6982226333143;4026.6577340926056;4033.1530378620523;4051.675865930748;5875.265591801025;5885.470307672287;5936.673482958392;5941.940580594225;5948.107783832416;4059.7361171523758;4079.0618157823865;4116.1767646470225;4123.299039163349;4123.773192474443;4160.687948403882;4173.770442625624;4181.142865712522;4181.707280490397;4208.494980602286;4769.928451073237;4811.302361157683;4816.831410900601;4825.638077144158;4836.95163048365;5429.972850135803;5654.434556544313;5660.8867822643315;5734.741936612862;5744;1358.9252591107386;1384.4185442114335;1406.7397531456713;1437.7771445425976;1439.5969128643997;940.83535381308;974.9129720361888;984.601236499219;995.4624502768569;999.6501224571342;5427.0773968425465;5431.891362172734;5433.619646624698;5445.856370162231;5448.346198456211;5705.942940570624;5722.427757224327;5742.799002034909;5760.971195143998;5784.698442850409;5294.88807350453;5399.217113518518;5411.702356370359;5417.918731219035;5432.972835135799;2676.6118912757543;2687.986560067187;2691.5558932776266;2699;2758.7103354147926;5174.870628234325;5180.274761533388;5218.243354713622;5260.553347829377;5267.789288428497;4865.659403841697;5103.948960510347;5139.691618502902;5148.227765835155;5217.243499692523;5153.788859617986;5163.741812909339;5172.250023746611;5172.301739265226;5196.532312091778;5056.306137443998;5060.569851562658;5069.290299358019;5083.872903177302;5158.916627508265;4767.6424268179335;4971.453140154508;5020.598352131894;5030.974845125751;5047.747612619428;5157.638965272545;5160.48395160496;5174.844754657366;5176;5183.533481986488;3735.327641024533;3736.8505259789304;3744.839099879046;3745.7003439724213;3755.1224387806096;4903.010829465735;4943.30793688891;5010.874728131826;5036.798528058983;5528.778848846162;5472.781088756406;5562.582806289625;5565.526930210977;5591.4688104629995;5630.634008789416;5583.330000399978;5608.775648973995;5656.151577263443;5684.971575142098;5706.630408701964;5309.707966061749;5590.357108932525;5604.299462567179;5604.775808967595;5650.830475085754;5686.658800471985;5688.488036076773;5720.570957178145;5722.8569285766525;5733.283339582552;5450.591205659671;5563.693996830213;5585.357683866408;5615.887682246251;5616.157576363578 01:34:22 INFO - PROCESS | 23755 | |5;yui.html;722.7181399254292;728.6611725547621;741.8590467811119;759.8290384663452;817.2032268538172;15607;17024.744628830562;17060.317587296526;17078.316867325324;17084.743728844063;15280.617984550365;15307.928445008825;15368.231588420562;15432.69134617307;15454.150021748828;653.0432915778383;780.5979920341028;804.4569915307173;809.348474478045;809.5830647216688;800.5836964778312;801.1267718187174;808.9110197878229;809.8056466448069;810.1533897077568;697.0450482838519;859.7549698335976;866.1425189062845;866.2333834556431;866.5840396609631;827.93790465715;835.2482765511028;844.5861527851339;848.5248260973813;849.4096602861014;615.9076138579204;616.1343312645695;616.1897105306508;616.374380004296;616.7163104971719;13772.793408098887;13776.311184440838;13803.861961380459;13824.308784560732;13846.446142154302;14134.505292314823;14179.929100354535;14332.42670293187;14488.565343039725;14488.782668259879;12081.335526546036;12096.33470159141;12285.262884226886;12312.199707019015;12625;3780.6956599972964;3785.4511095891216;3858.9807050964564;4339.132173565299;4455.688101832873;71473.64263178704;71674.2832571678;71902;72035.63982180055;73752.26247737507;10920.945395272973;10941.23411361213;11057.834132488018;11171.944140279247;11834.573912347949;18235.908820455814;18413.90793046053;18525.90737046333;18565.35021274249;18566.257349705862;8712.346574006848;8970.730878073668;8993.865092023625;9035.051319611477;9145.679901203559;4810.687305325143;4942.135126352874;4979.800807967713;4985.725785081783;5119.180931051014;3392.49112633107;3537.8407971641336;3538.840752166159;3539.4690796380232;3540.8760693376125;566.60620868496;623.2147494157394;623.6102435977514;624.9125122482856;651.726274964516;681.6387314723205;718.2207305074053;733.318014246745;733.4132693844903;765.8698021336386;9856.901430985741;10331.431771252654;10543.311455578778;10579.100776433936;10832.40421776794;642.6818724731288;698.7379732600275;720.0962791696387;746.4923851780733;758.719273868661;4572.679912406166;4840.298156767249;4849.2726091086615;4859.2954021666665;4863.80544778212;123694;125327;125428;125944.85137871443;129478;108300;108512;110014.44992775146;110234.44882775535;111219;89355;89988;91406.54296728474;94638.99999999862;94701.52649236847;9209.539523023688;9272.907270927204;9348.3456158071;9464.716058518256;9520.666776662783;41573;44000;45372.77313613411;45879;57318.14022789745;72222.63888680523;73502.48731345277;74121.6293918527;74898;77058.6147069261;12263.141580089665;12379.814302785286;12380.380980951097;12512.186707863959;12559.434825432694;14609.415623374945;14649.26753662334;14977.850221497645;15042.548723538312;15672.764908526153;11909.344986025852;12578.182418142791;14365.13809171453;14959.626009349853;15367.61580960485;12579.93710031444;12983;13356.599302020955;14022.929885350508;14100.858991409954;13273.469061237442;13491.25798081115;13968.930155349159;14348.85651143517;14414.97285570042;11068.493150684932;11197.552097915992;11307.265027773168;11929.642110736691;11962.940185299365;11416.31502109876;11760.58837940668;11793.582553590275;12216;12217.69455763613 01:34:22 INFO - PROCESS | 23755 | __end_tp_report 01:34:22 INFO - PROCESS | 23755 | __start_cc_report 01:34:22 INFO - PROCESS | 23755 | _x_x_mozilla_cycle_collect,995 01:34:22 INFO - PROCESS | 23755 | __end_cc_report 01:34:22 INFO - PROCESS | 23755 | __startTimestamp1472200462161__endTimestamp 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | ------- Summary: start ------- 01:34:22 INFO - PROCESS | 23755 | Number of tests: 6 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | [#0] dojo.html Cycles:165 Average:5290.46 Median:5526.56 stddev:599.71 (10.9%) stddev-sans-first:574.98 01:34:22 INFO - PROCESS | 23755 | Values: 3040.4 3048.6 3058.8 3095.6 3111.9 5223.0 5253.3 5281.3 5302.7 5368.0 5252.1 5329.8 5339.6 5354.3 5455.2 4788.8 4845.8 4887.5 4891.0 5010.1 4909.3 4929.6 4954.3 4971.2 5003.7 4664.0 4746.3 4750.3 4762.4 4897.0 4718.9 4727.0 4743.3 4801.8 4857.9 4752.6 4761.9 4780.1 4786.6 4831.5 5072.1 5156.3 5207.1 5276.7 5299.6 5614.9 5615.5 5640.6 5646.2 5647.9 5597.2 5673.0 5678.2 5692.5 5695.3 5261.7 5301.8 5310.8 5360.6 5378.8 5809.7 5810.4 5811.2 5813.2 5877.8 5479.3 5591.4 5599.6 5603.1 5604.4 5666.9 5741.5 5746.4 5775.7 5839.2 5699.7 5714.7 5720.7 5722.9 5723.4 5514.7 5690.8 5750.2 5755.1 5771.5 4368.2 4392.9 4414.2 4418.9 4432.0 3908.7 3925.8 3929.4 3974.5 3976.9 5301.8 5431.8 5435.5 5436.9 5449.2 5600.7 5643.5 5655.0 5663.3 5738.4 5354.2 5362.5 5376.2 5378.9 5383.0 4803.6 4929.5 4936.5 4940.7 5008.6 5857.2 5922.6 5923.2 5962.8 6038.5 5735.4 5752.1 5757.4 5759.2 5781.1 5720.9 5954.9 5957.1 5961.0 5961.1 5516.8 5527.8 5547.8 5552.0 5625.6 5611.6 5626.0 5643.1 5643.6 5645.3 5476.8 5526.6 5540.5 5544.6 5547.3 5543.3 5588.9 5602.7 5606.6 5669.6 5581.2 5588.7 5590.2 5591.3 5592.6 5433.6 5629.4 5673.0 5677.1 5685.0 5313.6 5458.7 5530.2 5538.9 5592.2 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | [#1] ext.html Cycles:180 Average:61471.14 Median:381989.00 stddev:120001.11 (31.4%) stddev-sans-first:120250.57 01:34:22 INFO - PROCESS | 23755 | Values: 550.7 584.2 601.5 609.4 625.5 32663.2 43714.0 46318.5 47765.8 47932.3 45456.5 45902.0 46000.8 46089.3 46148.8 8045.2 8930.0 9085.5 9090.2 10753.5 10498.8 10910.1 10918.3 10924.9 10929.5 10962.9 11014.2 11015.2 11024.4 11026.7 10887.5 10910.0 10950.7 10959.5 10963.3 10651.2 10787.9 10793.7 10798.7 10798.9 43349.3 43886.3 43889.0 43911.6 43951.0 3595.3 3817.9 3914.7 4014.6 4176.2 32575.0 32951.8 33020.2 33106.7 33275.7 3988.5 4088.0 4103.7 4115.1 4156.4 378494.0 379762.1 380079.1 381818.0 382160.0 59829.4 60502.7 60561.1 60658.7 60677.4 62684.4 62832.4 62860.7 62863.0 62874.0 16913.2 17582.3 17595.4 17614.9 17630.0 13554.6 13634.7 13646.4 13646.7 13648.6 16434.3 16717.0 16752.7 16788.6 16905.9 5905.4 5961.9 6028.0 6033.4 6167.3 954.6 983.0 1001.2 1051.0 1087.2 30547.7 30789.4 31317.8 31496.0 32570.0 1075.4 1083.8 1099.3 1108.7 1118.7 15826.5 16509.4 16663.3 16703.6 16916.0 515433.0 518568.0 520476.0 520946.0 521503.0 59036.0 59462.7 59479.0 59484.0 59500.4 1215.6 1288.1 1343.6 1348.4 1358.4 31300.5 33998.5 34013.3 34049.3 34508.3 50192.5 54823.5 56617.7 56659.2 61008.0 447908.0 449157.0 449626.0 450040.0 450331.0 25318.0 27833.6 28872.4 29008.9 29511.0 47957.0 47993.5 48143.8 48186.8 48478.3 40706.4 40729.0 40889.0 41438.6 42906.7 40928.2 42100.4 42459.8 42549.6 42696.8 40862.6 40898.8 41012.4 41315.2 41593.8 28017.0 29650.4 29935.9 30525.8 30669.2 27774.7 28091.6 28198.3 28276.5 28399.5 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | [#2] jquery.html Cycles:180 Average:27824.85 Median:23815.40 stddev:75276.47 (316.1%) stddev-sans-first:75459.44 01:34:22 INFO - PROCESS | 23755 | Values: 385.6 393.2 405.6 414.4 423.1 12600.3 18498.9 20385.3 20400.7 20450.6 19473.8 19641.0 19654.4 19773.9 20165.5 5822.8 5990.4 5993.5 5999.5 6022.3 5848.4 5934.1 5938.9 5939.8 5941.8 4310.7 4939.7 6586.9 6675.0 6780.1 6689.4 6689.5 6749.5 6753.6 6779.7 5767.1 5901.9 5905.7 5908.6 5910.1 23115.3 23810.9 23819.9 24121.2 24229.3 2349.7 2372.5 2505.9 2635.3 2686.5 14219.6 14415.8 14473.0 14751.9 14904.8 1798.5 1911.6 1919.8 1925.1 1998.3 59776.7 75723.2 77557.0 78476.0 87034.0 9962.9 10250.5 10287.2 10864.3 11098.8 15220.0 15258.6 15268.4 15270.4 15289.3 8200.2 8366.8 8511.4 8640.2 8672.2 8250.2 8284.3 8306.7 8455.8 8513.6 6504.3 6605.0 6607.8 6644.6 6721.3 1837.3 1935.5 1956.3 2008.7 2014.5 908.7 1014.3 1035.4 1042.2 1066.7 19589.7 19945.4 20049.4 20085.8 20540.4 988.9 1008.9 1015.3 1049.7 1061.2 10539.6 11541.1 11553.9 11566.9 11635.7 443828.0 453134.0 457906.0 464909.0 466255.0 84447.2 85626.0 86473.0 88225.6 89586.0 1012.1 1030.9 1062.8 1063.8 1068.3 16440.2 17021.2 17593.4 17703.6 17877.5 31255.5 38749.0 39422.4 40399.8 41282.4 52958.0 53090.5 53618.5 53858.5 55137.7 8440.8 8550.9 8580.8 8622.2 8978.0 12758.2 13000.9 13096.8 13129.1 13145.4 10717.0 10731.2 10875.5 11081.1 11427.7 11641.6 11673.2 11877.0 12038.3 12433.5 10535.3 11016.4 11102.4 11107.6 11181.6 8557.6 8710.8 8713.0 8763.7 8831.7 10521.5 10804.8 10949.6 11144.9 11273.2 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | [#3] mootools.html Cycles:180 Average:5950.15 Median:4627.70 stddev:3467.14 (74.9%) stddev-sans-first:3459.30 01:34:22 INFO - PROCESS | 23755 | Values: 1306.4 1499.2 1513.0 1651.0 1823.6 8683.7 9067.0 9070.8 9187.0 9263.6 8996.5 9081.5 9099.5 9111.0 9111.9 2391.7 2412.9 2414.8 2415.6 2430.7 2381.3 2394.9 2433.7 2439.7 2441.4 2394.6 2420.2 2425.0 2426.2 2426.5 2362.6 2369.9 2381.4 2388.4 2397.2 2303.1 2323.6 2338.6 2340.8 2343.8 9055.1 9107.4 9172.6 9173.6 9175.0 5214.2 5437.0 5450.5 5455.5 5465.6 3562.5 3589.1 3622.4 3622.9 3635.7 1159.4 1168.8 1182.4 1194.9 1195.9 13034.1 13087.6 13110.1 13124.3 13131.3 4346.5 4476.9 4608.4 4629.7 4648.6 9977.0 9978.5 9985.9 9987.6 10023.5 5398.4 5403.7 5404.7 5408.1 5420.9 4402.8 4497.2 4625.7 4696.3 5066.1 3881.1 3932.0 3983.2 4028.9 4038.5 1171.2 1171.6 1186.5 1194.0 1198.4 914.6 937.8 944.0 955.6 955.8 5767.7 5819.1 5852.5 6100.1 6113.4 440.4 441.5 442.7 444.1 454.0 3875.2 4062.2 4096.1 4112.4 4149.2 11071.2 11334.8 11433.4 11474.1 11475.1 11360.9 11384.0 11393.3 11456.0 11462.0 8354.2 8384.4 8430.4 8479.2 8500.4 5929.4 5957.0 6029.5 6171.7 6306.4 10258.9 10446.9 10583.3 10601.2 10745.6 9701.3 10551.9 10653.5 10706.7 10708.9 7868.0 7941.9 8079.0 8276.2 8286.3 8820.5 8848.5 8952.8 8975.5 9048.0 8152.3 8166.3 8190.3 8202.5 8339.3 7847.2 8039.1 8059.1 8097.8 8303.9 6995.4 7114.9 7133.8 7137.4 7137.7 4984.5 5073.0 5114.4 5201.5 5285.3 5092.9 5183.3 5217.8 5220.4 5251.6 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | [#4] prototype.html Cycles:180 Average:4588.84 Median:5162.11 stddev:1329.89 (25.8%) stddev-sans-first:1294.46 01:34:22 INFO - PROCESS | 23755 | Values: 320.5 337.4 338.6 338.8 345.3 5182.1 5347.7 5360.5 5413.4 5435.8 5354.9 5401.2 5407.9 5413.6 5431.9 4190.4 4198.9 4208.9 4224.1 4253.5 4105.4 4229.3 4230.4 4235.3 4235.5 4200.3 4200.8 4207.4 4214.5 4218.3 4206.8 4216.1 4230.4 4238.1 4238.6 3581.5 3675.9 3676.3 3698.9 3767.1 5235.0 5241.3 5261.3 5263.9 5294.2 4789.7 4792.6 4797.8 4805.2 4811.9 5452.7 5588.5 5614.7 5625.0 5670.6 4020.1 4023.7 4026.7 4033.2 4051.7 5875.3 5885.5 5936.7 5941.9 5948.1 4059.7 4079.1 4116.2 4123.3 4123.8 4160.7 4173.8 4181.1 4181.7 4208.5 4769.9 4811.3 4816.8 4825.6 4837.0 5430.0 5654.4 5660.9 5734.7 5744.0 1358.9 1384.4 1406.7 1437.8 1439.6 940.8 974.9 984.6 995.5 999.7 5427.1 5431.9 5433.6 5445.9 5448.3 5705.9 5722.4 5742.8 5761.0 5784.7 5294.9 5399.2 5411.7 5417.9 5433.0 2676.6 2688.0 2691.6 2699.0 2758.7 5174.9 5180.3 5218.2 5260.6 5267.8 4865.7 5103.9 5139.7 5148.2 5217.2 5153.8 5163.7 5172.3 5172.3 5196.5 5056.3 5060.6 5069.3 5083.9 5158.9 4767.6 4971.5 5020.6 5031.0 5047.7 5157.6 5160.5 5174.8 5176.0 5183.5 3735.3 3736.9 3744.8 3745.7 3755.1 4903.0 4943.3 5010.9 5036.8 5528.8 5472.8 5562.6 5565.5 5591.5 5630.6 5583.3 5608.8 5656.2 5685.0 5706.6 5309.7 5590.4 5604.3 5604.8 5650.8 5686.7 5688.5 5720.6 5722.9 5733.3 5450.6 5563.7 5585.4 5615.9 5616.2 01:34:22 INFO - PROCESS | 23755 | 01:34:22 INFO - PROCESS | 23755 | [#5] yui.html Cycles:180 Average:21330.24 Median:3822.22 stddev:31739.18 (830.4%) stddev-sans-first:31790.50 01:34:22 INFO - PROCESS | 23755 | Values: 722.7 728.7 741.9 759.8 817.2 15607.0 17024.7 17060.3 17078.3 17084.7 15280.6 15307.9 15368.2 15432.7 15454.2 653.0 780.6 804.5 809.3 809.6 800.6 801.1 808.9 809.8 810.2 697.0 859.8 866.1 866.2 866.6 827.9 835.2 844.6 848.5 849.4 615.9 616.1 616.2 616.4 616.7 13772.8 13776.3 13803.9 13824.3 13846.4 14134.5 14179.9 14332.4 14488.6 14488.8 12081.3 12096.3 12285.3 12312.2 12625.0 3780.7 3785.5 3859.0 4339.1 4455.7 71473.6 71674.3 71902.0 72035.6 73752.3 10920.9 10941.2 11057.8 11171.9 11834.6 18235.9 18413.9 18525.9 18565.4 18566.3 8712.3 8970.7 8993.9 9035.1 9145.7 4810.7 4942.1 4979.8 4985.7 5119.2 3392.5 3537.8 3538.8 3539.5 3540.9 566.6 623.2 623.6 624.9 651.7 681.6 718.2 733.3 733.4 765.9 9856.9 10331.4 10543.3 10579.1 10832.4 642.7 698.7 720.1 746.5 758.7 4572.7 4840.3 4849.3 4859.3 4863.8 123694.0 125327.0 125428.0 125944.9 129478.0 108300.0 108512.0 110014.4 110234.4 111219.0 89355.0 89988.0 91406.5 94639.0 94701.5 9209.5 9272.9 9348.3 9464.7 9520.7 41573.0 44000.0 45372.8 45879.0 57318.1 72222.6 73502.5 74121.6 74898.0 77058.6 12263.1 12379.8 12380.4 12512.2 12559.4 14609.4 14649.3 14977.9 15042.5 15672.8 11909.3 12578.2 14365.1 14959.6 15367.6 12579.9 12983.0 13356.6 14022.9 14100.9 13273.5 13491.3 13968.9 14348.9 14415.0 11068.5 11197.6 11307.3 11929.6 11962.9 11416.3 11760.6 11793.6 12216.0 12217.7 01:34:22 INFO - PROCESS | 23755 | -------- Summary: end -------- 01:34:22 INFO - PROCESS | 23755 | 01:34:23 INFO - TEST-INFO | 23755: exit 0 01:34:23 INFO - TEST-OK | dromaeo_css | took 1116331ms 01:34:23 INFO - TEST-START | kraken 01:34:23 INFO - operating with platform_type : linux_ 01:34:23 INFO - Initialising browser for kraken test... 01:34:23 INFO - TEST-INFO | started process 24067 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdfZlPE/profile http://localhost:50791/getInfo.html) 01:34:27 INFO - PROCESS | 24067 | __metrics Screen width/height:1600/1200 01:34:27 INFO - PROCESS | 24067 | colorDepth:24 01:34:27 INFO - PROCESS | 24067 | Browser inner width/height: 1024/697 01:34:27 INFO - PROCESS | 24067 | __metrics 01:34:27 INFO - PROCESS | 24067 | 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" 01:34:27 INFO - PROCESS | 24067 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:34:29 INFO - TEST-INFO | 24067: exit 0 01:34:29 INFO - Browser initialized. 01:34:29 INFO - Running cycle 1/1 for kraken test... 01:34:29 INFO - TEST-INFO | started process 24150 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdfZlPE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:34:41 INFO - PROCESS | 24150 | RSS: Main: 178708480 01:34:41 INFO - PROCESS | 24150 | 01:34:43 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50791/tests/kraken/driver.html?testName=audio-beat-detection) 01:34:44 INFO - PROCESS | 24150 | RSS: Main: 241176576 01:34:44 INFO - PROCESS | 24150 | 01:34:47 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50791/tests/kraken/driver.html?testName=audio-dft) 01:34:47 INFO - PROCESS | 24150 | RSS: Main: 223514624 01:34:47 INFO - PROCESS | 24150 | 01:34:50 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50791/tests/kraken/driver.html?testName=audio-fft) 01:34:51 INFO - PROCESS | 24150 | RSS: Main: 301182976 01:34:51 INFO - PROCESS | 24150 | 01:34:53 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50791/tests/kraken/driver.html?testName=audio-oscillator) 01:34:54 INFO - PROCESS | 24150 | RSS: Main: 210800640 01:34:54 INFO - PROCESS | 24150 | 01:34:56 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50791/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:34:56 INFO - PROCESS | 24150 | RSS: Main: 214126592 01:34:56 INFO - PROCESS | 24150 | 01:34:59 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50791/tests/kraken/driver.html?testName=imaging-darkroom) 01:35:00 INFO - PROCESS | 24150 | RSS: Main: 253284352 01:35:00 INFO - PROCESS | 24150 | 01:35:03 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50791/tests/kraken/driver.html?testName=imaging-desaturate) 01:35:04 INFO - PROCESS | 24150 | RSS: Main: 252452864 01:35:04 INFO - PROCESS | 24150 | 01:35:07 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50791/tests/kraken/driver.html?testName=json-parse-financial) 01:35:07 INFO - PROCESS | 24150 | RSS: Main: 252936192 01:35:07 INFO - PROCESS | 24150 | 01:35:09 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50791/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:35:09 INFO - PROCESS | 24150 | RSS: Main: 215015424 01:35:09 INFO - PROCESS | 24150 | 01:35:11 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:35:12 INFO - PROCESS | 24150 | RSS: Main: 222564352 01:35:12 INFO - PROCESS | 24150 | 01:35:14 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:35:15 INFO - PROCESS | 24150 | RSS: Main: 221577216 01:35:15 INFO - PROCESS | 24150 | 01:35:17 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:35:18 INFO - PROCESS | 24150 | RSS: Main: 222511104 01:35:18 INFO - PROCESS | 24150 | 01:35:20 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:35:21 INFO - PROCESS | 24150 | RSS: Main: 220483584 01:35:21 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | Cycle 1(1): loaded http://localhost:50791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50791/tests/kraken/driver.html?testName=ai-astar) 01:35:23 INFO - PROCESS | 24150 | RSS: Main: 271269888 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | __start_tp_report 01:35:23 INFO - PROCESS | 24150 | _x_x_mozilla_page_load 01:35:23 INFO - PROCESS | 24150 | _x_x_mozilla_page_load_details 01:35:23 INFO - PROCESS | 24150 | |i|pagename|runs| 01:35:23 INFO - PROCESS | 24150 | |0;ai-astar;110;111;125;114;111;113;112;111;111;111 01:35:23 INFO - PROCESS | 24150 | |1;audio-beat-detection;153;122;152;181;119;144;149;128;183;120 01:35:23 INFO - PROCESS | 24150 | |2;audio-dft;159;161;161;160;162;161;160;161;159;162 01:35:23 INFO - PROCESS | 24150 | |3;audio-fft;74;74;74;181;77;75;74;77;159;76 01:35:23 INFO - PROCESS | 24150 | |4;audio-oscillator;83;82;159;82;82;82;150;84;82;150 01:35:23 INFO - PROCESS | 24150 | |5;imaging-gaussian-blur;117;117;117;118;118;118;118;117;117;118 01:35:23 INFO - PROCESS | 24150 | |6;imaging-darkroom;146;146;146;146;145;148;146;146;145;145 01:35:23 INFO - PROCESS | 24150 | |7;imaging-desaturate;95;95;96;95;95;95;95;96;95;95 01:35:23 INFO - PROCESS | 24150 | |8;json-parse-financial;53;52;51;54;52;53;54;52;51;51 01:35:23 INFO - PROCESS | 24150 | |9;json-stringify-tinderbox;61;61;60;61;60;61;60;61;62;61 01:35:23 INFO - PROCESS | 24150 | |10;stanford-crypto-aes;79;79;79;79;85;78;78;86;79;80 01:35:23 INFO - PROCESS | 24150 | |11;stanford-crypto-ccm;125;125;130;125;122;132;122;124;183;121 01:35:23 INFO - PROCESS | 24150 | |12;stanford-crypto-pbkdf2;167;165;162;164;163;163;162;160;162;168 01:35:23 INFO - PROCESS | 24150 | |13;stanford-crypto-sha256-iterative;65;65;65;64;65;64;63;63;64;68 01:35:23 INFO - PROCESS | 24150 | __end_tp_report 01:35:23 INFO - PROCESS | 24150 | __start_cc_report 01:35:23 INFO - PROCESS | 24150 | _x_x_mozilla_cycle_collect,1677 01:35:23 INFO - PROCESS | 24150 | __end_cc_report 01:35:23 INFO - PROCESS | 24150 | __startTimestamp1472200523503__endTimestamp 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | ------- Summary: start ------- 01:35:23 INFO - PROCESS | 24150 | Number of tests: 14 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#0] ai-astar Cycles:10 Average:112.90 Median:111.50 stddev:4.41 (4.0%) stddev-sans-first:4.55 01:35:23 INFO - PROCESS | 24150 | Values: 110.0 111.0 125.0 114.0 111.0 113.0 112.0 111.0 111.0 111.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:150.50 stddev:23.54 (15.6%) stddev-sans-first:24.80 01:35:23 INFO - PROCESS | 24150 | Values: 153.0 122.0 152.0 181.0 119.0 144.0 149.0 128.0 183.0 120.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.07 (0.7%) stddev-sans-first:0.97 01:35:23 INFO - PROCESS | 24150 | Values: 159.0 161.0 161.0 160.0 162.0 161.0 160.0 161.0 159.0 162.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#3] audio-fft Cycles:10 Average:94.10 Median:74.50 stddev:40.36 (54.2%) stddev-sans-first:42.14 01:35:23 INFO - PROCESS | 24150 | Values: 74.0 74.0 74.0 181.0 77.0 75.0 74.0 77.0 159.0 76.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:82.00 stddev:34.18 (41.7%) stddev-sans-first:35.43 01:35:23 INFO - PROCESS | 24150 | Values: 83.0 82.0 159.0 82.0 82.0 82.0 150.0 84.0 82.0 150.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 01:35:23 INFO - PROCESS | 24150 | Values: 117.0 117.0 117.0 118.0 118.0 118.0 118.0 117.0 117.0 118.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 01:35:23 INFO - PROCESS | 24150 | Values: 146.0 146.0 146.0 146.0 145.0 148.0 146.0 146.0 145.0 145.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:35:23 INFO - PROCESS | 24150 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:1.16 (2.2%) stddev-sans-first:1.20 01:35:23 INFO - PROCESS | 24150 | Values: 53.0 52.0 51.0 54.0 52.0 53.0 54.0 52.0 51.0 51.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 01:35:23 INFO - PROCESS | 24150 | Values: 61.0 61.0 60.0 61.0 60.0 61.0 60.0 61.0 62.0 61.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:79.00 stddev:2.86 (3.6%) stddev-sans-first:3.00 01:35:23 INFO - PROCESS | 24150 | Values: 79.0 79.0 79.0 79.0 85.0 78.0 78.0 86.0 79.0 80.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#11] stanford-crypto-ccm Cycles:10 Average:130.90 Median:125.00 stddev:18.63 (14.9%) stddev-sans-first:19.64 01:35:23 INFO - PROCESS | 24150 | Values: 125.0 125.0 130.0 125.0 122.0 132.0 122.0 124.0 183.0 121.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.60 Median:163.50 stddev:2.46 (1.5%) stddev-sans-first:2.28 01:35:23 INFO - PROCESS | 24150 | Values: 167.0 165.0 162.0 164.0 163.0 163.0 162.0 160.0 162.0 168.0 01:35:23 INFO - PROCESS | 24150 | 01:35:23 INFO - PROCESS | 24150 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:1.43 (2.2%) stddev-sans-first:1.51 01:35:23 INFO - PROCESS | 24150 | Values: 65.0 65.0 65.0 64.0 65.0 64.0 63.0 63.0 64.0 68.0 01:35:23 INFO - PROCESS | 24150 | -------- Summary: end -------- 01:35:23 INFO - PROCESS | 24150 | 01:35:24 INFO - TEST-INFO | 24150: exit 0 01:35:24 INFO - TEST-OK | kraken | took 61745ms 01:35:24 INFO - SUITE-END | took 1178s 01:35:25 INFO - Completed test suite (00:19:38) 01:35:25 INFO - javascript benchmark 01:35:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3040.3615240799436, 3048.6494053183883, 3058.770592205585, 3095.5511450839645, 3111.937761244775, 5222.973885130574, 5253.3170687810625, 5281.339832520935, 5302.681839089652, 5368.00691872003, 5252.133397989327, 5329.813456529022, 5339.599530035253, 5354.330708661418, 5455.208994695777, 4788.784504697297, 4845.830395936143, 4887.535684110004, 4891.0, 5010.073136469747, 4909.337239472661, 4929.556339929406, 4954.3063971044085, 4971.229459433794, 5003.699778013331, 4663.953360466419, 4746.264329029023, 4750.311204875274, 4762.404699412574, 4897.04507621014, 4718.9292160617615, 4726.976365118152, 4743.264793956942, 4801.759912004386, 4857.854264372075, 4752.572268495852, 4761.857144285677, 4780.139574876521, 4786.641001924835, 4831.541003604652, 5072.0870243356185, 5156.329677141947, 5207.140821764404, 5276.736163191825, 5299.6025298102795, 5614.9157762633595, 5615.494605485526, 5640.605157638967, 5646.237757902672, 5647.915281270784, 5597.188407680904, 5672.971635141839, 5678.205051292823, 5692.544596432275, 5695.288088988877, 5261.657992230493, 5301.840944771663, 5310.840674779724, 5360.597955153379, 5378.838634840961, 5809.68046757424, 5810.448007439287, 5811.215485909433, 5813.157092221689, 5877.764889404376, 5479.315085614298, 5591.384947655754, 5599.60802743804, 5603.075492543766, 5604.3835178130375, 5666.858328541819, 5741.540676745851, 5746.396628353963, 5775.682337471395, 5839.153322768259, 5699.658020518782, 5714.657120572779, 5720.713964301768, 5722.914156287659, 5723.399043100498, 5514.6691198528215, 5690.77236910519, 5750.223719797774, 5755.107958266475, 5771.509421699118, 4368.170047690928, 4392.868213953587, 4414.1833760753725, 4418.867433976986, 4431.977840110779, 3908.7068469864876, 3925.823337949799, 3929.3713005918917, 3974.5429275633674, 3976.900577485586, 5301.761420736076, 5431.782728690808, 5435.483629055233, 5436.918446223231, 5449.1826226066405, 5600.691961942132, 5643.52030077448, 5655.0386434306265, 5663.32040155184, 5738.426157384228, 5354.223637572531, 5362.463753624606, 5376.247325374451, 5378.91931621026, 5383.031054410166, 4803.5916947059195, 4929.531694489017, 4936.456989731127, 4940.728259945666, 5008.649394542347, 5857.179994800731, 5922.615030023035, 5923.200367950271, 5962.791302304398, 6038.486728628028, 5735.426457354231, 5752.050911599539, 5757.366689664217, 5759.1649210865025, 5781.103928891029, 5720.856978575569, 5954.89834380641, 5957.106434034929, 5961.0, 5961.105834124743, 5516.806911758069, 5527.834164975057, 5547.750351234285, 5551.972240138935, 5625.606207565431, 5611.579131565068, 5625.971870140623, 5643.068893632505, 5643.604947653625, 5645.266115405136, 5476.753546090516, 5526.557875370041, 5540.473655002768, 5544.611877168721, 5547.306586676666, 5543.2793736815565, 5588.944110559005, 5602.719864006865, 5606.579506536945, 5669.610945318424, 5581.218629391969, 5588.720563971867, 5590.189422533791, 5591.27313449249, 5592.5525957922455, 5433.592480563894, 5629.380768115422, 5672.971635141963, 5677.148427735872, 5685.0, 5313.574914006793, 5458.672479651074, 5530.225768392509, 5538.861528461821, 5592.217089607377], "value": 5250.127623645401, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.7173942768769, 584.1967294969417, 601.4707057789145, 609.4423602403798, 625.4902254662453, 32663.1834204145, 43714.0, 46318.53681463189, 47765.76117119418, 47932.28101578479, 45456.545434545726, 45902.0, 46000.769996149975, 46089.308660370036, 46148.769256153755, 8045.235702608243, 8929.95535022324, 9085.454872707625, 9090.181883630472, 10753.526688861566, 10498.842517362207, 10910.072643825244, 10918.290311129751, 10924.945375273113, 10929.508172132233, 10962.890371096306, 11014.173936954721, 11015.228933974626, 11024.44877756113, 11026.724331891726, 10887.455627218607, 10910.0, 10950.726231844188, 10959.520464737237, 10963.287386319904, 10651.201159913038, 10787.946060269649, 10793.730156746065, 10798.73003174919, 10798.946005269923, 43349.349759753626, 43886.34170487445, 43889.0, 43911.56088439139, 43951.0, 3595.334863050344, 3817.923641527154, 3914.7455415398194, 4014.6220729814245, 4176.206520761076, 32575.0, 32951.83524082388, 33020.17449563756, 33106.6689333106, 33275.6672433275, 3988.481497405319, 4087.97956010221, 4103.651189648884, 4115.115250221217, 4156.418101465797, 378494.0, 379762.1368687858, 380079.09960450296, 381818.0, 382160.0, 59829.401705983255, 60502.69748651228, 60561.09158362628, 60658.69670651619, 60677.39322606806, 62684.37315626877, 62832.37167628265, 62860.68569657122, 62863.0, 62874.0, 16913.154342282836, 17582.296708131787, 17595.384161554284, 17614.917389456794, 17630.030348330965, 13554.59336219915, 13634.659133521742, 13646.385912633757, 13646.727065458834, 13648.590542283748, 16434.342626295052, 16717.0, 16752.748708769377, 16788.58028549296, 16905.915470422817, 5905.409459054146, 5961.940380596226, 6027.969860150672, 6033.396660333931, 6167.290761562388, 954.6467806911487, 983.0022527134963, 1001.1940387987739, 1050.9632162874263, 1087.2280680716622, 30547.694523054928, 30789.38421231563, 31317.843410783255, 31496.0, 32570.0, 1075.44077079918, 1083.8049151152868, 1099.2854644480992, 1108.6895669212633, 1118.7246538945608, 15826.525204243891, 16509.422170223985, 16663.25015374311, 16703.582410439838, 16916.0, 515433.0, 518568.0, 520476.0, 520946.0, 521503.0, 59036.0, 59462.70268648716, 59479.0, 59484.0, 59500.40499595035, 1215.6231568213882, 1288.1369482446596, 1343.6439343573961, 1348.4201793229006, 1358.4362489566918, 31300.530492043094, 33998.49002265018, 34013.319733605684, 34049.31901362009, 34508.30983380268, 50192.498075020245, 54823.53294101695, 56617.71691141683, 56659.150112749165, 61008.0, 447908.0, 449157.0, 449626.0, 450040.0, 450331.0, 25318.0, 27833.582496262166, 28872.422551549273, 29008.854955725088, 29511.0, 47957.0, 47993.5200648003, 48143.75928120337, 48186.75906620585, 48478.27282590693, 40706.38940415837, 40729.0, 40889.0, 41438.58561414347, 42906.71279861609, 40928.18143637051, 42100.36849447322, 42459.787701061294, 42549.57450425456, 42696.78651606722, 40862.59137408588, 40898.79550602228, 41012.384814228404, 41315.1736965265, 41593.79203103965, 28017.0, 29650.406991860473, 29935.850320748257, 30525.847370763004, 30669.23326916845, 27774.72225277802, 28091.552938368503, 28198.29504262328, 28276.52951271105, 28399.526987912923], "value": 20593.43547376996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.6163117697891, 393.2174971806105, 405.5863019719887, 414.4053283538122, 423.11779938827544, 12600.345899689102, 18498.90750546247, 20385.28651497198, 20400.693989590167, 20450.590988180247, 19473.805261947375, 19641.0, 19654.410367688994, 19773.901130494363, 20165.495862603442, 5822.767089316433, 5990.400959904018, 5993.520518358543, 5999.5200383969395, 6022.2773267207995, 5848.385919478457, 5934.139549765281, 5938.940610593903, 5939.7921072762465, 5941.821745347646, 4310.698251122422, 4939.654224204307, 6586.93413065868, 6675.0, 6780.118584583997, 6689.364510371532, 6689.531732778707, 6749.493787965897, 6753.561018533805, 6779.72881084756, 5767.106098554731, 5901.8819623607715, 5905.704714764244, 5908.586398952075, 5910.1430292607765, 23115.306540803802, 23810.880945595334, 23819.928103235397, 24121.155759548506, 24229.273121806196, 2349.6710460535537, 2372.5254949010096, 2505.8997640094376, 2635.262126604554, 2686.543287641086, 14219.644508887257, 14415.78376324356, 14473.0, 14751.852481475154, 14904.776428353583, 1798.4784412520353, 1911.5603411215498, 1919.7504324437734, 1925.0932979521704, 1998.3005947918257, 59776.70111649457, 75723.24276757217, 77557.0, 78476.0, 87034.0, 9962.850557241647, 10250.538725757358, 10287.22845786569, 10864.348139111678, 11098.833517497244, 15220.0, 15258.618534536503, 15268.389264429521, 15270.38918443272, 15289.311980960883, 8200.220979006985, 8366.790830229293, 8511.404201705822, 8640.179182977732, 8672.219500245008, 8250.174982501701, 8284.295834854105, 8306.70926517569, 8455.788605284793, 8513.616887240089, 6504.252011018699, 6605.0, 6607.768728094494, 6644.60132392058, 6721.294264102296, 1837.3477415517566, 1935.4967708395918, 1956.3371134535814, 2008.658528050235, 2014.476236178604, 908.68650315641, 1014.2899970021015, 1035.3787727363522, 1042.2027149230844, 1066.6853278282924, 19589.706154407697, 19945.401637950883, 20049.398518044483, 20085.799142008684, 20540.383788486368, 988.9109980101825, 1008.8587597736323, 1015.3349556040805, 1049.6955882793904, 1061.1775873697945, 10539.578416863393, 11541.134414918915, 11553.942230288794, 11566.942165289289, 11635.650930472098, 443828.0, 453134.0, 457906.0, 464909.0, 466255.0, 84447.15552844516, 85626.0, 86473.0, 88225.55887220651, 89586.0, 1012.089119792193, 1030.907218350337, 1062.8193207154802, 1063.7553362726462, 1068.3322923173016, 16440.177991100638, 17021.23404446778, 17593.384231551834, 17703.645927081645, 17877.463676089737, 31255.53116703206, 38749.0, 39422.40866386949, 40399.7980010098, 41282.38076428916, 52958.0, 53090.46909530855, 53618.463815362906, 53858.46141538535, 55137.72431137819, 8440.788980275542, 8550.871736923827, 8580.785480363042, 8622.223999839794, 8978.012418634076, 12758.234505929673, 13000.934995324962, 13096.803547946598, 13129.146605470614, 13145.408456619283, 10717.0, 10731.195160362848, 10875.456227188768, 11081.11351091927, 11427.714307142056, 11641.59254426091, 11673.241239319417, 11877.0, 12038.337891416057, 12433.502659893504, 10535.262531622713, 11016.394098324668, 11102.44487775592, 11107.611233606785, 11181.608643697431, 8557.61490732906, 8710.782230444289, 8713.0, 8763.737087887373, 8831.735047948572, 10521.4739263035, 10804.783904321712, 10949.616763413242, 11144.888551114385, 11273.154513411362], "value": 9430.09601021741, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.3729409883263, 1499.1529785671096, 1513.046780528267, 1650.9504714858551, 1823.5930466167802, 8683.739487815368, 9066.954665226673, 9070.818583628323, 9187.03536128707, 9263.583138758755, 8996.505192214441, 9081.500517471537, 9099.499527525983, 9110.954445227764, 9111.908880911189, 2391.7249516305587, 2412.9034838606435, 2414.8430352027062, 2415.6376543518527, 2430.6718592989896, 2381.261808839257, 2394.8802559872024, 2433.6957880264968, 2439.6828412306463, 2441.3652450362943, 2394.616861302192, 2420.1529464687305, 2425.0421083672027, 2426.1872272788637, 2426.502566973775, 2362.574736547421, 2369.9289021329387, 2381.38084098134, 2388.36708272308, 2397.2448678666165, 2303.1248125712123, 2323.604987152188, 2338.590746619335, 2340.847844890077, 2343.777341152605, 9055.094490550959, 9107.408018478844, 9172.633094676205, 9173.633054677804, 9174.954125229398, 5214.243934629495, 5437.0, 5450.482204190595, 5455.536279416255, 5465.6447330923365, 3562.4656301554714, 3589.1206654369585, 3622.4099768665533, 3622.8913132606062, 3635.654612811779, 1159.4173952554786, 1168.8305195746655, 1182.408795602199, 1194.8685644579089, 1195.9222650527774, 13034.087613867034, 13087.607371778862, 13110.082294239312, 13124.343782810822, 13131.34343282832, 4346.47842258931, 4476.932846007313, 4608.423947006624, 4629.699069560468, 4648.628109751212, 9977.002299769965, 9978.451185184884, 9985.850212246676, 9987.550560224807, 10023.548940297704, 5398.379186393615, 5403.729813509309, 5404.702741349185, 5408.0535906216255, 5420.945790542123, 4402.823887044482, 4497.190505709003, 4625.699329543569, 4696.295555666609, 5066.08810414129, 3881.146147847469, 3931.9606803932165, 3983.24318379507, 4028.8992775180855, 4038.5153781545814, 1171.1743221029153, 1171.6426489920486, 1186.463529729003, 1193.9701507462382, 1198.3648666206925, 914.6387177065155, 937.7702462896634, 943.9669611563562, 955.5795450001976, 955.8338826631638, 5767.653940763484, 5819.098039803837, 5852.502537284294, 6100.115483254903, 6113.358097399798, 440.41204991336286, 441.492283873548, 442.69896470399794, 444.0874003921907, 453.9909201815945, 3875.244327356195, 4062.1875624875142, 4096.09885825118, 4112.424260603518, 4149.170165966819, 11071.232191952013, 11334.773304533863, 11433.371164586028, 11474.139439542068, 11475.139364547693, 11360.943195283971, 11384.0, 11393.259438136494, 11456.0, 11462.025727813063, 8354.206350396702, 8384.413091083565, 8430.367722420842, 8479.19447652472, 8500.362472814688, 5929.377415371324, 5957.046872500379, 6029.487493563096, 6171.691415429301, 6306.432421082125, 10258.897411025795, 10446.947765261126, 10583.259171857895, 10601.204909631655, 10745.623903163352, 9701.272404569545, 10551.894481055399, 10653.520591573553, 10706.73233169177, 10708.89291107079, 7867.9606601966625, 7941.880871786812, 8079.0, 8276.213759692819, 8286.295664868554, 8820.470771753715, 8848.46909185451, 8952.820943581222, 8975.461472311683, 9047.954760226157, 8152.3478121751305, 8166.265036146775, 8190.262876341158, 8202.507849529047, 8339.332853371594, 7847.176046515034, 8039.075506316848, 8059.073206581318, 8097.757067287991, 8303.875441868498, 6995.405390541861, 7114.928850711427, 7133.785986420416, 7137.357637812622, 7137.714491420493, 4984.501549844987, 5073.001732244494, 5114.414531495248, 5201.479852014768, 5285.260063591023, 5092.923606145837, 5183.300254465599, 5217.791288348423, 5220.425753167225, 5251.632385732962], "value": 4640.986026982365, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.53042293040704, 337.41458569382127, 338.6478062814673, 338.7577881814503, 345.25252827628185, 5182.093133701596, 5347.7326133693305, 5360.490753378433, 5413.4045255021965, 5435.755391007404, 5354.866128346794, 5401.189821526773, 5407.945920540794, 5413.648112872661, 5431.945680543194, 4190.434291370671, 4198.874033778991, 4208.852690155845, 4224.091820258644, 4253.468316460442, 4105.384192371153, 4229.344451610005, 4230.407742915994, 4235.322348424253, 4235.512916014652, 4200.264953633117, 4200.810963506649, 4207.368894665794, 4214.473190851144, 4218.346156345771, 4206.831726730927, 4216.072464057917, 4230.428892099589, 4238.131183107486, 4238.639715624176, 3581.5494724636565, 3675.8529658813613, 3676.2647470506004, 3698.9445158322646, 3767.1147280389087, 5235.005348983681, 5241.266222728821, 5261.316028916216, 5263.894722105537, 5294.179402192666, 4789.664723469358, 4792.568668819823, 4797.784099715486, 4805.1590971579835, 4811.879703007453, 5452.700101494416, 5588.497035266845, 5614.691191984439, 5624.971875140639, 5670.5747068969995, 4020.0954785173394, 4023.6982226333143, 4026.6577340926056, 4033.1530378620523, 4051.675865930748, 5875.265591801025, 5885.470307672287, 5936.673482958392, 5941.940580594225, 5948.107783832416, 4059.7361171523758, 4079.0618157823865, 4116.1767646470225, 4123.299039163349, 4123.773192474443, 4160.687948403882, 4173.770442625624, 4181.142865712522, 4181.707280490397, 4208.494980602286, 4769.928451073237, 4811.302361157683, 4816.831410900601, 4825.638077144158, 4836.95163048365, 5429.972850135803, 5654.434556544313, 5660.8867822643315, 5734.741936612862, 5744.0, 1358.9252591107386, 1384.4185442114335, 1406.7397531456713, 1437.7771445425976, 1439.5969128643997, 940.83535381308, 974.9129720361888, 984.601236499219, 995.4624502768569, 999.6501224571342, 5427.0773968425465, 5431.891362172734, 5433.619646624698, 5445.856370162231, 5448.346198456211, 5705.942940570624, 5722.427757224327, 5742.799002034909, 5760.971195143998, 5784.698442850409, 5294.88807350453, 5399.217113518518, 5411.702356370359, 5417.918731219035, 5432.972835135799, 2676.6118912757543, 2687.986560067187, 2691.5558932776266, 2699.0, 2758.7103354147926, 5174.870628234325, 5180.274761533388, 5218.243354713622, 5260.553347829377, 5267.789288428497, 4865.659403841697, 5103.948960510347, 5139.691618502902, 5148.227765835155, 5217.243499692523, 5153.788859617986, 5163.741812909339, 5172.250023746611, 5172.301739265226, 5196.532312091778, 5056.306137443998, 5060.569851562658, 5069.290299358019, 5083.872903177302, 5158.916627508265, 4767.6424268179335, 4971.453140154508, 5020.598352131894, 5030.974845125751, 5047.747612619428, 5157.638965272545, 5160.48395160496, 5174.844754657366, 5176.0, 5183.533481986488, 3735.327641024533, 3736.8505259789304, 3744.839099879046, 3745.7003439724213, 3755.1224387806096, 4903.010829465735, 4943.30793688891, 5010.874728131826, 5036.798528058983, 5528.778848846162, 5472.781088756406, 5562.582806289625, 5565.526930210977, 5591.4688104629995, 5630.634008789416, 5583.330000399978, 5608.775648973995, 5656.151577263443, 5684.971575142098, 5706.630408701964, 5309.707966061749, 5590.357108932525, 5604.299462567179, 5604.775808967595, 5650.830475085754, 5686.658800471985, 5688.488036076773, 5720.570957178145, 5722.8569285766525, 5733.283339582552, 5450.591205659671, 5563.693996830213, 5585.357683866408, 5615.887682246251, 5616.157576363578], "value": 4169.541229932297, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.7181399254292, 728.6611725547621, 741.8590467811119, 759.8290384663452, 817.2032268538172, 15607.0, 17024.744628830562, 17060.317587296526, 17078.316867325324, 17084.743728844063, 15280.617984550365, 15307.928445008825, 15368.231588420562, 15432.69134617307, 15454.150021748828, 653.0432915778383, 780.5979920341028, 804.4569915307173, 809.348474478045, 809.5830647216688, 800.5836964778312, 801.1267718187174, 808.9110197878229, 809.8056466448069, 810.1533897077568, 697.0450482838519, 859.7549698335976, 866.1425189062845, 866.2333834556431, 866.5840396609631, 827.93790465715, 835.2482765511028, 844.5861527851339, 848.5248260973813, 849.4096602861014, 615.9076138579204, 616.1343312645695, 616.1897105306508, 616.374380004296, 616.7163104971719, 13772.793408098887, 13776.311184440838, 13803.861961380459, 13824.308784560732, 13846.446142154302, 14134.505292314823, 14179.929100354535, 14332.42670293187, 14488.565343039725, 14488.782668259879, 12081.335526546036, 12096.33470159141, 12285.262884226886, 12312.199707019015, 12625.0, 3780.6956599972964, 3785.4511095891216, 3858.9807050964564, 4339.132173565299, 4455.688101832873, 71473.64263178704, 71674.2832571678, 71902.0, 72035.63982180055, 73752.26247737507, 10920.945395272973, 10941.23411361213, 11057.834132488018, 11171.944140279247, 11834.573912347949, 18235.908820455814, 18413.90793046053, 18525.90737046333, 18565.35021274249, 18566.257349705862, 8712.346574006848, 8970.730878073668, 8993.865092023625, 9035.051319611477, 9145.679901203559, 4810.687305325143, 4942.135126352874, 4979.800807967713, 4985.725785081783, 5119.180931051014, 3392.49112633107, 3537.8407971641336, 3538.840752166159, 3539.4690796380232, 3540.8760693376125, 566.60620868496, 623.2147494157394, 623.6102435977514, 624.9125122482856, 651.726274964516, 681.6387314723205, 718.2207305074053, 733.318014246745, 733.4132693844903, 765.8698021336386, 9856.901430985741, 10331.431771252654, 10543.311455578778, 10579.100776433936, 10832.40421776794, 642.6818724731288, 698.7379732600275, 720.0962791696387, 746.4923851780733, 758.719273868661, 4572.679912406166, 4840.298156767249, 4849.2726091086615, 4859.2954021666665, 4863.80544778212, 123694.0, 125327.0, 125428.0, 125944.85137871443, 129478.0, 108300.0, 108512.0, 110014.44992775146, 110234.44882775535, 111219.0, 89355.0, 89988.0, 91406.54296728474, 94638.99999999862, 94701.52649236847, 9209.539523023688, 9272.907270927204, 9348.3456158071, 9464.716058518256, 9520.666776662783, 41573.0, 44000.0, 45372.77313613411, 45879.0, 57318.14022789745, 72222.63888680523, 73502.48731345277, 74121.6293918527, 74898.0, 77058.6147069261, 12263.141580089665, 12379.814302785286, 12380.380980951097, 12512.186707863959, 12559.434825432694, 14609.415623374945, 14649.26753662334, 14977.850221497645, 15042.548723538312, 15672.764908526153, 11909.344986025852, 12578.182418142791, 14365.13809171453, 14959.626009349853, 15367.61580960485, 12579.93710031444, 12983.0, 13356.599302020955, 14022.929885350508, 14100.858991409954, 13273.469061237442, 13491.25798081115, 13968.930155349159, 14348.85651143517, 14414.97285570042, 11068.493150684932, 11197.552097915992, 11307.265027773168, 11929.642110736691, 11962.940185299365, 11416.31502109876, 11760.58837940668, 11793.582553590275, 12216.0, 12217.69455763613], "value": 7604.896214113625, "unit": "score"}], "name": "dromaeo_css", "value": 7289.688543784438, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 125.0, 114.0, 111.0, 113.0, 112.0, 111.0, 111.0, 111.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 122.0, 152.0, 181.0, 119.0, 144.0, 149.0, 128.0, 183.0, 120.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 160.0, 162.0, 161.0, 160.0, 161.0, 159.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 181.0, 77.0, 75.0, 74.0, 77.0, 159.0, 76.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 159.0, 82.0, 82.0, 82.0, 150.0, 84.0, 82.0, 150.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 148.0, 146.0, 146.0, 145.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 54.0, 52.0, 53.0, 54.0, 52.0, 51.0, 51.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 61.0, 60.0, 61.0, 60.0, 61.0, 62.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 85.0, 78.0, 78.0, 86.0, 79.0, 80.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 130.0, 125.0, 122.0, 132.0, 122.0, 124.0, 183.0, 121.0], "value": 130.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 162.0, 164.0, 163.0, 163.0, 162.0, 160.0, 162.0, 168.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 64.0, 63.0, 63.0, 64.0, 68.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1527.3, "alertThreshold": 2.0}]} 01:35:25 INFO - Return code: 0 01:35:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:35:25 INFO - # TBPL SUCCESS # 01:35:25 INFO - Running post-action listener: _resource_record_post_action 01:35:25 INFO - [mozharness: 2016-08-26 08:35:25.337409Z] Finished run-tests step (success) 01:35:25 INFO - Running post-run listener: _resource_record_post_run 01:35:25 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 418607104; Read time: 0; Write time: 366416 01:35:25 INFO - TinderboxPrint: CPU usage
13.1% 01:35:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 01:35:25 INFO - TinderboxPrint: I/O write bytes / time
418,607,104 / 366,416 01:35:25 INFO - TinderboxPrint: CPU idle
8,293.8 (86.9%) 01:35:25 INFO - TinderboxPrint: CPU user
1,196.7 (12.5%) 01:35:25 INFO - TinderboxPrint: Swap in / out
0 / 0 01:35:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 128798720; Read time: 0; Write time: 101604 01:35:25 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 288907264; Read time: 0; Write time: 264780 01:35:25 INFO - Running post-run listener: _upload_blobber_files 01:35:25 INFO - Blob upload gear active. 01:35:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:35:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:35:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:35:26 INFO - (blobuploader) - INFO - Open directory for files ... 01:35:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:35:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:35:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:35:30 INFO - (blobuploader) - INFO - Done attempting. 01:35:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:35:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:35:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:35:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:35:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:35:31 INFO - (blobuploader) - INFO - Done attempting. 01:35:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:35:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:35:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:35:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:35:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:35:32 INFO - (blobuploader) - INFO - Done attempting. 01:35:32 INFO - (blobuploader) - INFO - Iteration through files over. 01:35:32 INFO - Return code: 0 01:35:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:35:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:35:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3454d4ba82f0f879a59caeccad4457764d2157d04c592936aebeaede910df840173e0963ca5d953188374654b436573580cd26b7f8981583fdb5b8e98858e36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a04e43de260d8a9bda8f99c971eea9fbde78ee6d606232431c4f9acf39be0a8d8ecc5f33c1d4f6312a1ea33a6dd902ab4c94b885d78646737c2c3dd26ed15d08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22eaac379510b3fe2ff730611ed2d37c556b12857188bcef3b201d5b8304a6ff14b64f435dfc9c086d0afd3fdc44296adc873dd93fe8e0591cb133fda8c8e054"} 01:35:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:35:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:35:32 INFO - Contents: 01:35:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3454d4ba82f0f879a59caeccad4457764d2157d04c592936aebeaede910df840173e0963ca5d953188374654b436573580cd26b7f8981583fdb5b8e98858e36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a04e43de260d8a9bda8f99c971eea9fbde78ee6d606232431c4f9acf39be0a8d8ecc5f33c1d4f6312a1ea33a6dd902ab4c94b885d78646737c2c3dd26ed15d08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22eaac379510b3fe2ff730611ed2d37c556b12857188bcef3b201d5b8304a6ff14b64f435dfc9c086d0afd3fdc44296adc873dd93fe8e0591cb133fda8c8e054"} 01:35:32 INFO - Running post-run listener: copy_logs_to_upload_dir 01:35:32 INFO - Copying logs to upload dir... 01:35:32 INFO - mkdir: /builds/slave/test/build/upload/logs 01:35:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.863159 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-26 01:35:32.030357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 01:35:32.037407) ========= 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=41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358 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-Inbound/sha512/3454d4ba82f0f879a59caeccad4457764d2157d04c592936aebeaede910df840173e0963ca5d953188374654b436573580cd26b7f8981583fdb5b8e98858e36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a04e43de260d8a9bda8f99c971eea9fbde78ee6d606232431c4f9acf39be0a8d8ecc5f33c1d4f6312a1ea33a6dd902ab4c94b885d78646737c2c3dd26ed15d08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22eaac379510b3fe2ff730611ed2d37c556b12857188bcef3b201d5b8304a6ff14b64f435dfc9c086d0afd3fdc44296adc873dd93fe8e0591cb133fda8c8e054"} build_url:https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3454d4ba82f0f879a59caeccad4457764d2157d04c592936aebeaede910df840173e0963ca5d953188374654b436573580cd26b7f8981583fdb5b8e98858e36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a04e43de260d8a9bda8f99c971eea9fbde78ee6d606232431c4f9acf39be0a8d8ecc5f33c1d4f6312a1ea33a6dd902ab4c94b885d78646737c2c3dd26ed15d08", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22eaac379510b3fe2ff730611ed2d37c556b12857188bcef3b201d5b8304a6ff14b64f435dfc9c086d0afd3fdc44296adc873dd93fe8e0591cb133fda8c8e054"}' ========= master_lag: 0.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 01:35:32.342375) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:35:32.342700) ========= 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=41a7e32711b8e08598a63237000001d6-1472198063.493550-2039991358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:35:32.422579) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 01:35:32.422910) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 01:35:32.423198) ========= ========= Total master_lag: 0.46 =========