builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1470833359.75 results: success (0) buildid: 20160810030202 builduid: 1104e10009e6475594166a23e45c1d7b revision: 6cf0089510fad8deb866136f5b92bbced9498447 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.748799) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.749331) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.750016) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.767320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.767821) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 05:49:19-- 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% 227M=0s 2016-08-10 05:49:20 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081489 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.866469) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.866903) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021987 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:19.918485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 05:49:19.918863) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6cf0089510fad8deb866136f5b92bbced9498447 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6cf0089510fad8deb866136f5b92bbced9498447 --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=9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 05:49:20,147 truncating revision to first 12 chars 2016-08-10 05:49:20,147 Setting DEBUG logging. 2016-08-10 05:49:20,147 attempt 1/10 2016-08-10 05:49:20,147 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6cf0089510fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 05:49:21,293 unpacking tar archive at: mozilla-central-6cf0089510fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.496170 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 05:49:21.429651) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:21.430056) ========= script_repo_revision: 6cf0089510fad8deb866136f5b92bbced9498447 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:21.430538) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:21.430871) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 05:49:21.442238) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-08-10 05:49:21.442585) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:49:21 INFO - MultiFileLogger online at 20160810 05:49:21 in /builds/slave/test 05:49:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:49:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:49:21 INFO - {'append_to_log': False, 05:49:21 INFO - 'base_work_dir': '/builds/slave/test', 05:49:21 INFO - 'blob_upload_branch': 'Firefox', 05:49:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:49:21 INFO - 'branch': 'Firefox', 05:49:21 INFO - 'buildbot_json_path': 'buildprops.json', 05:49:21 INFO - 'config_files': ('talos/linux_config.py',), 05:49:21 INFO - 'default_actions': ('clobber', 05:49:21 INFO - 'read-buildbot-config', 05:49:21 INFO - 'download-and-extract', 05:49:21 INFO - 'populate-webroot', 05:49:21 INFO - 'create-virtualenv', 05:49:21 INFO - 'install', 05:49:21 INFO - 'run-tests'), 05:49:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:49:21 INFO - 'download_minidump_stackwalk': True, 05:49:21 INFO - 'download_symbols': 'ondemand', 05:49:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:49:21 INFO - 'tooltool.py': '/tools/tooltool.py', 05:49:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:49:21 INFO - '/tools/misc-python/virtualenv.py')}, 05:49:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:49:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:49:21 INFO - 'installer_path': 'installer.exe', 05:49:21 INFO - 'log_level': 'info', 05:49:21 INFO - 'log_name': 'talos', 05:49:21 INFO - 'log_to_console': True, 05:49:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:49:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:49:21 INFO - 'opt_config_files': (), 05:49:21 INFO - 'pip_index': False, 05:49:21 INFO - 'sps_profile': False, 05:49:21 INFO - 'sps_profile_interval': 0, 05:49:21 INFO - 'suite': 'dromaeojs', 05:49:21 INFO - 'system_bits': '32', 05:49:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:49:21 INFO - 'title': 'talos-linux64-ix-029', 05:49:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:49:21 INFO - 'use_talos_json': True, 05:49:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:49:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:49:21 INFO - 'work_dir': 'build'} 05:49:21 INFO - [mozharness: 2016-08-10 12:49:21.761077Z] Running clobber step. 05:49:21 INFO - Running pre-action listener: _resource_record_pre_action 05:49:21 INFO - Running main action method: clobber 05:49:21 INFO - rmtree: /builds/slave/test/build 05:49:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:49:22 INFO - Running post-action listener: _resource_record_post_action 05:49:22 INFO - [mozharness: 2016-08-10 12:49:22.210451Z] Finished clobber step (success) 05:49:22 INFO - [mozharness: 2016-08-10 12:49:22.210554Z] Running read-buildbot-config step. 05:49:22 INFO - Running pre-action listener: _resource_record_pre_action 05:49:22 INFO - Running main action method: read_buildbot_config 05:49:22 INFO - Using buildbot properties: 05:49:22 INFO - { 05:49:22 INFO - "project": "", 05:49:22 INFO - "product": "firefox", 05:49:22 INFO - "script_repo_revision": "production", 05:49:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:49:22 INFO - "repository": "", 05:49:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:49:22 INFO - "buildid": "20160810030202", 05:49:22 INFO - "stage_platform": "linux64-pgo", 05:49:22 INFO - "basedir": "/builds/slave/test", 05:49:22 INFO - "nightly_build": "True", 05:49:22 INFO - "buildnumber": 826, 05:49:22 INFO - "slavename": "talos-linux64-ix-029", 05:49:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:49:22 INFO - "platform": "ubuntu64_hw", 05:49:22 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:49:22 INFO - "branch": "mozilla-central", 05:49:22 INFO - "repo_path": "mozilla-central", 05:49:22 INFO - "revision": "6cf0089510fad8deb866136f5b92bbced9498447", 05:49:22 INFO - "pgo_build": "True", 05:49:22 INFO - "builduid": "1104e10009e6475594166a23e45c1d7b", 05:49:22 INFO - "slavebuilddir": "test" 05:49:22 INFO - } 05:49:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:49:22 INFO - Running post-action listener: _resource_record_post_action 05:49:22 INFO - [mozharness: 2016-08-10 12:49:22.213079Z] Finished read-buildbot-config step (success) 05:49:22 INFO - [mozharness: 2016-08-10 12:49:22.213168Z] Running download-and-extract step. 05:49:22 INFO - Running pre-action listener: _resource_record_pre_action 05:49:22 INFO - Running main action method: download_and_extract 05:49:22 INFO - mkdir: /builds/slave/test/build/tests 05:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:22 INFO - trying https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:49:22 INFO - Downloading https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:22 INFO - Downloaded 1361 bytes. 05:49:22 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:49:22 INFO - Using the following test package requirements: 05:49:22 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:49:22 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:49:22 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:49:22 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'jsshell-linux-x86_64.zip'], 05:49:22 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:49:22 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:49:22 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:49:22 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:49:22 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:49:22 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:49:22 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:49:22 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:22 INFO - trying https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:49:22 INFO - Downloading https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:23 INFO - Downloaded 21831311 bytes. 05:49:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:49:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:49:24 INFO - Return code: 0 05:49:24 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 05:49: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')]} 05:49:24 INFO - trying https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:49:24 INFO - Downloading https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:25 INFO - Downloaded 21831311 bytes. 05:49:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:49:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:49:26 INFO - Return code: 0 05:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:26 INFO - trying https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:49:26 INFO - Downloading https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:27 INFO - Downloaded 14413723 bytes. 05:49:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:49:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:49:27 INFO - Return code: 0 05:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:27 INFO - trying https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:49:27 INFO - Downloading https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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 05:49:29 INFO - Downloaded 59337906 bytes. 05:49:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:49:29 INFO - mkdir: /builds/slave/test/properties 05:49:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:49:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:49:29 INFO - Contents: 05:49:29 INFO - build_url:https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:49:29 INFO - Running post-action listener: _resource_record_post_action 05:49:29 INFO - Running post-action listener: set_extra_try_arguments 05:49:29 INFO - [mozharness: 2016-08-10 12:49:29.694922Z] Finished download-and-extract step (success) 05:49:29 INFO - [mozharness: 2016-08-10 12:49:29.695156Z] Running populate-webroot step. 05:49:29 INFO - Running pre-action listener: _resource_record_pre_action 05:49:29 INFO - Running main action method: populate_webroot 05:49:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:49:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:49:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:49:29 INFO - '--tppagecycles', 05:49:29 INFO - '7'], 05:49:29 INFO - 'tests': ['tsvgm']}}, 05:49:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:49:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:49:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:49:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:49:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:49:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:49:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['damp', 'tps']}, 05:49:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'tests': ['damp', 'tps']}, 05:49:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['dromaeo_dom']}, 05:49:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:49:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['basic_compositor_video']}, 05:49:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:49:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['a11yr', 05:49:29 INFO - 'ts_paint', 05:49:29 INFO - 'tpaint', 05:49:29 INFO - 'sessionrestore', 05:49:29 INFO - 'sessionrestore_no_auto_restore', 05:49:29 INFO - 'tabpaint']}, 05:49:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:49:29 INFO - 'ts_paint', 05:49:29 INFO - 'tpaint', 05:49:29 INFO - 'sessionrestore', 05:49:29 INFO - 'sessionrestore_no_auto_restore', 05:49:29 INFO - 'tabpaint']}, 05:49:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:49:29 INFO - 'ts_paint', 05:49:29 INFO - 'tpaint', 05:49:29 INFO - 'sessionrestore', 05:49:29 INFO - 'sessionrestore_no_auto_restore', 05:49:29 INFO - 'tabpaint']}, 05:49:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:49:29 INFO - 'ts_paint', 05:49:29 INFO - 'tpaint', 05:49:29 INFO - 'sessionrestore', 05:49:29 INFO - 'sessionrestore_no_auto_restore', 05:49:29 INFO - 'tabpaint']}, 05:49:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['a11yr', 05:49:29 INFO - 'ts_paint', 05:49:29 INFO - 'tpaint', 05:49:29 INFO - 'sessionrestore', 05:49:29 INFO - 'sessionrestore_no_auto_restore', 05:49:29 INFO - 'tabpaint']}, 05:49:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['a11yr', 05:49:29 INFO - 'ts_paint', 05:49:29 INFO - 'tpaint', 05:49:29 INFO - 'sessionrestore', 05:49:29 INFO - 'sessionrestore_no_auto_restore', 05:49:29 INFO - 'tabpaint']}, 05:49:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['tsvgx', 05:49:29 INFO - 'tsvgr_opacity', 05:49:29 INFO - 'tart', 05:49:29 INFO - 'tscrollx', 05:49:29 INFO - 'cart']}, 05:49:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:49:29 INFO - 'tsvgr_opacity', 05:49:29 INFO - 'tart', 05:49:29 INFO - 'tscrollx', 05:49:29 INFO - 'cart']}, 05:49:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'talos_options': ['--disable-e10s'], 05:49:29 INFO - 'tests': ['tp5o']}, 05:49:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'tests': ['tp5o']}, 05:49:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'talos_options': ['--disable-e10s', 05:49:29 INFO - '--xperf_path', 05:49:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:49:29 INFO - 'tests': ['tp5n']}, 05:49:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:29 INFO - 'talos_options': ['--xperf_path', 05:49:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:49:29 INFO - 'tests': ['tp5n']}}, 05:49:29 INFO - 'talos.zip': {'path': '', 05:49:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:49:29 INFO - Running post-action listener: _resource_record_post_action 05:49:29 INFO - [mozharness: 2016-08-10 12:49:29.712170Z] Finished populate-webroot step (success) 05:49:29 INFO - [mozharness: 2016-08-10 12:49:29.712259Z] Running create-virtualenv step. 05:49:29 INFO - Running pre-action listener: _resource_record_pre_action 05:49:29 INFO - Running main action method: create_virtualenv 05:49:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:49:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:49:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:49:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:49:29 INFO - Using real prefix '/usr' 05:49:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:49:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:49:32 INFO - Installing pip.................done. 05:49:32 INFO - Return code: 0 05:49:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:49:32 INFO - Using env: {'DISPLAY': ':0', 05:49:32 INFO - 'HOME': '/home/cltbld', 05:49:32 INFO - 'LANG': 'en_US.UTF-8', 05:49:32 INFO - 'LANGUAGE': 'en_US:en', 05:49:32 INFO - 'LOGNAME': 'cltbld', 05:49:32 INFO - 'MAIL': '/var/mail/cltbld', 05:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:32 INFO - 'MOZ_NO_REMOTE': '1', 05:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:32 INFO - 'NO_EM_RESTART': '1', 05:49:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:32 INFO - 'PWD': '/builds/slave/test', 05:49:32 INFO - 'SHELL': '/bin/bash', 05:49:32 INFO - 'SHLVL': '1', 05:49:32 INFO - 'TERM': 'linux', 05:49:32 INFO - 'TMOUT': '86400', 05:49:32 INFO - 'USER': 'cltbld', 05:49:32 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:32 INFO - '_': '/tools/buildbot/bin/python'} 05:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:49:32 INFO - Cleaning up... 05:49:32 INFO - Return code: 0 05:49:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:49:32 INFO - Using env: {'DISPLAY': ':0', 05:49:32 INFO - 'HOME': '/home/cltbld', 05:49:32 INFO - 'LANG': 'en_US.UTF-8', 05:49:32 INFO - 'LANGUAGE': 'en_US:en', 05:49:32 INFO - 'LOGNAME': 'cltbld', 05:49:32 INFO - 'MAIL': '/var/mail/cltbld', 05:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:32 INFO - 'MOZ_NO_REMOTE': '1', 05:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:32 INFO - 'NO_EM_RESTART': '1', 05:49:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:32 INFO - 'PWD': '/builds/slave/test', 05:49:32 INFO - 'SHELL': '/bin/bash', 05:49:32 INFO - 'SHLVL': '1', 05:49:32 INFO - 'TERM': 'linux', 05:49:32 INFO - 'TMOUT': '86400', 05:49:32 INFO - 'USER': 'cltbld', 05:49:32 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:32 INFO - '_': '/tools/buildbot/bin/python'} 05:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:32 INFO - Downloading/unpacking psutil>=3.1.1 05:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:49:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:49:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:49:34 INFO - Installing collected packages: psutil 05:49:34 INFO - Running setup.py install for psutil 05:49:34 INFO - building 'psutil._psutil_linux' extension 05:49:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:49:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:49:35 INFO - building 'psutil._psutil_posix' extension 05:49:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:49:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:49:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:49:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:49:35 INFO - Successfully installed psutil 05:49:35 INFO - Cleaning up... 05:49:36 INFO - Return code: 0 05:49:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:36 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:49:36 INFO - Using env: {'DISPLAY': ':0', 05:49:36 INFO - 'HOME': '/home/cltbld', 05:49:36 INFO - 'LANG': 'en_US.UTF-8', 05:49:36 INFO - 'LANGUAGE': 'en_US:en', 05:49:36 INFO - 'LOGNAME': 'cltbld', 05:49:36 INFO - 'MAIL': '/var/mail/cltbld', 05:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:36 INFO - 'MOZ_NO_REMOTE': '1', 05:49:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:36 INFO - 'NO_EM_RESTART': '1', 05:49:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:36 INFO - 'PWD': '/builds/slave/test', 05:49:36 INFO - 'SHELL': '/bin/bash', 05:49:36 INFO - 'SHLVL': '1', 05:49:36 INFO - 'TERM': 'linux', 05:49:36 INFO - 'TMOUT': '86400', 05:49:36 INFO - 'USER': 'cltbld', 05:49:36 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:36 INFO - '_': '/tools/buildbot/bin/python'} 05:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:49:38 INFO - Installing collected packages: mozsystemmonitor 05:49:38 INFO - Running setup.py install for mozsystemmonitor 05:49:38 INFO - Successfully installed mozsystemmonitor 05:49:38 INFO - Cleaning up... 05:49:38 INFO - Return code: 0 05:49:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:38 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:49:38 INFO - Using env: {'DISPLAY': ':0', 05:49:38 INFO - 'HOME': '/home/cltbld', 05:49:38 INFO - 'LANG': 'en_US.UTF-8', 05:49:38 INFO - 'LANGUAGE': 'en_US:en', 05:49:38 INFO - 'LOGNAME': 'cltbld', 05:49:38 INFO - 'MAIL': '/var/mail/cltbld', 05:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:38 INFO - 'MOZ_NO_REMOTE': '1', 05:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:38 INFO - 'NO_EM_RESTART': '1', 05:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:38 INFO - 'PWD': '/builds/slave/test', 05:49:38 INFO - 'SHELL': '/bin/bash', 05:49:38 INFO - 'SHLVL': '1', 05:49:38 INFO - 'TERM': 'linux', 05:49:38 INFO - 'TMOUT': '86400', 05:49:38 INFO - 'USER': 'cltbld', 05:49:38 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:38 INFO - '_': '/tools/buildbot/bin/python'} 05:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:38 INFO - Downloading/unpacking jsonschema==2.5.1 05:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:49:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:40 INFO - Downloading functools32-3.2.3-2.tar.gz 05:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:49:40 INFO - warning: no files found matching '*.txt' 05:49:40 INFO - no previously-included directories found matching 'build' 05:49:40 INFO - no previously-included directories found matching 'dist' 05:49:40 INFO - no previously-included directories found matching '.git*' 05:49:40 INFO - Installing collected packages: jsonschema, functools32 05:49:40 INFO - Running setup.py install for functools32 05:49:40 INFO - warning: no files found matching '*.txt' 05:49:40 INFO - no previously-included directories found matching 'build' 05:49:40 INFO - no previously-included directories found matching 'dist' 05:49:40 INFO - no previously-included directories found matching '.git*' 05:49:40 INFO - Successfully installed jsonschema functools32 05:49:40 INFO - Cleaning up... 05:49:40 INFO - Return code: 0 05:49:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:40 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:49:40 INFO - Using env: {'DISPLAY': ':0', 05:49:40 INFO - 'HOME': '/home/cltbld', 05:49:40 INFO - 'LANG': 'en_US.UTF-8', 05:49:40 INFO - 'LANGUAGE': 'en_US:en', 05:49:40 INFO - 'LOGNAME': 'cltbld', 05:49:40 INFO - 'MAIL': '/var/mail/cltbld', 05:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:40 INFO - 'MOZ_NO_REMOTE': '1', 05:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:40 INFO - 'NO_EM_RESTART': '1', 05:49:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:40 INFO - 'PWD': '/builds/slave/test', 05:49:40 INFO - 'SHELL': '/bin/bash', 05:49:40 INFO - 'SHLVL': '1', 05:49:40 INFO - 'TERM': 'linux', 05:49:40 INFO - 'TMOUT': '86400', 05:49:40 INFO - 'USER': 'cltbld', 05:49:40 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:40 INFO - '_': '/tools/buildbot/bin/python'} 05:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:49:41 INFO - Cleaning up... 05:49:41 INFO - Return code: 0 05:49:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:41 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:49:41 INFO - Using env: {'DISPLAY': ':0', 05:49:41 INFO - 'HOME': '/home/cltbld', 05:49:41 INFO - 'LANG': 'en_US.UTF-8', 05:49:41 INFO - 'LANGUAGE': 'en_US:en', 05:49:41 INFO - 'LOGNAME': 'cltbld', 05:49:41 INFO - 'MAIL': '/var/mail/cltbld', 05:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:41 INFO - 'MOZ_NO_REMOTE': '1', 05:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:41 INFO - 'NO_EM_RESTART': '1', 05:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:41 INFO - 'PWD': '/builds/slave/test', 05:49:41 INFO - 'SHELL': '/bin/bash', 05:49:41 INFO - 'SHLVL': '1', 05:49:41 INFO - 'TERM': 'linux', 05:49:41 INFO - 'TMOUT': '86400', 05:49:41 INFO - 'USER': 'cltbld', 05:49:41 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:41 INFO - '_': '/tools/buildbot/bin/python'} 05:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:49:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:49:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:49:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:43 INFO - Downloading docopt-0.6.1.tar.gz 05:49:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:49:43 INFO - Installing collected packages: blobuploader, requests, docopt 05:49:43 INFO - Running setup.py install for blobuploader 05:49:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:49:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:49:44 INFO - Running setup.py install for requests 05:49:44 INFO - Running setup.py install for docopt 05:49:44 INFO - Successfully installed blobuploader requests docopt 05:49:44 INFO - Cleaning up... 05:49:44 INFO - Return code: 0 05:49:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:44 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:49:44 INFO - Using env: {'DISPLAY': ':0', 05:49:44 INFO - 'HOME': '/home/cltbld', 05:49:44 INFO - 'LANG': 'en_US.UTF-8', 05:49:44 INFO - 'LANGUAGE': 'en_US:en', 05:49:44 INFO - 'LOGNAME': 'cltbld', 05:49:44 INFO - 'MAIL': '/var/mail/cltbld', 05:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:44 INFO - 'MOZ_NO_REMOTE': '1', 05:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:44 INFO - 'NO_EM_RESTART': '1', 05:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:44 INFO - 'PWD': '/builds/slave/test', 05:49:44 INFO - 'SHELL': '/bin/bash', 05:49:44 INFO - 'SHLVL': '1', 05:49:44 INFO - 'TERM': 'linux', 05:49:44 INFO - 'TMOUT': '86400', 05:49:44 INFO - 'USER': 'cltbld', 05:49:44 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:44 INFO - '_': '/tools/buildbot/bin/python'} 05:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:49:44 INFO - Running setup.py (path:/tmp/pip-Hfihfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:49:44 INFO - Running setup.py (path:/tmp/pip-tgGfR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:49:44 INFO - Running setup.py (path:/tmp/pip-Wl4isQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:49:44 INFO - Running setup.py (path:/tmp/pip-xrz9wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:49:45 INFO - Running setup.py (path:/tmp/pip-bIQSzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:49:45 INFO - Running setup.py (path:/tmp/pip-g6cmKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:49:45 INFO - Running setup.py (path:/tmp/pip-1VADKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:49:45 INFO - Running setup.py (path:/tmp/pip-ZFIEO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:49:45 INFO - Running setup.py (path:/tmp/pip-qbXvxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:49:45 INFO - Running setup.py (path:/tmp/pip-uc5P0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:49:45 INFO - Running setup.py (path:/tmp/pip-FqQwgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:49:45 INFO - Running setup.py (path:/tmp/pip-ZkMXBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:49:45 INFO - Running setup.py (path:/tmp/pip-Yhb3Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:49:45 INFO - Running setup.py (path:/tmp/pip-PVSNFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:45 INFO - Running setup.py (path:/tmp/pip-Anr8_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:49:45 INFO - Running setup.py (path:/tmp/pip-BgeECu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:49:45 INFO - Running setup.py (path:/tmp/pip-XUTJLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:49:45 INFO - Running setup.py install for manifestparser 05:49:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Running setup.py install for mozcrash 05:49:46 INFO - Running setup.py install for mozdebug 05:49:46 INFO - Running setup.py install for mozdevice 05:49:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Running setup.py install for mozfile 05:49:46 INFO - Running setup.py install for mozhttpd 05:49:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Running setup.py install for mozinfo 05:49:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Running setup.py install for mozInstall 05:49:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:49:46 INFO - Running setup.py install for mozleak 05:49:46 INFO - Running setup.py install for mozlog 05:49:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:49:47 INFO - Running setup.py install for moznetwork 05:49:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:49:47 INFO - Running setup.py install for mozprocess 05:49:47 INFO - Running setup.py install for mozprofile 05:49:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:49:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:49:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:49:47 INFO - Running setup.py install for mozrunner 05:49:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:49:47 INFO - Running setup.py install for mozscreenshot 05:49:47 INFO - Running setup.py install for moztest 05:49:47 INFO - Running setup.py install for mozversion 05:49:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:49:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:49:47 INFO - Cleaning up... 05:49:47 INFO - Return code: 0 05:49:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:47 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:49:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:49:47 INFO - Using env: {'DISPLAY': ':0', 05:49:47 INFO - 'HOME': '/home/cltbld', 05:49:47 INFO - 'LANG': 'en_US.UTF-8', 05:49:47 INFO - 'LANGUAGE': 'en_US:en', 05:49:47 INFO - 'LOGNAME': 'cltbld', 05:49:47 INFO - 'MAIL': '/var/mail/cltbld', 05:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:47 INFO - 'MOZ_NO_REMOTE': '1', 05:49:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:47 INFO - 'NO_EM_RESTART': '1', 05:49:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:47 INFO - 'PWD': '/builds/slave/test', 05:49:47 INFO - 'SHELL': '/bin/bash', 05:49:47 INFO - 'SHLVL': '1', 05:49:47 INFO - 'TERM': 'linux', 05:49:47 INFO - 'TMOUT': '86400', 05:49:47 INFO - 'USER': 'cltbld', 05:49:47 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:47 INFO - '_': '/tools/buildbot/bin/python'} 05:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:49:48 INFO - Running setup.py (path:/tmp/pip-5aPxKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:49:48 INFO - Running setup.py (path:/tmp/pip-Bgh1pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:49:48 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)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:49:48 INFO - Running setup.py (path:/tmp/pip-bvNu1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:49:48 INFO - Running setup.py (path:/tmp/pip-43oVH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:49:48 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)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:49:48 INFO - Running setup.py (path:/tmp/pip-7_KhPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:49:48 INFO - Running setup.py (path:/tmp/pip-3CPZkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:49:48 INFO - Running setup.py (path:/tmp/pip-9o14aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:49:48 INFO - Running setup.py (path:/tmp/pip-IAD5xA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:49:48 INFO - Running setup.py (path:/tmp/pip-18qGT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:49:48 INFO - Running setup.py (path:/tmp/pip-wvkq2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:49:48 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)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:49:48 INFO - Running setup.py (path:/tmp/pip-X4PTza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:49:48 INFO - Running setup.py (path:/tmp/pip-TFMCsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:49:48 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)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:49:48 INFO - Running setup.py (path:/tmp/pip-8QaqTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:49:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:49:48 INFO - Running setup.py (path:/tmp/pip-HH0HEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:49:49 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)) 05:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:49 INFO - Running setup.py (path:/tmp/pip-xRIM0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:49:49 INFO - Running setup.py (path:/tmp/pip-z3Deku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:49:49 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)) 05:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:49:49 INFO - Running setup.py (path:/tmp/pip-oPz4_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:49:49 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)) 05:49:49 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)) 05:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:49:49 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)) 05:49:49 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)) 05:49:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:51 INFO - Downloading blessings-1.6.tar.gz 05:49:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:49:51 INFO - Installing collected packages: blessings 05:49:51 INFO - Running setup.py install for blessings 05:49:51 INFO - Successfully installed blessings 05:49:51 INFO - Cleaning up... 05:49:51 INFO - Return code: 0 05:49:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:49:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:51 INFO - Reading from file tmpfile_stdout 05:49:51 INFO - Current package versions: 05:49:51 INFO - argparse == 1.2.1 05:49:51 INFO - blessings == 1.6 05:49:51 INFO - blobuploader == 1.2.4 05:49:51 INFO - docopt == 0.6.1 05:49:51 INFO - functools32 == 3.2.3-2 05:49:51 INFO - jsonschema == 2.5.1 05:49:51 INFO - manifestparser == 1.1 05:49:51 INFO - mozInstall == 1.12 05:49:51 INFO - mozcrash == 0.17 05:49:51 INFO - mozdebug == 0.1 05:49:51 INFO - mozdevice == 0.48 05:49:51 INFO - mozfile == 1.2 05:49:51 INFO - mozhttpd == 0.7 05:49:51 INFO - mozinfo == 0.9 05:49:51 INFO - mozleak == 0.1 05:49:51 INFO - mozlog == 3.3 05:49:51 INFO - moznetwork == 0.27 05:49:51 INFO - mozprocess == 0.23 05:49:51 INFO - mozprofile == 0.28 05:49:51 INFO - mozrunner == 6.12 05:49:51 INFO - mozscreenshot == 0.1 05:49:51 INFO - mozsystemmonitor == 0.3 05:49:51 INFO - moztest == 0.7 05:49:51 INFO - mozversion == 1.4 05:49:51 INFO - psutil == 3.1.1 05:49:51 INFO - requests == 1.2.3 05:49:51 INFO - wsgiref == 0.1.2 05:49:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:51 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:49:51 INFO - Using env: {'DISPLAY': ':0', 05:49:51 INFO - 'HOME': '/home/cltbld', 05:49:51 INFO - 'LANG': 'en_US.UTF-8', 05:49:51 INFO - 'LANGUAGE': 'en_US:en', 05:49:51 INFO - 'LOGNAME': 'cltbld', 05:49:51 INFO - 'MAIL': '/var/mail/cltbld', 05:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:51 INFO - 'MOZ_NO_REMOTE': '1', 05:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:51 INFO - 'NO_EM_RESTART': '1', 05:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:51 INFO - 'PWD': '/builds/slave/test', 05:49:51 INFO - 'SHELL': '/bin/bash', 05:49:51 INFO - 'SHLVL': '1', 05:49:51 INFO - 'TERM': 'linux', 05:49:51 INFO - 'TMOUT': '86400', 05:49:51 INFO - 'USER': 'cltbld', 05:49:51 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:51 INFO - '_': '/tools/buildbot/bin/python'} 05:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:49:51 INFO - Cleaning up... 05:49:51 INFO - Return code: 0 05:49:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:49:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:49:51 INFO - Using env: {'DISPLAY': ':0', 05:49:51 INFO - 'HOME': '/home/cltbld', 05:49:51 INFO - 'LANG': 'en_US.UTF-8', 05:49:51 INFO - 'LANGUAGE': 'en_US:en', 05:49:51 INFO - 'LOGNAME': 'cltbld', 05:49:51 INFO - 'MAIL': '/var/mail/cltbld', 05:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:51 INFO - 'MOZ_NO_REMOTE': '1', 05:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:51 INFO - 'NO_EM_RESTART': '1', 05:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:51 INFO - 'PWD': '/builds/slave/test', 05:49:51 INFO - 'SHELL': '/bin/bash', 05:49:51 INFO - 'SHLVL': '1', 05:49:51 INFO - 'TERM': 'linux', 05:49:51 INFO - 'TMOUT': '86400', 05:49:51 INFO - 'USER': 'cltbld', 05:49:51 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:51 INFO - '_': '/tools/buildbot/bin/python'} 05:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:49:52 INFO - Cleaning up... 05:49:52 INFO - Return code: 0 05:49:52 INFO - Running post-action listener: _resource_record_post_action 05:49:52 INFO - Running post-action listener: _start_resource_monitoring 05:49:52 INFO - Starting resource monitoring. 05:49:52 INFO - [mozharness: 2016-08-10 12:49:52.118059Z] Finished create-virtualenv step (success) 05:49:52 INFO - [mozharness: 2016-08-10 12:49:52.118498Z] Running install step. 05:49:52 INFO - Running pre-action listener: _resource_record_pre_action 05:49:52 INFO - Running main action method: install 05:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:52 INFO - Reading from file tmpfile_stdout 05:49:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:49:52 INFO - Reading from file tmpfile_stdout 05:49:52 INFO - Output received: 05:49:52 INFO - Usage: mozinstall [options] installer 05:49:52 INFO - Options: 05:49:52 INFO - -h, --help show this help message and exit 05:49:52 INFO - -d DEST, --destination=DEST 05:49:52 INFO - Directory to install application into. [default: 05:49:52 INFO - "/builds/slave/test"] 05:49:52 INFO - --app=APP Application being installed. [default: firefox] 05:49:52 INFO - mkdir: /builds/slave/test/build/application 05:49:52 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'] 05:49:52 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 05:50:09 INFO - Reading from file tmpfile_stdout 05:50:09 INFO - Output received: 05:50:09 INFO - /builds/slave/test/build/application/firefox/firefox 05:50:09 INFO - Running post-action listener: _resource_record_post_action 05:50:09 INFO - [mozharness: 2016-08-10 12:50:09.278909Z] Finished install step (success) 05:50:09 INFO - [mozharness: 2016-08-10 12:50:09.279158Z] Running run-tests step. 05:50:09 INFO - Running pre-action listener: _resource_record_pre_action 05:50:09 INFO - Running main action method: run_tests 05:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:50:09 INFO - Python 2.7.3 05:50:09 INFO - Return code: 0 05:50:09 INFO - grabbing minidump binary from tooltool 05:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:09 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 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:50:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:50:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:50:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:50:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:50:09 INFO - Return code: 0 05:50:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:50:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:50:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:50:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:50:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:50:09 INFO - Using env: {'DISPLAY': ':0', 05:50:09 INFO - 'HOME': '/home/cltbld', 05:50:09 INFO - 'LANG': 'en_US.UTF-8', 05:50:09 INFO - 'LANGUAGE': 'en_US:en', 05:50:09 INFO - 'LOGNAME': 'cltbld', 05:50:09 INFO - 'MAIL': '/var/mail/cltbld', 05:50:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:50:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:09 INFO - 'MOZ_NO_REMOTE': '1', 05:50:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:50:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:50:09 INFO - 'NO_EM_RESTART': '1', 05:50:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:09 INFO - 'PWD': '/builds/slave/test', 05:50:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:50:09 INFO - 'SHELL': '/bin/bash', 05:50:09 INFO - 'SHLVL': '1', 05:50:09 INFO - 'TERM': 'linux', 05:50:09 INFO - 'TMOUT': '86400', 05:50:09 INFO - 'USER': 'cltbld', 05:50:09 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897', 05:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:09 INFO - '_': '/tools/buildbot/bin/python'} 05:50:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:50:09 INFO - mozversion application_buildid: 20160810030202 05:50:09 INFO - mozversion application_changeset: 6cf0089510fad8deb866136f5b92bbced9498447 05:50:09 INFO - mozversion application_display_name: Nightly 05:50:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:50:09 INFO - mozversion application_name: Firefox 05:50:09 INFO - mozversion application_remotingname: firefox 05:50:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:50:09 INFO - mozversion application_vendor: Mozilla 05:50:09 INFO - mozversion application_version: 51.0a1 05:50:09 INFO - mozversion platform_buildid: 20160810030202 05:50:09 INFO - mozversion platform_changeset: 6cf0089510fad8deb866136f5b92bbced9498447 05:50:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:50:09 INFO - mozversion platform_version: 51.0a1 05:50:09 INFO - using testdate: 1470833409 05:50:09 INFO - actual date: 1470833409 05:50:09 INFO - starting webserver on 'localhost:33951' 05:50:09 INFO - SUITE-START | Running 2 tests 05:50:09 INFO - TEST-START | dromaeo_css 05:50:09 INFO - operating with platform_type : linux_ 05:50:09 INFO - Initialising browser for dromaeo_css test... 05:50:09 INFO - TEST-INFO | started process 3930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM01_Eu/profile http://localhost:33951/getInfo.html) 05:50:13 INFO - PROCESS | 3930 | __metrics Screen width/height:1600/1200 05:50:13 INFO - PROCESS | 3930 | colorDepth:24 05:50:13 INFO - PROCESS | 3930 | Browser inner width/height: 1024/697 05:50:13 INFO - PROCESS | 3930 | __metrics 05:50:14 INFO - PROCESS | 3930 | 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" 05:50:14 INFO - PROCESS | 3930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:50:18 INFO - PROCESS | 3930 | console.error: PushService: 05:50:18 INFO - PROCESS | 3930 | stateChangeProcessEnqueue: Error transitioning state 05:50:18 INFO - PROCESS | 3930 | UnknownError 05:50:18 INFO - TEST-INFO | 3930: exit 0 05:50:18 INFO - Browser initialized. 05:50:18 INFO - Running cycle 1/1 for dromaeo_css test... 05:50:18 INFO - TEST-INFO | started process 4012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM01_Eu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:30 INFO - PROCESS | 4012 | RSS: Main: 155516928 05:50:30 INFO - PROCESS | 4012 | 05:51:22 INFO - PROCESS | 4012 | 1470833482152 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] 05:53:31 INFO - PROCESS | 4012 | Cycle 1(1): loaded http://localhost:33951/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33951/tests/dromaeo/cssquery-ext.html) 05:53:32 INFO - PROCESS | 4012 | RSS: Main: 267014144 05:53:32 INFO - PROCESS | 4012 | 05:56:35 INFO - PROCESS | 4012 | Cycle 1(1): loaded http://localhost:33951/tests/dromaeo/cssquery-ext.html (next: http://localhost:33951/tests/dromaeo/cssquery-jquery.html) 05:56:35 INFO - PROCESS | 4012 | RSS: Main: 243896320 05:56:35 INFO - PROCESS | 4012 | 05:59:37 INFO - PROCESS | 4012 | Cycle 1(1): loaded http://localhost:33951/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33951/tests/dromaeo/cssquery-mootools.html) 05:59:38 INFO - PROCESS | 4012 | RSS: Main: 206761984 05:59:38 INFO - PROCESS | 4012 | 06:02:40 INFO - PROCESS | 4012 | Cycle 1(1): loaded http://localhost:33951/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33951/tests/dromaeo/cssquery-prototype.html) 06:02:40 INFO - PROCESS | 4012 | RSS: Main: 225861632 06:02:40 INFO - PROCESS | 4012 | 06:05:43 INFO - PROCESS | 4012 | Cycle 1(1): loaded http://localhost:33951/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33951/tests/dromaeo/cssquery-yui.html) 06:05:44 INFO - PROCESS | 4012 | RSS: Main: 181600256 06:05:44 INFO - PROCESS | 4012 | 06:08:46 INFO - PROCESS | 4012 | Cycle 1(1): loaded http://localhost:33951/tests/dromaeo/cssquery-yui.html (next: http://localhost:33951/tests/dromaeo/cssquery-dojo.html) 06:08:47 INFO - PROCESS | 4012 | RSS: Main: 277950464 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | __start_tp_report 06:08:47 INFO - PROCESS | 4012 | _x_x_mozilla_page_load 06:08:47 INFO - PROCESS | 4012 | _x_x_mozilla_page_load_details 06:08:47 INFO - PROCESS | 4012 | |i|pagename|runs| 06:08:47 INFO - PROCESS | 4012 | |0;dojo.html;3019.8037127586704;3023.7429818465434;3045.4518186726395;3069.570260163579;3075.3080556874706;5242.004019236342;5242.266082748409;5257.369115706116;5366.221897824813;5398.24424580559;5407.540359069474;5412.269343638608;5465.863353416166;5479.17812328151;5485.067538518451;4852.4662287148385;4934.5312195341385;4954.603631709472;4964.280179373988;5131.794728210867;5003.924941125886;5062.493750624945;5073.239014147871;5079.974600126994;5094.312267843849;4778.235482322829;4816.325714399987;4817.156997525454;4887.3402090717655;4968.40379154502;4833.10587541306;4834.057358815033;4836.129496690594;4921.483244259372;4956.87607809804;4881.1213981483315;4882.340883980688;4883.633727470418;4906.460289368167;4936.2102063669645;5170.638055336128;5326.387465441485;5381.327334083239;5421.051316019681;5430.83707488776;5818.854528636775;5820.18517407559;5842.941570584282;5847.883042339172;5868.853278668067;5855.736491857877;5888.470037696585;5890.410958904118;5899.410058994109;5910.438508341744;5282.154855223146;5334.066538355811;5397.59518036141;5405.918911216335;5498.5876059295715;5948.167256584082;5983.311919129269;6004.639721616717;6004.6697431640805;6011.639301641915;5721.742521586539;5723.971380143073;5753.33836608787;5762.567807414461;5770;5577.498025177753;5709.714514274269;5751.626144300691;5762.625429347079;5869.941300587025;5740;5786.768529258782;5791.015527360359;5796.072628379439;5798.072308430716;5756.0502517084215;5807.216025836543;5813.738381772746;5817.912731309034;5831.416858314219;4334.913301733947;4345.435093437896;4348.630366418826;4370.737755734666;4396.472423309159;3958.1687845552756;3967;3974.5230572331507;3993.3211354069877;4003.4795476587856;5482.862928426821;5490.835274941758;5495.697736624444;5497.615166938356;5558.638688485235;5606.85982850432;5730.570207234474;5784.82645520635;5798.82603521895;5817.825465236049;5534.142207957773;5534.916976245359;5538.169274608841;5569.610127291132;5594.412586678422;5070.315507406527;5078.695278283315;5078.923816142761;5080.898382032338;5086.033653605803;5840.620359676607;5921.467067963904;5960.523158147251;6096.390360963868;6110.877782444326;5766.913496297643;5789.363170051375;5794.217780599565;5828.825135245949;5837.124431335334;6001.729922153426;6019.849503762441;6036.607620504653;6038.5772995889865;6044.123602077762;5569.220309156803;5600.943990560042;5605.383407825055;5735.025045742151;5736.168255602997;5776.971115144397;5796.1305804129715;5797.159411885337;5813.157092221689;5845.386234445322;5608.242887210175;5643.492085712305;5656.3495198051105;5659.462351076641;5659.462351076641;5541.944580554304;5560.610757246954;5561.3882472927135;5632.915506267491;5642.943570564242;5560.555155587463;5564.165375193591;5573.303337082864;5596.9440305598055;5612.270404847343;5670.574706896917;5691.174779656843;5715.342735585295;5739.483446489836;5742.885142297047;5375.3280839895015;5379.354477462573;5438.374586922397;5570.721463926869;5578.358488773681 06:08:47 INFO - PROCESS | 4012 | |1;ext.html;580.892534881047;585.4906231578526;604.3503234023426;612.6814056690519;614.1708693264094;32721.345573088525;43385.56614433855;44319.77840110803;44627.55372446275;44639.10721785562;45435.31847022297;45957.31064034042;45965.54034459654;45973.77013114938;45980;9054.185123338882;9200.585973631169;9213.815723685522;9214.493202873839;9216.585253663601;8898.777530561723;8997.05530919254;9016.233620142295;9030.548472576378;9030.593623286968;9013.504257265848;9117.997020327759;9122.452652840817;9125.954370228174;9140.085991400872;9073.13805188506;9108.134727200906;9114.13415725505;9120.179183873417;9122;8946.463212207223;9038.051004644549;9042.683506077321;9051.773705657346;9055.547222638841;42796.786016069724;43137.35293970593;43193;43207;43226.56773432257;3324.817135057571;3352.2625022495013;3382.120648631349;3441;3825.158465137665;33417.16457088568;33725.494117588256;33989.320213595835;34885;35035.474467883;4417.624501917341;4458.9331160032625;4498.707584007062;4507.076049409863;4527.616675679554;247842;248048;248069;248512;251770;57691.711541442026;58033.419665803645;58492.70753646205;58592;58594;60564;60857;61278;61309.693451532454;61386.69306653528;16277.837221627633;16462.835371646368;16531.6693666126;16533.173341332884;16554.42059527911;12547.811782823264;12574.81137782934;12607.621771346874;12655.177413468095;12671.936640316924;16736.41422550205;16929.492115236564;16993.150342482826;17000.23498942526;17000.469957703866;5810.389909059572;5943.790353209219;5997.670128142907;6145.385461453819;6179.16581261533;944.8157609266127;1043.0299821166388;1089.5369467976236;1094.9671509854718;1100.7248187953012;33025.66974330226;33185.4153904387;33256.501152482735;33458.66541334604;33856.66143338535;885;1058.692979036071;1061.2836335473678;1106.0930037369442;1184.5084290019718;17012.57468563271;17121.22954467052;17146.22841972114;17199.31202751901;17277.13614319279;469725;471377;472524;475399;476098;57318;57375.42624573784;57391.13913291304;57675.71162144246;57713.71143144257;1211.6849619099098;1267.524678245658;1326.316946772413;1344.610063081715;1345.280275052842;32709;33972.83013584916;34075.14812129716;34269.314613708084;34318.656813431546;49172.26241606451;50564.24153637772;51023.23465147951;51100.488995109576;51177.488225117275;359159;361387;362988;364184;502604;29267.268318292212;29286.26784330409;29323.41353172967;29642.25894352572;29664.258393540335;48815.75592122017;49068.75465622649;49116.26325605047;49126.5087349122;50462;41362.172756545304;42044.36933445939;42240.577594223665;42701;44211.55788442203;42812.57187428086;43004;43347.133057339306;43591;43649.34525982176;41791.79104104581;42358.15283694371;42671;43025.78487107545;43590.78204608957;35753.46369804403;37606.811965939996;37934.62065379311;38486.23027539378;38558.80720596491;28609.4504141259;28729.856350718113;28819.423611527232;29041.273968150967;29248.707512924597 06:08:47 INFO - PROCESS | 4012 | |2;jquery.html;365.67454965081066;378.8371000469797;384.095455202997;385.60090306532743;400.69747340757726;17711.025893575847;19760.90119549404;19797.50506237345;19914.502137446554;21182.152713891464;19196.328128515506;19321.806781932177;19572.510687232825;19794;20306.18775248992;5456.208849716789;5746.6782639992825;5756.827295181151;5908.645481271137;5949.0481522956115;5795;5833.591648584601;5839.240898683165;5844.211031510734;5847.678377689225;5720.485156335929;5771.105478650816;5789.247397838275;5808.56435767317;5818.970905145468;5678.318601767794;5815.476607105338;5815.854603634901;5818.854528636775;5819.156222347757;6377.075324077982;6766.492513061491;6860.554063985875;6914.792556223321;6924.134483189601;24507.897144628536;25316.746832531808;25399.873000635063;27449.725502745117;27751.861240693666;2187.289111190506;2280.5552917181158;2311.3412677386864;2396.5206958608346;2555.910543131;15487.922560387238;15782.289796959165;16286.918565407215;16598.170091495376;16834.15829208547;2041.652919003773;2064.9277275295294;2072.5440403111293;2073.139647046474;2103.5897999889876;91074.54462727711;100174;100317;101136.49431752868;103228.48385758023;17278.308867645406;18646.34737784171;18675.81324186768;18684.626307473773;18709.287153362686;33534.3293134136;33596.49605255923;33694.3261134776;33978.49032264518;34019.82990085034;9817.705468835946;9857.324254876688;9867.901320986843;9903.306768526134;10153;8089.959550202211;8129.552874591955;8146.877796833052;8336.374771892133;8351.8329633407;9065.546722663972;9116.544172791333;9183.632654693738;9347.766305842408;9493.145616894375;3405.455127179689;3490.982545087309;3560.9317204838444;3720.5535335759882;3726.7950262735267;1000.2046326843333;1003.9046290602381;1033.5917312661456;1078.928457361867;1079.6868908016588;19597.118129684197;20748.688769668166;20855.687164692237;21841.453963650718;21858;972.5672075926286;973.8052389521982;984.1536278800226;991.9305648604673;1009.4044513736931;9644.469554174588;11354.716132096599;11374.033207177317;11449.141314401286;11524.135689823297;488820;506102;510193;521068;523966;64409;94248;95291;99993;101694;990.6631745206664;1036.8237399642078;1046.0899017854363;1049.6221360310283;1059.9152067834555;17285.395011174547;17823.376181833573;18100.818991809912;18217.362392316205;18593.721094183868;36555.81722091373;43363.132737344444;43371.783141084095;44291.55708443003;44861;56871.71564142153;61236.693816530635;62586.374136258055;62631.6868415655;62686.373136269875;7573.431992600466;7928.643211055401;8541.829163416822;8646.697365592174;8871.068537803696;12478.563250286197;12876.22742635445;12984.870151298366;13074.738505230032;13324.733505330032;11275;11307.151963602599;11347.106487845876;11503.827442588201;11729.648110556696;6210.7515699371515;11565;11615.593454229062;11707.473163707484;11781.764364712486;11930.343831089027;12006.21959572625;12041;12167.209131406431;12423.875761242272;7339.412846972336;7524.247575242651;7747.806304842198;7891.053073630971;8003.159668234985;8271.379646526655;11810.586629467927;11871.050315974873;11906.987906028085;11945.16383853122 06:08:47 INFO - PROCESS | 4012 | |3;mootools.html;1362.7410791949524;1466.1935935235622;1527.0379660813685;1643.5973186569292;1766.2316892151923;8015.799605009877;8340.165983401657;8415.747527574167;8575.356848236392;8583.914160858389;8430.452020618655;8469.05211801286;8482.618282177302;8527.78680532987;8540;2385.105585405473;2407.6508906208587;2408.205292253552;2410.8432951858163;2414.2033129067368;2331.5453486570127;2346.307839187435;2386.916457923973;2387.8328517003815;2390.7011623547055;2315.3285547191294;2338.2751347082376;2345.296411076679;2348.049040138738;2352.329586067971;2241.450844543081;2255.4248666589997;2261.231181398324;2261.4685548895995;2305.250793492122;2244.147224054865;2249.325202439262;2252.042881775239;2254.537819746948;2255.8082562982167;8469.153084691543;8521.275691566225;8521.914780852174;8522.786930326729;8741;4981.227909674007;5120.436751957282;5164.715940623264;5177.637565370425;5179.637425380225;3133.796303240305;3302.4220761366905;3357.0432426758257;3363.3273345330786;3367.1413789483768;1076.8330908709163;1078.6386560502162;1080.486768784821;1099.8020356335855;1106.9169812264113;12101.092418068592;12273.631791046282;12331.198472099284;12332.19840710351;12357.629271121881;4449.555044495524;4528.411306530196;4683.718976861399;4701.012787314702;4709.7174169549935;10363.170946324275;10399.94800025995;10433.634822781318;10436;10444.21668374875;4963.8262660806695;5011.273365362002;5013.473585273567;5015.423226329019;5025.572826309804;4270.572942705705;4523.095380923828;4608.907821843544;4652.232381657056;4687.95312046882;3933.9213215735526;3957.3668213086344;4081.9591804082174;4109.794510274474;4115.835366585303;1247.2017908538362;1268.099649249025;1275.4451813461174;1285.3508977966067;1306.5884246462333;966.3960024984385;969.2004096620176;986.1223511075005;996.2080146283722;1003.2275148135894;6102.847428814315;6168.784092556739;6210.4100110489435;6230.56386052981;6315.778947736807;484.7503535679128;488.21884984025274;491.91391506486224;493.53854146373254;495.7075325557938;4179.811908464127;4190.141021090669;4245.87262382129;4324.2648749712625;4385.144896745104;10647.361158330525;11061.115110791117;11072;11125.221234513507;11134.33194008362;10997.175211859143;11000.119990400748;11002.669919902255;11013.174011949135;11027.338359698444;7769.72805951789;8096.230858068474;8105.311048560939;8286.337093032544;8355.540445275568;5915.290165180206;6110.572259941761;6177.351378105234;6178.505719542336;6376.107344971801;10185.439800811026;10421.114205292635;10452.111570516287;10458.947705261426;10471.057604815604;9767.755806104904;9862;10019.699409017741;10162.187025038127;10169.898301016896;7095.8935615964765;7874.291313781787;7979;8037.276645101969;8244.505329680238;8396.454230475;8670.13298670128;8674.175953284428;8683;8686.478811271343;7639.314985807837;7740.148583655906;7841.686332546634;7998.48009879356;8003.679852806055;7526.774196774106;7617.65720542588;7635.541867487968;7709.691612335444;7730.7680769577;6924.515283930076;7042.330978557029;7042.507024508235;7046.577205367695;7055.858882822212;5386.569074473954;5389.649672771257;5486.177073439016;5493.505584497414;5593.300837395325;5297.708626025606;5379.569634429158;5408.59435542328;5433.538149257357;5470.589705772003 06:08:47 INFO - PROCESS | 4012 | |4;prototype.html;315.8042013951349;327.4057585482349;332.11822610967874;334.6904113694832;336.6364326527351;5027.421846487655;5091.592672586194;5182.704323919021;5224.555912747416;5225.294585230993;5043.344365232524;5076.36545431821;5141.22881567765;5162.251473536345;5168.2764412982215;4116.073883376232;4116.650084742801;4125.504939407276;4129.855455059066;4135.710500264982;3982.721209515335;4107.507099148106;4152.2733521633745;4160;4164;4112.527059388179;4114.465119534471;4123.731947428917;4127.896802579945;4131.8553850615235;4090.713650044498;4101.52832424272;4103.158852435258;4126.525449573277;4154.397612346193;3552.964470355289;3677.6506231907924;3684.355237833395;3694.3719567673293;3736.5142531470733;5003.4746351633285;5028.270900719375;5032.597392208614;5034.370703662043;5053.115704751691;4663.417072865892;4667.4632417272105;4679.929801052953;4697.295405689139;4718.882027949295;5252.23842542833;5299.682019078829;5299.867503312409;5358.91961620576;5376.784928602851;3978.1446988897237;3979.880603581897;3992.121733218687;4005.218982298453;4010.8341495592344;5549.972250138723;5572.631631556572;5590.546457920929;5625;5655.812279421327;3923.3330333843314;4097.8770636880945;4116.773577453268;4130.855420060284;4136.73111247774;4149.522804877417;4149.8962525937095;4156.314208155621;4160.1471698301775;4162.5629308922735;4687.835925742651;4687.882802929954;4690;4698.271767875985;4721.669483136147;5142.768575414116;5387.24578559002;5419.593530485229;5441.319835020622;5441.61238884082;1355.4510423278589;1396.4902810474307;1420.8295004599515;1432.4341884955384;1435.9066660667024;990.0841721407669;992.4591097851688;1004.9296549241483;1012.5038731021747;1025.1183981775666;5134.152864777345;5142.537171654569;5147.948520514747;5153.974230128825;5155.252488389163;5343.732813359316;5395.05586522357;5407.674280317577;5445.809396671098;5449.427810079964;5041.8487445376695;5115.590752739847;5117.181250999822;5134.947335796152;5234.92147617786;2922.6638936522145;2927.6486821581548;2939.2211064068038;2939.808912420686;2940.9411811763644;5759.539236861041;5771.769129234784;5776.624519406225;5791.5656325775735;5814.418558144152;5557.3331200256225;5643.971780141073;5657.43425657431;5683.374828768832;5697.173909783141;5747.109198074305;5756.107803290497;5763.682997435181;5767.22142510764;5768.192453056491;5281.339832520935;5526.778928842801;5527.778888844401;5563.109902415594;5572.442755724557;5456.645318054314;5514.5864060196445;5521.585881058856;5525.806596769094;5530.787684926062;5714.342850572238;5720.570957178145;5726.656400615976;5777.429044075346;5793.681347525758;3787.6401741834484;4010.7794071326357;4024.678025757874;4026.7382620129597;4050.5341885682346;5335.132703553725;5347.075052981001;5518.972405138109;5528.530074943675;5533.750981205936;5427.3215848019;5440.29276194108;5443.836684899459;5444.564434845282;5455.863603409788;5424.372688193605;5455.427180146027;5469.699166545719;5479.3972663007835;5486.835394938158;5217.713025783618;5328.866778330573;5339.973300133474;5362.65142765719;5437.4562543745305;5426.348838139448;5438.347398312069;5442.782288708566;5444.26502422168;5444.782208711607;5337.306150200449;5343.198520221998;5369.140937449989;5371.40914499397;5385.299911011543 06:08:47 INFO - PROCESS | 4012 | |5;yui.html;723.9276072392761;774.5585016540571;797.3860127701666;831.0401486283345;831.5010993403962;15527.689446211069;16442.506724798277;16848.49454516365;16864.156792160404;17443.73834392485;14270.357833897473;14447.133172009686;14458.855411445882;14468.421263149488;14472.565823025327;632.4023797511354;800.655718041245;817.709713051867;835.6490274084869;838.9244967952883;840.0717207485732;851.953142577158;855.8844555984956;858.6608289725555;860.9483430994161;894.6197865906996;900.3697411811726;901.5086777706166;901.8782464367292;901.9762569483651;703.4196787650158;855.9144085591454;897.717219075989;898.5731777405746;898.8786513820648;640.2061443809642;641.2081079866362;641.9903701444435;642.0209180998967;642.2998931165052;16064.357425702958;16144.515664530083;16151.353945842153;16157.83842161575;16167.595810104724;15048.398064077424;15209.467668631545;15247.61880952974;15265.771013434807;15405.306761195772;13163.868361316456;13454.865451345557;13583.388747506386;13740;14067.577972660836;4510.458744950594;4535.977320113411;4544.068465964469;4628.606568441686;4654.86035418938;82017.76973345404;82560;83697.58151209266;85712.14287857105;85840.57079714624;11854.170208085352;11880.584179553674;11907.88092119085;12133.817992730115;12180;22671.659925101136;22757.43106422353;22767.886160569422;22776.20283290077;22780.43048923757;8524.275436587835;8671.4363566368;8943.642254309754;9045.457272563535;9101.317401194938;4634.652401069933;4799.328094066834;4866.391701037371;4886.486918873538;5062.7215503146945;3238.9676103238667;3319.3195394943978;3319.502074688816;3336.466165413522;3342.8328583570724;549.1022178737712;551.310861423221;563.1327755256858;567.7076305702567;568.3407247592772;700.1248439450686;704.6237210087004;736.5359823311387;738.103204606395;747.8691229034897;9366.250699944125;9609.38201182734;10063.144632706155;10312.948435257777;10411.375317480975;556.1796350383153;613.3130893399422;695.6139342664768;706.9893951590627;718.7359816577717;4562.749048802281;4813.133635945564;4971.626078258564;4994.4423121975515;4998.531308032011;83878;99364.50317748365;124010;126024.36987815003;129096;95823.52088239654;97090.5145474268;97352;97671;98999.50500247453;56696.71651641715;81570.59214703889;82282;87082.56458717666;90794.99999999868;10231.181505479395;10290.588376465157;10312.948435257777;10442.216833737652;10509.211809114195;39090;39750;49022.75488622534;49266;50962.23556646579;70864.64567677128;71243;75607;76205.61897190478;76951.23048769665;13412.597622071353;13473.663158421117;13522.25627590492;13573.59279221625;14265.857341426869;15260.923695381452;15459.458918937784;15600.309450725053;15810.367585296459;16231.269592868113;14299.785503217669;14623.415063397344;14950.075297087807;15122.773158402413;15704.293306800993;14488.927555362156;14943.701125977203;15098.622534436727;15196.620084497534;15620.453284135001;13660.795088073488;13985.790213147015;14074.225917574631;14088.506902258372;14143.575692729235;12088.39558022113;12135.393230338625;12333.814992774936;12428.75142497127;12574;10772.299800512941;10968.451577420938;10973.945130274298;12360.876391235972;12454.564090256797 06:08:47 INFO - PROCESS | 4012 | __end_tp_report 06:08:47 INFO - PROCESS | 4012 | __start_cc_report 06:08:47 INFO - PROCESS | 4012 | _x_x_mozilla_cycle_collect,922 06:08:47 INFO - PROCESS | 4012 | __end_cc_report 06:08:47 INFO - PROCESS | 4012 | __startTimestamp1470834527131__endTimestamp 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | ------- Summary: start ------- 06:08:47 INFO - PROCESS | 4012 | Number of tests: 6 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | [#0] dojo.html Cycles:165 Average:5377.74 Median:5569.61 stddev:624.44 (11.2%) stddev-sans-first:598.33 06:08:47 INFO - PROCESS | 4012 | Values: 3019.8 3023.7 3045.5 3069.6 3075.3 5242.0 5242.3 5257.4 5366.2 5398.2 5407.5 5412.3 5465.9 5479.2 5485.1 4852.5 4934.5 4954.6 4964.3 5131.8 5003.9 5062.5 5073.2 5080.0 5094.3 4778.2 4816.3 4817.2 4887.3 4968.4 4833.1 4834.1 4836.1 4921.5 4956.9 4881.1 4882.3 4883.6 4906.5 4936.2 5170.6 5326.4 5381.3 5421.1 5430.8 5818.9 5820.2 5842.9 5847.9 5868.9 5855.7 5888.5 5890.4 5899.4 5910.4 5282.2 5334.1 5397.6 5405.9 5498.6 5948.2 5983.3 6004.6 6004.7 6011.6 5721.7 5724.0 5753.3 5762.6 5770.0 5577.5 5709.7 5751.6 5762.6 5869.9 5740.0 5786.8 5791.0 5796.1 5798.1 5756.1 5807.2 5813.7 5817.9 5831.4 4334.9 4345.4 4348.6 4370.7 4396.5 3958.2 3967.0 3974.5 3993.3 4003.5 5482.9 5490.8 5495.7 5497.6 5558.6 5606.9 5730.6 5784.8 5798.8 5817.8 5534.1 5534.9 5538.2 5569.6 5594.4 5070.3 5078.7 5078.9 5080.9 5086.0 5840.6 5921.5 5960.5 6096.4 6110.9 5766.9 5789.4 5794.2 5828.8 5837.1 6001.7 6019.8 6036.6 6038.6 6044.1 5569.2 5600.9 5605.4 5735.0 5736.2 5777.0 5796.1 5797.2 5813.2 5845.4 5608.2 5643.5 5656.3 5659.5 5659.5 5541.9 5560.6 5561.4 5632.9 5642.9 5560.6 5564.2 5573.3 5596.9 5612.3 5670.6 5691.2 5715.3 5739.5 5742.9 5375.3 5379.4 5438.4 5570.7 5578.4 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | [#1] ext.html Cycles:180 Average:54780.43 Median:43200.00 stddev:101345.04 (234.6%) stddev-sans-first:101547.64 06:08:47 INFO - PROCESS | 4012 | Values: 580.9 585.5 604.4 612.7 614.2 32721.3 43385.6 44319.8 44627.6 44639.1 45435.3 45957.3 45965.5 45973.8 45980.0 9054.2 9200.6 9213.8 9214.5 9216.6 8898.8 8997.1 9016.2 9030.5 9030.6 9013.5 9118.0 9122.5 9126.0 9140.1 9073.1 9108.1 9114.1 9120.2 9122.0 8946.5 9038.1 9042.7 9051.8 9055.5 42796.8 43137.4 43193.0 43207.0 43226.6 3324.8 3352.3 3382.1 3441.0 3825.2 33417.2 33725.5 33989.3 34885.0 35035.5 4417.6 4458.9 4498.7 4507.1 4527.6 247842.0 248048.0 248069.0 248512.0 251770.0 57691.7 58033.4 58492.7 58592.0 58594.0 60564.0 60857.0 61278.0 61309.7 61386.7 16277.8 16462.8 16531.7 16533.2 16554.4 12547.8 12574.8 12607.6 12655.2 12671.9 16736.4 16929.5 16993.2 17000.2 17000.5 5810.4 5943.8 5997.7 6145.4 6179.2 944.8 1043.0 1089.5 1095.0 1100.7 33025.7 33185.4 33256.5 33458.7 33856.7 885.0 1058.7 1061.3 1106.1 1184.5 17012.6 17121.2 17146.2 17199.3 17277.1 469725.0 471377.0 472524.0 475399.0 476098.0 57318.0 57375.4 57391.1 57675.7 57713.7 1211.7 1267.5 1326.3 1344.6 1345.3 32709.0 33972.8 34075.1 34269.3 34318.7 49172.3 50564.2 51023.2 51100.5 51177.5 359159.0 361387.0 362988.0 364184.0 502604.0 29267.3 29286.3 29323.4 29642.3 29664.3 48815.8 49068.8 49116.3 49126.5 50462.0 41362.2 42044.4 42240.6 42701.0 44211.6 42812.6 43004.0 43347.1 43591.0 43649.3 41791.8 42358.2 42671.0 43025.8 43590.8 35753.5 37606.8 37934.6 38486.2 38558.8 28609.5 28729.9 28819.4 29041.3 29248.7 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | [#2] jquery.html Cycles:180 Average:31412.27 Median:33836.41 stddev:84237.60 (249.0%) stddev-sans-first:84441.65 06:08:47 INFO - PROCESS | 4012 | Values: 365.7 378.8 384.1 385.6 400.7 17711.0 19760.9 19797.5 19914.5 21182.2 19196.3 19321.8 19572.5 19794.0 20306.2 5456.2 5746.7 5756.8 5908.6 5949.0 5795.0 5833.6 5839.2 5844.2 5847.7 5720.5 5771.1 5789.2 5808.6 5819.0 5678.3 5815.5 5815.9 5818.9 5819.2 6377.1 6766.5 6860.6 6914.8 6924.1 24507.9 25316.7 25399.9 27449.7 27751.9 2187.3 2280.6 2311.3 2396.5 2555.9 15487.9 15782.3 16286.9 16598.2 16834.2 2041.7 2064.9 2072.5 2073.1 2103.6 91074.5 100174.0 100317.0 101136.5 103228.5 17278.3 18646.3 18675.8 18684.6 18709.3 33534.3 33596.5 33694.3 33978.5 34019.8 9817.7 9857.3 9867.9 9903.3 10153.0 8090.0 8129.6 8146.9 8336.4 8351.8 9065.5 9116.5 9183.6 9347.8 9493.1 3405.5 3491.0 3560.9 3720.6 3726.8 1000.2 1003.9 1033.6 1078.9 1079.7 19597.1 20748.7 20855.7 21841.5 21858.0 972.6 973.8 984.2 991.9 1009.4 9644.5 11354.7 11374.0 11449.1 11524.1 488820.0 506102.0 510193.0 521068.0 523966.0 64409.0 94248.0 95291.0 99993.0 101694.0 990.7 1036.8 1046.1 1049.6 1059.9 17285.4 17823.4 18100.8 18217.4 18593.7 36555.8 43363.1 43371.8 44291.6 44861.0 56871.7 61236.7 62586.4 62631.7 62686.4 7573.4 7928.6 8541.8 8646.7 8871.1 12478.6 12876.2 12984.9 13074.7 13324.7 11275.0 11307.2 11347.1 11503.8 11729.6 6210.8 11565.0 11615.6 11707.5 11781.8 11930.3 12006.2 12041.0 12167.2 12423.9 7339.4 7524.2 7747.8 7891.1 8003.2 8271.4 11810.6 11871.1 11907.0 11945.2 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | [#3] mootools.html Cycles:180 Average:5799.71 Median:4685.84 stddev:3315.94 (70.8%) stddev-sans-first:3308.47 06:08:47 INFO - PROCESS | 4012 | Values: 1362.7 1466.2 1527.0 1643.6 1766.2 8015.8 8340.2 8415.7 8575.4 8583.9 8430.5 8469.1 8482.6 8527.8 8540.0 2385.1 2407.7 2408.2 2410.8 2414.2 2331.5 2346.3 2386.9 2387.8 2390.7 2315.3 2338.3 2345.3 2348.0 2352.3 2241.5 2255.4 2261.2 2261.5 2305.3 2244.1 2249.3 2252.0 2254.5 2255.8 8469.2 8521.3 8521.9 8522.8 8741.0 4981.2 5120.4 5164.7 5177.6 5179.6 3133.8 3302.4 3357.0 3363.3 3367.1 1076.8 1078.6 1080.5 1099.8 1106.9 12101.1 12273.6 12331.2 12332.2 12357.6 4449.6 4528.4 4683.7 4701.0 4709.7 10363.2 10399.9 10433.6 10436.0 10444.2 4963.8 5011.3 5013.5 5015.4 5025.6 4270.6 4523.1 4608.9 4652.2 4688.0 3933.9 3957.4 4082.0 4109.8 4115.8 1247.2 1268.1 1275.4 1285.4 1306.6 966.4 969.2 986.1 996.2 1003.2 6102.8 6168.8 6210.4 6230.6 6315.8 484.8 488.2 491.9 493.5 495.7 4179.8 4190.1 4245.9 4324.3 4385.1 10647.4 11061.1 11072.0 11125.2 11134.3 10997.2 11000.1 11002.7 11013.2 11027.3 7769.7 8096.2 8105.3 8286.3 8355.5 5915.3 6110.6 6177.4 6178.5 6376.1 10185.4 10421.1 10452.1 10458.9 10471.1 9767.8 9862.0 10019.7 10162.2 10169.9 7095.9 7874.3 7979.0 8037.3 8244.5 8396.5 8670.1 8674.2 8683.0 8686.5 7639.3 7740.1 7841.7 7998.5 8003.7 7526.8 7617.7 7635.5 7709.7 7730.8 6924.5 7042.3 7042.5 7046.6 7055.9 5386.6 5389.6 5486.2 5493.5 5593.3 5297.7 5379.6 5408.6 5433.5 5470.6 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | [#4] prototype.html Cycles:180 Average:4586.60 Median:5150.96 stddev:1317.26 (25.6%) stddev-sans-first:1281.36 06:08:47 INFO - PROCESS | 4012 | Values: 315.8 327.4 332.1 334.7 336.6 5027.4 5091.6 5182.7 5224.6 5225.3 5043.3 5076.4 5141.2 5162.3 5168.3 4116.1 4116.7 4125.5 4129.9 4135.7 3982.7 4107.5 4152.3 4160.0 4164.0 4112.5 4114.5 4123.7 4127.9 4131.9 4090.7 4101.5 4103.2 4126.5 4154.4 3553.0 3677.7 3684.4 3694.4 3736.5 5003.5 5028.3 5032.6 5034.4 5053.1 4663.4 4667.5 4679.9 4697.3 4718.9 5252.2 5299.7 5299.9 5358.9 5376.8 3978.1 3979.9 3992.1 4005.2 4010.8 5550.0 5572.6 5590.5 5625.0 5655.8 3923.3 4097.9 4116.8 4130.9 4136.7 4149.5 4149.9 4156.3 4160.1 4162.6 4687.8 4687.9 4690.0 4698.3 4721.7 5142.8 5387.2 5419.6 5441.3 5441.6 1355.5 1396.5 1420.8 1432.4 1435.9 990.1 992.5 1004.9 1012.5 1025.1 5134.2 5142.5 5147.9 5154.0 5155.3 5343.7 5395.1 5407.7 5445.8 5449.4 5041.8 5115.6 5117.2 5134.9 5234.9 2922.7 2927.6 2939.2 2939.8 2940.9 5759.5 5771.8 5776.6 5791.6 5814.4 5557.3 5644.0 5657.4 5683.4 5697.2 5747.1 5756.1 5763.7 5767.2 5768.2 5281.3 5526.8 5527.8 5563.1 5572.4 5456.6 5514.6 5521.6 5525.8 5530.8 5714.3 5720.6 5726.7 5777.4 5793.7 3787.6 4010.8 4024.7 4026.7 4050.5 5335.1 5347.1 5519.0 5528.5 5533.8 5427.3 5440.3 5443.8 5444.6 5455.9 5424.4 5455.4 5469.7 5479.4 5486.8 5217.7 5328.9 5340.0 5362.7 5437.5 5426.3 5438.3 5442.8 5444.3 5444.8 5337.3 5343.2 5369.1 5371.4 5385.3 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - PROCESS | 4012 | [#5] yui.html Cycles:180 Average:20965.24 Median:4553.41 stddev:29590.68 (649.9%) stddev-sans-first:29634.65 06:08:47 INFO - PROCESS | 4012 | Values: 723.9 774.6 797.4 831.0 831.5 15527.7 16442.5 16848.5 16864.2 17443.7 14270.4 14447.1 14458.9 14468.4 14472.6 632.4 800.7 817.7 835.6 838.9 840.1 852.0 855.9 858.7 860.9 894.6 900.4 901.5 901.9 902.0 703.4 855.9 897.7 898.6 898.9 640.2 641.2 642.0 642.0 642.3 16064.4 16144.5 16151.4 16157.8 16167.6 15048.4 15209.5 15247.6 15265.8 15405.3 13163.9 13454.9 13583.4 13740.0 14067.6 4510.5 4536.0 4544.1 4628.6 4654.9 82017.8 82560.0 83697.6 85712.1 85840.6 11854.2 11880.6 11907.9 12133.8 12180.0 22671.7 22757.4 22767.9 22776.2 22780.4 8524.3 8671.4 8943.6 9045.5 9101.3 4634.7 4799.3 4866.4 4886.5 5062.7 3239.0 3319.3 3319.5 3336.5 3342.8 549.1 551.3 563.1 567.7 568.3 700.1 704.6 736.5 738.1 747.9 9366.3 9609.4 10063.1 10312.9 10411.4 556.2 613.3 695.6 707.0 718.7 4562.7 4813.1 4971.6 4994.4 4998.5 83878.0 99364.5 124010.0 126024.4 129096.0 95823.5 97090.5 97352.0 97671.0 98999.5 56696.7 81570.6 82282.0 87082.6 90795.0 10231.2 10290.6 10312.9 10442.2 10509.2 39090.0 39750.0 49022.8 49266.0 50962.2 70864.6 71243.0 75607.0 76205.6 76951.2 13412.6 13473.7 13522.3 13573.6 14265.9 15260.9 15459.5 15600.3 15810.4 16231.3 14299.8 14623.4 14950.1 15122.8 15704.3 14488.9 14943.7 15098.6 15196.6 15620.5 13660.8 13985.8 14074.2 14088.5 14143.6 12088.4 12135.4 12333.8 12428.8 12574.0 10772.3 10968.5 10973.9 12360.9 12454.6 06:08:47 INFO - PROCESS | 4012 | -------- Summary: end -------- 06:08:47 INFO - PROCESS | 4012 | 06:08:47 INFO - TEST-INFO | 4012: exit 0 06:08:47 INFO - TEST-OK | dromaeo_css | took 1118200ms 06:08:47 INFO - TEST-START | kraken 06:08:47 INFO - operating with platform_type : linux_ 06:08:47 INFO - Initialising browser for kraken test... 06:08:47 INFO - TEST-INFO | started process 4206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm7bgbP/profile http://localhost:33951/getInfo.html) 06:08:52 INFO - PROCESS | 4206 | __metrics Screen width/height:1600/1200 06:08:52 INFO - PROCESS | 4206 | colorDepth:24 06:08:52 INFO - PROCESS | 4206 | Browser inner width/height: 1024/697 06:08:52 INFO - PROCESS | 4206 | __metrics 06:08:52 INFO - PROCESS | 4206 | 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" 06:08:52 INFO - PROCESS | 4206 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:08:53 INFO - TEST-INFO | 4206: exit 0 06:08:53 INFO - Browser initialized. 06:08:53 INFO - Running cycle 1/1 for kraken test... 06:08:53 INFO - TEST-INFO | started process 4289 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm7bgbP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:09:05 INFO - PROCESS | 4289 | RSS: Main: 155820032 06:09:05 INFO - PROCESS | 4289 | 06:09:08 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33951/tests/kraken/driver.html?testName=audio-beat-detection) 06:09:08 INFO - PROCESS | 4289 | RSS: Main: 211935232 06:09:08 INFO - PROCESS | 4289 | 06:09:11 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33951/tests/kraken/driver.html?testName=audio-dft) 06:09:12 INFO - PROCESS | 4289 | RSS: Main: 191479808 06:09:12 INFO - PROCESS | 4289 | 06:09:15 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33951/tests/kraken/driver.html?testName=audio-fft) 06:09:15 INFO - PROCESS | 4289 | RSS: Main: 267399168 06:09:15 INFO - PROCESS | 4289 | 06:09:17 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33951/tests/kraken/driver.html?testName=audio-oscillator) 06:09:18 INFO - PROCESS | 4289 | RSS: Main: 178495488 06:09:18 INFO - PROCESS | 4289 | 06:09:20 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33951/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:09:21 INFO - PROCESS | 4289 | RSS: Main: 182632448 06:09:21 INFO - PROCESS | 4289 | 06:09:24 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33951/tests/kraken/driver.html?testName=imaging-darkroom) 06:09:24 INFO - PROCESS | 4289 | RSS: Main: 220127232 06:09:24 INFO - PROCESS | 4289 | 06:09:28 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33951/tests/kraken/driver.html?testName=imaging-desaturate) 06:09:28 INFO - PROCESS | 4289 | RSS: Main: 218710016 06:09:28 INFO - PROCESS | 4289 | 06:09:31 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33951/tests/kraken/driver.html?testName=json-parse-financial) 06:09:32 INFO - PROCESS | 4289 | RSS: Main: 218824704 06:09:32 INFO - PROCESS | 4289 | 06:09:33 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33951/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:09:34 INFO - PROCESS | 4289 | RSS: Main: 180940800 06:09:34 INFO - PROCESS | 4289 | 06:09:35 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:09:36 INFO - PROCESS | 4289 | RSS: Main: 188174336 06:09:36 INFO - PROCESS | 4289 | 06:09:38 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:09:39 INFO - PROCESS | 4289 | RSS: Main: 193273856 06:09:39 INFO - PROCESS | 4289 | 06:09:41 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:09:42 INFO - PROCESS | 4289 | RSS: Main: 188456960 06:09:42 INFO - PROCESS | 4289 | 06:09:44 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:09:45 INFO - PROCESS | 4289 | RSS: Main: 191832064 06:09:45 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | Cycle 1(1): loaded http://localhost:33951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33951/tests/kraken/driver.html?testName=ai-astar) 06:09:47 INFO - PROCESS | 4289 | RSS: Main: 242036736 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | __start_tp_report 06:09:47 INFO - PROCESS | 4289 | _x_x_mozilla_page_load 06:09:47 INFO - PROCESS | 4289 | _x_x_mozilla_page_load_details 06:09:47 INFO - PROCESS | 4289 | |i|pagename|runs| 06:09:47 INFO - PROCESS | 4289 | |0;ai-astar;101;113;110;111;112;112;111;110;112;112 06:09:47 INFO - PROCESS | 4289 | |1;audio-beat-detection;146;118;120;171;117;120;151;125;156;118 06:09:47 INFO - PROCESS | 4289 | |2;audio-dft;162;161;161;160;160;161;159;159;160;162 06:09:47 INFO - PROCESS | 4289 | |3;audio-fft;74;74;76;133;76;79;75;75;103;74 06:09:47 INFO - PROCESS | 4289 | |4;audio-oscillator;84;83;127;84;84;85;126;84;84;122 06:09:47 INFO - PROCESS | 4289 | |5;imaging-gaussian-blur;118;116;117;117;116;117;116;116;117;117 06:09:47 INFO - PROCESS | 4289 | |6;imaging-darkroom;146;145;146;145;145;145;146;145;146;146 06:09:47 INFO - PROCESS | 4289 | |7;imaging-desaturate;95;96;95;95;96;94;95;95;95;95 06:09:47 INFO - PROCESS | 4289 | |8;json-parse-financial;52;51;52;52;51;51;51;52;50;50 06:09:47 INFO - PROCESS | 4289 | |9;json-stringify-tinderbox;62;63;63;62;62;62;61;62;62;62 06:09:47 INFO - PROCESS | 4289 | |10;stanford-crypto-aes;79;76;75;76;84;79;75;84;76;80 06:09:47 INFO - PROCESS | 4289 | |11;stanford-crypto-ccm;123;129;125;121;124;121;136;128;149;124 06:09:47 INFO - PROCESS | 4289 | |12;stanford-crypto-pbkdf2;165;159;157;158;164;159;159;156;156;160 06:09:47 INFO - PROCESS | 4289 | |13;stanford-crypto-sha256-iterative;64;65;64;63;63;72;66;64;64;63 06:09:47 INFO - PROCESS | 4289 | __end_tp_report 06:09:47 INFO - PROCESS | 4289 | __start_cc_report 06:09:47 INFO - PROCESS | 4289 | _x_x_mozilla_cycle_collect,1686 06:09:47 INFO - PROCESS | 4289 | __end_cc_report 06:09:47 INFO - PROCESS | 4289 | __startTimestamp1470834587491__endTimestamp 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | ------- Summary: start ------- 06:09:47 INFO - PROCESS | 4289 | Number of tests: 14 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:3.44 (3.1%) stddev-sans-first:1.01 06:09:47 INFO - PROCESS | 4289 | Values: 101.0 113.0 110.0 111.0 112.0 112.0 111.0 110.0 112.0 112.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:135.50 stddev:19.89 (14.7%) stddev-sans-first:20.63 06:09:47 INFO - PROCESS | 4289 | Values: 146.0 118.0 120.0 171.0 117.0 120.0 151.0 125.0 156.0 118.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 06:09:47 INFO - PROCESS | 4289 | Values: 162.0 161.0 161.0 160.0 160.0 161.0 159.0 159.0 160.0 162.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#3] audio-fft Cycles:10 Average:83.90 Median:75.00 stddev:19.37 (25.8%) stddev-sans-first:20.21 06:09:47 INFO - PROCESS | 4289 | Values: 74.0 74.0 76.0 133.0 76.0 79.0 75.0 75.0 103.0 74.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:84.00 stddev:19.85 (23.6%) stddev-sans-first:20.55 06:09:47 INFO - PROCESS | 4289 | Values: 84.0 83.0 127.0 84.0 84.0 85.0 126.0 84.0 84.0 122.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 06:09:47 INFO - PROCESS | 4289 | Values: 118.0 116.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 117.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 06:09:47 INFO - PROCESS | 4289 | Values: 146.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 146.0 146.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 06:09:47 INFO - PROCESS | 4289 | Values: 95.0 96.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 95.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:0.79 (1.5%) stddev-sans-first:0.78 06:09:47 INFO - PROCESS | 4289 | Values: 52.0 51.0 52.0 52.0 51.0 51.0 51.0 52.0 50.0 50.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 06:09:47 INFO - PROCESS | 4289 | Values: 62.0 63.0 63.0 62.0 62.0 62.0 61.0 62.0 62.0 62.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:79.00 stddev:3.44 (4.4%) stddev-sans-first:3.64 06:09:47 INFO - PROCESS | 4289 | Values: 79.0 76.0 75.0 76.0 84.0 79.0 75.0 84.0 76.0 80.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:126.50 stddev:8.63 (6.8%) stddev-sans-first:8.96 06:09:47 INFO - PROCESS | 4289 | Values: 123.0 129.0 125.0 121.0 124.0 121.0 136.0 128.0 149.0 124.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.30 Median:159.00 stddev:3.06 (1.9%) stddev-sans-first:2.45 06:09:47 INFO - PROCESS | 4289 | Values: 165.0 159.0 157.0 158.0 164.0 159.0 159.0 156.0 156.0 160.0 06:09:47 INFO - PROCESS | 4289 | 06:09:47 INFO - PROCESS | 4289 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:64.00 stddev:2.70 (4.2%) stddev-sans-first:2.85 06:09:47 INFO - PROCESS | 4289 | Values: 64.0 65.0 64.0 63.0 63.0 72.0 66.0 64.0 64.0 63.0 06:09:47 INFO - PROCESS | 4289 | -------- Summary: end -------- 06:09:47 INFO - PROCESS | 4289 | 06:09:48 INFO - TEST-INFO | 4289: exit 0 06:09:48 INFO - TEST-OK | kraken | took 60597ms 06:09:48 INFO - SUITE-END | took 1178s 06:09:48 INFO - Completed test suite (00:19:38) 06:09:48 INFO - javascript benchmark 06:09:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3019.8037127586704, 3023.7429818465434, 3045.4518186726395, 3069.570260163579, 3075.3080556874706, 5242.004019236342, 5242.266082748409, 5257.369115706116, 5366.221897824813, 5398.24424580559, 5407.540359069474, 5412.269343638608, 5465.863353416166, 5479.17812328151, 5485.067538518451, 4852.4662287148385, 4934.5312195341385, 4954.603631709472, 4964.280179373988, 5131.794728210867, 5003.924941125886, 5062.493750624945, 5073.239014147871, 5079.974600126994, 5094.312267843849, 4778.235482322829, 4816.325714399987, 4817.156997525454, 4887.3402090717655, 4968.40379154502, 4833.10587541306, 4834.057358815033, 4836.129496690594, 4921.483244259372, 4956.87607809804, 4881.1213981483315, 4882.340883980688, 4883.633727470418, 4906.460289368167, 4936.2102063669645, 5170.638055336128, 5326.387465441485, 5381.327334083239, 5421.051316019681, 5430.83707488776, 5818.854528636775, 5820.18517407559, 5842.941570584282, 5847.883042339172, 5868.853278668067, 5855.736491857877, 5888.470037696585, 5890.410958904118, 5899.410058994109, 5910.438508341744, 5282.154855223146, 5334.066538355811, 5397.59518036141, 5405.918911216335, 5498.5876059295715, 5948.167256584082, 5983.311919129269, 6004.639721616717, 6004.6697431640805, 6011.639301641915, 5721.742521586539, 5723.971380143073, 5753.33836608787, 5762.567807414461, 5770.0, 5577.498025177753, 5709.714514274269, 5751.626144300691, 5762.625429347079, 5869.941300587025, 5740.0, 5786.768529258782, 5791.015527360359, 5796.072628379439, 5798.072308430716, 5756.0502517084215, 5807.216025836543, 5813.738381772746, 5817.912731309034, 5831.416858314219, 4334.913301733947, 4345.435093437896, 4348.630366418826, 4370.737755734666, 4396.472423309159, 3958.1687845552756, 3967.0, 3974.5230572331507, 3993.3211354069877, 4003.4795476587856, 5482.862928426821, 5490.835274941758, 5495.697736624444, 5497.615166938356, 5558.638688485235, 5606.85982850432, 5730.570207234474, 5784.82645520635, 5798.82603521895, 5817.825465236049, 5534.142207957773, 5534.916976245359, 5538.169274608841, 5569.610127291132, 5594.412586678422, 5070.315507406527, 5078.695278283315, 5078.923816142761, 5080.898382032338, 5086.033653605803, 5840.620359676607, 5921.467067963904, 5960.523158147251, 6096.390360963868, 6110.877782444326, 5766.913496297643, 5789.363170051375, 5794.217780599565, 5828.825135245949, 5837.124431335334, 6001.729922153426, 6019.849503762441, 6036.607620504653, 6038.5772995889865, 6044.123602077762, 5569.220309156803, 5600.943990560042, 5605.383407825055, 5735.025045742151, 5736.168255602997, 5776.971115144397, 5796.1305804129715, 5797.159411885337, 5813.157092221689, 5845.386234445322, 5608.242887210175, 5643.492085712305, 5656.3495198051105, 5659.462351076641, 5659.462351076641, 5541.944580554304, 5560.610757246954, 5561.3882472927135, 5632.915506267491, 5642.943570564242, 5560.555155587463, 5564.165375193591, 5573.303337082864, 5596.9440305598055, 5612.270404847343, 5670.574706896917, 5691.174779656843, 5715.342735585295, 5739.483446489836, 5742.885142297047, 5375.3280839895015, 5379.354477462573, 5438.374586922397, 5570.721463926869, 5578.358488773681], "value": 5334.170346081463, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.892534881047, 585.4906231578526, 604.3503234023426, 612.6814056690519, 614.1708693264094, 32721.345573088525, 43385.56614433855, 44319.77840110803, 44627.55372446275, 44639.10721785562, 45435.31847022297, 45957.31064034042, 45965.54034459654, 45973.77013114938, 45980.0, 9054.185123338882, 9200.585973631169, 9213.815723685522, 9214.493202873839, 9216.585253663601, 8898.777530561723, 8997.05530919254, 9016.233620142295, 9030.548472576378, 9030.593623286968, 9013.504257265848, 9117.997020327759, 9122.452652840817, 9125.954370228174, 9140.085991400872, 9073.13805188506, 9108.134727200906, 9114.13415725505, 9120.179183873417, 9122.0, 8946.463212207223, 9038.051004644549, 9042.683506077321, 9051.773705657346, 9055.547222638841, 42796.786016069724, 43137.35293970593, 43193.0, 43207.0, 43226.56773432257, 3324.817135057571, 3352.2625022495013, 3382.120648631349, 3441.0, 3825.158465137665, 33417.16457088568, 33725.494117588256, 33989.320213595835, 34885.0, 35035.474467883, 4417.624501917341, 4458.9331160032625, 4498.707584007062, 4507.076049409863, 4527.616675679554, 247842.0, 248048.0, 248069.0, 248512.0, 251770.0, 57691.711541442026, 58033.419665803645, 58492.70753646205, 58592.0, 58594.0, 60564.0, 60857.0, 61278.0, 61309.693451532454, 61386.69306653528, 16277.837221627633, 16462.835371646368, 16531.6693666126, 16533.173341332884, 16554.42059527911, 12547.811782823264, 12574.81137782934, 12607.621771346874, 12655.177413468095, 12671.936640316924, 16736.41422550205, 16929.492115236564, 16993.150342482826, 17000.23498942526, 17000.469957703866, 5810.389909059572, 5943.790353209219, 5997.670128142907, 6145.385461453819, 6179.16581261533, 944.8157609266127, 1043.0299821166388, 1089.5369467976236, 1094.9671509854718, 1100.7248187953012, 33025.66974330226, 33185.4153904387, 33256.501152482735, 33458.66541334604, 33856.66143338535, 885.0, 1058.692979036071, 1061.2836335473678, 1106.0930037369442, 1184.5084290019718, 17012.57468563271, 17121.22954467052, 17146.22841972114, 17199.31202751901, 17277.13614319279, 469725.0, 471377.0, 472524.0, 475399.0, 476098.0, 57318.0, 57375.42624573784, 57391.13913291304, 57675.71162144246, 57713.71143144257, 1211.6849619099098, 1267.524678245658, 1326.316946772413, 1344.610063081715, 1345.280275052842, 32709.0, 33972.83013584916, 34075.14812129716, 34269.314613708084, 34318.656813431546, 49172.26241606451, 50564.24153637772, 51023.23465147951, 51100.488995109576, 51177.488225117275, 359159.0, 361387.0, 362988.0, 364184.0, 502604.0, 29267.268318292212, 29286.26784330409, 29323.41353172967, 29642.25894352572, 29664.258393540335, 48815.75592122017, 49068.75465622649, 49116.26325605047, 49126.5087349122, 50462.0, 41362.172756545304, 42044.36933445939, 42240.577594223665, 42701.0, 44211.55788442203, 42812.57187428086, 43004.0, 43347.133057339306, 43591.0, 43649.34525982176, 41791.79104104581, 42358.15283694371, 42671.0, 43025.78487107545, 43590.78204608957, 35753.46369804403, 37606.811965939996, 37934.62065379311, 38486.23027539378, 38558.80720596491, 28609.4504141259, 28729.856350718113, 28819.423611527232, 29041.273968150967, 29248.707512924597], "value": 19901.760932856436, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.67454965081066, 378.8371000469797, 384.095455202997, 385.60090306532743, 400.69747340757726, 17711.025893575847, 19760.90119549404, 19797.50506237345, 19914.502137446554, 21182.152713891464, 19196.328128515506, 19321.806781932177, 19572.510687232825, 19794.0, 20306.18775248992, 5456.208849716789, 5746.6782639992825, 5756.827295181151, 5908.645481271137, 5949.0481522956115, 5795.0, 5833.591648584601, 5839.240898683165, 5844.211031510734, 5847.678377689225, 5720.485156335929, 5771.105478650816, 5789.247397838275, 5808.56435767317, 5818.970905145468, 5678.318601767794, 5815.476607105338, 5815.854603634901, 5818.854528636775, 5819.156222347757, 6377.075324077982, 6766.492513061491, 6860.554063985875, 6914.792556223321, 6924.134483189601, 24507.897144628536, 25316.746832531808, 25399.873000635063, 27449.725502745117, 27751.861240693666, 2187.289111190506, 2280.5552917181158, 2311.3412677386864, 2396.5206958608346, 2555.910543131, 15487.922560387238, 15782.289796959165, 16286.918565407215, 16598.170091495376, 16834.15829208547, 2041.652919003773, 2064.9277275295294, 2072.5440403111293, 2073.139647046474, 2103.5897999889876, 91074.54462727711, 100174.0, 100317.0, 101136.49431752868, 103228.48385758023, 17278.308867645406, 18646.34737784171, 18675.81324186768, 18684.626307473773, 18709.287153362686, 33534.3293134136, 33596.49605255923, 33694.3261134776, 33978.49032264518, 34019.82990085034, 9817.705468835946, 9857.324254876688, 9867.901320986843, 9903.306768526134, 10153.0, 8089.959550202211, 8129.552874591955, 8146.877796833052, 8336.374771892133, 8351.8329633407, 9065.546722663972, 9116.544172791333, 9183.632654693738, 9347.766305842408, 9493.145616894375, 3405.455127179689, 3490.982545087309, 3560.9317204838444, 3720.5535335759882, 3726.7950262735267, 1000.2046326843333, 1003.9046290602381, 1033.5917312661456, 1078.928457361867, 1079.6868908016588, 19597.118129684197, 20748.688769668166, 20855.687164692237, 21841.453963650718, 21858.0, 972.5672075926286, 973.8052389521982, 984.1536278800226, 991.9305648604673, 1009.4044513736931, 9644.469554174588, 11354.716132096599, 11374.033207177317, 11449.141314401286, 11524.135689823297, 488820.0, 506102.0, 510193.0, 521068.0, 523966.0, 64409.0, 94248.0, 95291.0, 99993.0, 101694.0, 990.6631745206664, 1036.8237399642078, 1046.0899017854363, 1049.6221360310283, 1059.9152067834555, 17285.395011174547, 17823.376181833573, 18100.818991809912, 18217.362392316205, 18593.721094183868, 36555.81722091373, 43363.132737344444, 43371.783141084095, 44291.55708443003, 44861.0, 56871.71564142153, 61236.693816530635, 62586.374136258055, 62631.6868415655, 62686.373136269875, 7573.431992600466, 7928.643211055401, 8541.829163416822, 8646.697365592174, 8871.068537803696, 12478.563250286197, 12876.22742635445, 12984.870151298366, 13074.738505230032, 13324.733505330032, 11275.0, 11307.151963602599, 11347.106487845876, 11503.827442588201, 11729.648110556696, 6210.7515699371515, 11565.0, 11615.593454229062, 11707.473163707484, 11781.764364712486, 11930.343831089027, 12006.21959572625, 12041.0, 12167.209131406431, 12423.875761242272, 7339.412846972336, 7524.247575242651, 7747.806304842198, 7891.053073630971, 8003.159668234985, 8271.379646526655, 11810.586629467927, 11871.050315974873, 11906.987906028085, 11945.16383853122], "value": 10291.336738397753, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.7410791949524, 1466.1935935235622, 1527.0379660813685, 1643.5973186569292, 1766.2316892151923, 8015.799605009877, 8340.165983401657, 8415.747527574167, 8575.356848236392, 8583.914160858389, 8430.452020618655, 8469.05211801286, 8482.618282177302, 8527.78680532987, 8540.0, 2385.105585405473, 2407.6508906208587, 2408.205292253552, 2410.8432951858163, 2414.2033129067368, 2331.5453486570127, 2346.307839187435, 2386.916457923973, 2387.8328517003815, 2390.7011623547055, 2315.3285547191294, 2338.2751347082376, 2345.296411076679, 2348.049040138738, 2352.329586067971, 2241.450844543081, 2255.4248666589997, 2261.231181398324, 2261.4685548895995, 2305.250793492122, 2244.147224054865, 2249.325202439262, 2252.042881775239, 2254.537819746948, 2255.8082562982167, 8469.153084691543, 8521.275691566225, 8521.914780852174, 8522.786930326729, 8741.0, 4981.227909674007, 5120.436751957282, 5164.715940623264, 5177.637565370425, 5179.637425380225, 3133.796303240305, 3302.4220761366905, 3357.0432426758257, 3363.3273345330786, 3367.1413789483768, 1076.8330908709163, 1078.6386560502162, 1080.486768784821, 1099.8020356335855, 1106.9169812264113, 12101.092418068592, 12273.631791046282, 12331.198472099284, 12332.19840710351, 12357.629271121881, 4449.555044495524, 4528.411306530196, 4683.718976861399, 4701.012787314702, 4709.7174169549935, 10363.170946324275, 10399.94800025995, 10433.634822781318, 10436.0, 10444.21668374875, 4963.8262660806695, 5011.273365362002, 5013.473585273567, 5015.423226329019, 5025.572826309804, 4270.572942705705, 4523.095380923828, 4608.907821843544, 4652.232381657056, 4687.95312046882, 3933.9213215735526, 3957.3668213086344, 4081.9591804082174, 4109.794510274474, 4115.835366585303, 1247.2017908538362, 1268.099649249025, 1275.4451813461174, 1285.3508977966067, 1306.5884246462333, 966.3960024984385, 969.2004096620176, 986.1223511075005, 996.2080146283722, 1003.2275148135894, 6102.847428814315, 6168.784092556739, 6210.4100110489435, 6230.56386052981, 6315.778947736807, 484.7503535679128, 488.21884984025274, 491.91391506486224, 493.53854146373254, 495.7075325557938, 4179.811908464127, 4190.141021090669, 4245.87262382129, 4324.2648749712625, 4385.144896745104, 10647.361158330525, 11061.115110791117, 11072.0, 11125.221234513507, 11134.33194008362, 10997.175211859143, 11000.119990400748, 11002.669919902255, 11013.174011949135, 11027.338359698444, 7769.72805951789, 8096.230858068474, 8105.311048560939, 8286.337093032544, 8355.540445275568, 5915.290165180206, 6110.572259941761, 6177.351378105234, 6178.505719542336, 6376.107344971801, 10185.439800811026, 10421.114205292635, 10452.111570516287, 10458.947705261426, 10471.057604815604, 9767.755806104904, 9862.0, 10019.699409017741, 10162.187025038127, 10169.898301016896, 7095.8935615964765, 7874.291313781787, 7979.0, 8037.276645101969, 8244.505329680238, 8396.454230475, 8670.13298670128, 8674.175953284428, 8683.0, 8686.478811271343, 7639.314985807837, 7740.148583655906, 7841.686332546634, 7998.48009879356, 8003.679852806055, 7526.774196774106, 7617.65720542588, 7635.541867487968, 7709.691612335444, 7730.7680769577, 6924.515283930076, 7042.330978557029, 7042.507024508235, 7046.577205367695, 7055.858882822212, 5386.569074473954, 5389.649672771257, 5486.177073439016, 5493.505584497414, 5593.300837395325, 5297.708626025606, 5379.569634429158, 5408.59435542328, 5433.538149257357, 5470.589705772003], "value": 4566.984284670472, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.8042013951349, 327.4057585482349, 332.11822610967874, 334.6904113694832, 336.6364326527351, 5027.421846487655, 5091.592672586194, 5182.704323919021, 5224.555912747416, 5225.294585230993, 5043.344365232524, 5076.36545431821, 5141.22881567765, 5162.251473536345, 5168.2764412982215, 4116.073883376232, 4116.650084742801, 4125.504939407276, 4129.855455059066, 4135.710500264982, 3982.721209515335, 4107.507099148106, 4152.2733521633745, 4160.0, 4164.0, 4112.527059388179, 4114.465119534471, 4123.731947428917, 4127.896802579945, 4131.8553850615235, 4090.713650044498, 4101.52832424272, 4103.158852435258, 4126.525449573277, 4154.397612346193, 3552.964470355289, 3677.6506231907924, 3684.355237833395, 3694.3719567673293, 3736.5142531470733, 5003.4746351633285, 5028.270900719375, 5032.597392208614, 5034.370703662043, 5053.115704751691, 4663.417072865892, 4667.4632417272105, 4679.929801052953, 4697.295405689139, 4718.882027949295, 5252.23842542833, 5299.682019078829, 5299.867503312409, 5358.91961620576, 5376.784928602851, 3978.1446988897237, 3979.880603581897, 3992.121733218687, 4005.218982298453, 4010.8341495592344, 5549.972250138723, 5572.631631556572, 5590.546457920929, 5625.0, 5655.812279421327, 3923.3330333843314, 4097.8770636880945, 4116.773577453268, 4130.855420060284, 4136.73111247774, 4149.522804877417, 4149.8962525937095, 4156.314208155621, 4160.1471698301775, 4162.5629308922735, 4687.835925742651, 4687.882802929954, 4690.0, 4698.271767875985, 4721.669483136147, 5142.768575414116, 5387.24578559002, 5419.593530485229, 5441.319835020622, 5441.61238884082, 1355.4510423278589, 1396.4902810474307, 1420.8295004599515, 1432.4341884955384, 1435.9066660667024, 990.0841721407669, 992.4591097851688, 1004.9296549241483, 1012.5038731021747, 1025.1183981775666, 5134.152864777345, 5142.537171654569, 5147.948520514747, 5153.974230128825, 5155.252488389163, 5343.732813359316, 5395.05586522357, 5407.674280317577, 5445.809396671098, 5449.427810079964, 5041.8487445376695, 5115.590752739847, 5117.181250999822, 5134.947335796152, 5234.92147617786, 2922.6638936522145, 2927.6486821581548, 2939.2211064068038, 2939.808912420686, 2940.9411811763644, 5759.539236861041, 5771.769129234784, 5776.624519406225, 5791.5656325775735, 5814.418558144152, 5557.3331200256225, 5643.971780141073, 5657.43425657431, 5683.374828768832, 5697.173909783141, 5747.109198074305, 5756.107803290497, 5763.682997435181, 5767.22142510764, 5768.192453056491, 5281.339832520935, 5526.778928842801, 5527.778888844401, 5563.109902415594, 5572.442755724557, 5456.645318054314, 5514.5864060196445, 5521.585881058856, 5525.806596769094, 5530.787684926062, 5714.342850572238, 5720.570957178145, 5726.656400615976, 5777.429044075346, 5793.681347525758, 3787.6401741834484, 4010.7794071326357, 4024.678025757874, 4026.7382620129597, 4050.5341885682346, 5335.132703553725, 5347.075052981001, 5518.972405138109, 5528.530074943675, 5533.750981205936, 5427.3215848019, 5440.29276194108, 5443.836684899459, 5444.564434845282, 5455.863603409788, 5424.372688193605, 5455.427180146027, 5469.699166545719, 5479.3972663007835, 5486.835394938158, 5217.713025783618, 5328.866778330573, 5339.973300133474, 5362.65142765719, 5437.4562543745305, 5426.348838139448, 5438.347398312069, 5442.782288708566, 5444.26502422168, 5444.782208711607, 5337.306150200449, 5343.198520221998, 5369.140937449989, 5371.40914499397, 5385.299911011543], "value": 4171.821629170492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.9276072392761, 774.5585016540571, 797.3860127701666, 831.0401486283345, 831.5010993403962, 15527.689446211069, 16442.506724798277, 16848.49454516365, 16864.156792160404, 17443.73834392485, 14270.357833897473, 14447.133172009686, 14458.855411445882, 14468.421263149488, 14472.565823025327, 632.4023797511354, 800.655718041245, 817.709713051867, 835.6490274084869, 838.9244967952883, 840.0717207485732, 851.953142577158, 855.8844555984956, 858.6608289725555, 860.9483430994161, 894.6197865906996, 900.3697411811726, 901.5086777706166, 901.8782464367292, 901.9762569483651, 703.4196787650158, 855.9144085591454, 897.717219075989, 898.5731777405746, 898.8786513820648, 640.2061443809642, 641.2081079866362, 641.9903701444435, 642.0209180998967, 642.2998931165052, 16064.357425702958, 16144.515664530083, 16151.353945842153, 16157.83842161575, 16167.595810104724, 15048.398064077424, 15209.467668631545, 15247.61880952974, 15265.771013434807, 15405.306761195772, 13163.868361316456, 13454.865451345557, 13583.388747506386, 13740.0, 14067.577972660836, 4510.458744950594, 4535.977320113411, 4544.068465964469, 4628.606568441686, 4654.86035418938, 82017.76973345404, 82560.0, 83697.58151209266, 85712.14287857105, 85840.57079714624, 11854.170208085352, 11880.584179553674, 11907.88092119085, 12133.817992730115, 12180.0, 22671.659925101136, 22757.43106422353, 22767.886160569422, 22776.20283290077, 22780.43048923757, 8524.275436587835, 8671.4363566368, 8943.642254309754, 9045.457272563535, 9101.317401194938, 4634.652401069933, 4799.328094066834, 4866.391701037371, 4886.486918873538, 5062.7215503146945, 3238.9676103238667, 3319.3195394943978, 3319.502074688816, 3336.466165413522, 3342.8328583570724, 549.1022178737712, 551.310861423221, 563.1327755256858, 567.7076305702567, 568.3407247592772, 700.1248439450686, 704.6237210087004, 736.5359823311387, 738.103204606395, 747.8691229034897, 9366.250699944125, 9609.38201182734, 10063.144632706155, 10312.948435257777, 10411.375317480975, 556.1796350383153, 613.3130893399422, 695.6139342664768, 706.9893951590627, 718.7359816577717, 4562.749048802281, 4813.133635945564, 4971.626078258564, 4994.4423121975515, 4998.531308032011, 83878.0, 99364.50317748365, 124010.0, 126024.36987815003, 129096.0, 95823.52088239654, 97090.5145474268, 97352.0, 97671.0, 98999.50500247453, 56696.71651641715, 81570.59214703889, 82282.0, 87082.56458717666, 90794.99999999868, 10231.181505479395, 10290.588376465157, 10312.948435257777, 10442.216833737652, 10509.211809114195, 39090.0, 39750.0, 49022.75488622534, 49266.0, 50962.23556646579, 70864.64567677128, 71243.0, 75607.0, 76205.61897190478, 76951.23048769665, 13412.597622071353, 13473.663158421117, 13522.25627590492, 13573.59279221625, 14265.857341426869, 15260.923695381452, 15459.458918937784, 15600.309450725053, 15810.367585296459, 16231.269592868113, 14299.785503217669, 14623.415063397344, 14950.075297087807, 15122.773158402413, 15704.293306800993, 14488.927555362156, 14943.701125977203, 15098.622534436727, 15196.620084497534, 15620.453284135001, 13660.795088073488, 13985.790213147015, 14074.225917574631, 14088.506902258372, 14143.575692729235, 12088.39558022113, 12135.393230338625, 12333.814992774936, 12428.75142497127, 12574.0, 10772.299800512941, 10968.451577420938, 10973.945130274298, 12360.876391235972, 12454.564090256797], "value": 7780.157486692346, "unit": "score"}], "name": "dromaeo_css", "value": 7383.068300826876, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 110.0, 111.0, 112.0, 112.0, 111.0, 110.0, 112.0, 112.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 120.0, 171.0, 117.0, 120.0, 151.0, 125.0, 156.0, 118.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 160.0, 161.0, 159.0, 159.0, 160.0, 162.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 133.0, 76.0, 79.0, 75.0, 75.0, 103.0, 74.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 127.0, 84.0, 84.0, 85.0, 126.0, 84.0, 84.0, 122.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 51.0, 51.0, 51.0, 52.0, 50.0, 50.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 75.0, 76.0, 84.0, 79.0, 75.0, 84.0, 76.0, 80.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 129.0, 125.0, 121.0, 124.0, 121.0, 136.0, 128.0, 149.0, 124.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 157.0, 158.0, 164.0, 159.0, 159.0, 156.0, 156.0, 160.0], "value": 159.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 63.0, 63.0, 72.0, 66.0, 64.0, 64.0, 63.0], "value": 64.8, "unit": "score"}], "name": "kraken", "value": 1486.4, "alertThreshold": 2.0}]} 06:09:48 INFO - Return code: 0 06:09:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:09:48 INFO - # TBPL SUCCESS # 06:09:48 INFO - Running post-action listener: _resource_record_post_action 06:09:48 INFO - [mozharness: 2016-08-10 13:09:48.807723Z] Finished run-tests step (success) 06:09:48 INFO - Running post-run listener: _resource_record_post_run 06:09:48 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 425984; Write bytes: 364929024; Read time: 60; Write time: 466360 06:09:48 INFO - TinderboxPrint: CPU usage
13.0% 06:09:48 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 60 06:09:48 INFO - TinderboxPrint: I/O write bytes / time
364,929,024 / 466,360 06:09:48 INFO - TinderboxPrint: CPU idle
8,304.2 (87.0%) 06:09:48 INFO - TinderboxPrint: CPU user
1,191.6 (12.5%) 06:09:48 INFO - TinderboxPrint: Swap in / out
0 / 0 06:09:48 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 72704000; Read time: 0; Write time: 48368 06:09:48 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 421888; Write bytes: 285687808; Read time: 40; Write time: 404732 06:09:49 INFO - Running post-run listener: _upload_blobber_files 06:09:49 INFO - Blob upload gear active. 06:09:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:09:49 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 06:09:49 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:09:49 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:09:49 INFO - (blobuploader) - INFO - Open directory for files ... 06:09:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:09:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:09:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:53 INFO - (blobuploader) - INFO - Done attempting. 06:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:09:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:54 INFO - (blobuploader) - INFO - Done attempting. 06:09:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:09:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:09:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:55 INFO - (blobuploader) - INFO - Done attempting. 06:09:55 INFO - (blobuploader) - INFO - Iteration through files over. 06:09:55 INFO - Return code: 0 06:09:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:09:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:09:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/063675cc073c9a3b14c952693e15750a0f6f03af64ba0f36e54612c004b56a4395d961a4e2646eb616ecd06bbf17091b5148047240e8c829b3464054bb752c46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c264364467b48ee9bf3412acc96b57ff53a6b2560c2f1ea8bc89cc92411aac2b6ae13fcdba24cfbebbbb64ed4225cf642533eb2f281a601b097d72986957fb9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/358d7c21eeb156da7ef4c0b141314044dfc3403c101271938bfe270ecbbc4cfd9aa387ac619e4d880351cfa8fb6fdee9be67bcf08956fffaa0965a5264f06778"} 06:09:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:09:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:09:55 INFO - Contents: 06:09:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/063675cc073c9a3b14c952693e15750a0f6f03af64ba0f36e54612c004b56a4395d961a4e2646eb616ecd06bbf17091b5148047240e8c829b3464054bb752c46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c264364467b48ee9bf3412acc96b57ff53a6b2560c2f1ea8bc89cc92411aac2b6ae13fcdba24cfbebbbb64ed4225cf642533eb2f281a601b097d72986957fb9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/358d7c21eeb156da7ef4c0b141314044dfc3403c101271938bfe270ecbbc4cfd9aa387ac619e4d880351cfa8fb6fdee9be67bcf08956fffaa0965a5264f06778"} 06:09:55 INFO - Running post-run listener: copy_logs_to_upload_dir 06:09:55 INFO - Copying logs to upload dir... 06:09:55 INFO - mkdir: /builds/slave/test/build/upload/logs 06:09:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.873752 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-08-10 06:09:55.329820) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 06:09:55.335895) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/063675cc073c9a3b14c952693e15750a0f6f03af64ba0f36e54612c004b56a4395d961a4e2646eb616ecd06bbf17091b5148047240e8c829b3464054bb752c46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c264364467b48ee9bf3412acc96b57ff53a6b2560c2f1ea8bc89cc92411aac2b6ae13fcdba24cfbebbbb64ed4225cf642533eb2f281a601b097d72986957fb9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/358d7c21eeb156da7ef4c0b141314044dfc3403c101271938bfe270ecbbc4cfd9aa387ac619e4d880351cfa8fb6fdee9be67bcf08956fffaa0965a5264f06778"} build_url:https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/FotInl3UTRSclQiDU1HLrg/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/Firefox/sha512/063675cc073c9a3b14c952693e15750a0f6f03af64ba0f36e54612c004b56a4395d961a4e2646eb616ecd06bbf17091b5148047240e8c829b3464054bb752c46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c264364467b48ee9bf3412acc96b57ff53a6b2560c2f1ea8bc89cc92411aac2b6ae13fcdba24cfbebbbb64ed4225cf642533eb2f281a601b097d72986957fb9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/358d7c21eeb156da7ef4c0b141314044dfc3403c101271938bfe270ecbbc4cfd9aa387ac619e4d880351cfa8fb6fdee9be67bcf08956fffaa0965a5264f06778"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 06:09:55.361190) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 06:09:55.361535) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1470833357.460428-497125897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 06:09:55.447028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 06:09:55.447772) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 06:09:55.448433) ========= ========= Total master_lag: 0.17 =========