builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-017 starttime: 1472536131.63 results: success (0) buildid: 20160829212920 builduid: 4eefb6625e464d06856f4799f6f5cc36 revision: 26e22af660e543ebb69930f082188b69ec756185 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.625918) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.626350) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.629687) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.711451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.711904) ========= 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=0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 22:48:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-08-29 22:48:52 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103201 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.833536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.836933) ========= 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=0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016316 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.891968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:51.892338) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 26e22af660e543ebb69930f082188b69ec756185 --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 26e22af660e543ebb69930f082188b69ec756185 --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=0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 22:48:52,229 truncating revision to first 12 chars 2016-08-29 22:48:52,230 Setting DEBUG logging. 2016-08-29 22:48:52,230 attempt 1/10 2016-08-29 22:48:52,230 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/26e22af660e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 22:48:52,527 unpacking tar archive at: mozilla-central-26e22af660e5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.689139 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:52.606501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:52.607117) ========= script_repo_revision: 26e22af660e543ebb69930f082188b69ec756185 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:52.607968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:52.608554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 22:48:52.619985) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-29 22:48:52.620282) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:48:53 INFO - MultiFileLogger online at 20160829 22:48:53 in /builds/slave/test 22:48:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:48:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:48:53 INFO - {'append_to_log': False, 22:48:53 INFO - 'base_work_dir': '/builds/slave/test', 22:48:53 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:48:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:48:53 INFO - 'branch': 'Firefox-Non-PGO', 22:48:53 INFO - 'buildbot_json_path': 'buildprops.json', 22:48:53 INFO - 'config_files': ('talos/linux_config.py',), 22:48:53 INFO - 'default_actions': ('clobber', 22:48:53 INFO - 'read-buildbot-config', 22:48:53 INFO - 'download-and-extract', 22:48:53 INFO - 'populate-webroot', 22:48:53 INFO - 'create-virtualenv', 22:48:53 INFO - 'install', 22:48:53 INFO - 'run-tests'), 22:48:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:48:53 INFO - 'download_minidump_stackwalk': True, 22:48:53 INFO - 'download_symbols': 'ondemand', 22:48:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:48:53 INFO - 'tooltool.py': '/tools/tooltool.py', 22:48:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:48:53 INFO - '/tools/misc-python/virtualenv.py')}, 22:48:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:48:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:48:53 INFO - 'installer_path': 'installer.exe', 22:48:53 INFO - 'log_level': 'info', 22:48:53 INFO - 'log_name': 'talos', 22:48:53 INFO - 'log_to_console': True, 22:48:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:48:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:48:53 INFO - 'opt_config_files': (), 22:48:53 INFO - 'pip_index': False, 22:48:53 INFO - 'sps_profile': False, 22:48:53 INFO - 'sps_profile_interval': 0, 22:48:53 INFO - 'suite': 'dromaeojs', 22:48:53 INFO - 'system_bits': '32', 22:48:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:48:53 INFO - 'title': 'talos-linux64-ix-017', 22:48:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:48:53 INFO - 'use_talos_json': True, 22:48:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:48:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:48:53 INFO - 'work_dir': 'build'} 22:48:53 INFO - [mozharness: 2016-08-30 05:48:53.038838Z] Running clobber step. 22:48:53 INFO - Running pre-action listener: _resource_record_pre_action 22:48:53 INFO - Running main action method: clobber 22:48:53 INFO - rmtree: /builds/slave/test/build 22:48:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:48:54 INFO - Running post-action listener: _resource_record_post_action 22:48:54 INFO - [mozharness: 2016-08-30 05:48:54.008407Z] Finished clobber step (success) 22:48:54 INFO - [mozharness: 2016-08-30 05:48:54.008508Z] Running read-buildbot-config step. 22:48:54 INFO - Running pre-action listener: _resource_record_pre_action 22:48:54 INFO - Running main action method: read_buildbot_config 22:48:54 INFO - Using buildbot properties: 22:48:54 INFO - { 22:48:54 INFO - "project": "", 22:48:54 INFO - "product": "firefox", 22:48:54 INFO - "script_repo_revision": "production", 22:48:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:48:54 INFO - "repository": "", 22:48:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 22:48:54 INFO - "buildid": "20160829212920", 22:48:54 INFO - "pgo_build": "False", 22:48:54 INFO - "basedir": "/builds/slave/test", 22:48:54 INFO - "buildnumber": 696, 22:48:54 INFO - "slavename": "talos-linux64-ix-017", 22:48:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:48:54 INFO - "platform": "ubuntu64_hw", 22:48:54 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 22:48:54 INFO - "branch": "mozilla-central", 22:48:54 INFO - "repo_path": "mozilla-central", 22:48:54 INFO - "revision": "26e22af660e543ebb69930f082188b69ec756185", 22:48:54 INFO - "stage_platform": "linux64", 22:48:54 INFO - "builduid": "4eefb6625e464d06856f4799f6f5cc36", 22:48:54 INFO - "slavebuilddir": "test" 22:48:54 INFO - } 22:48:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:48:54 INFO - Running post-action listener: _resource_record_post_action 22:48:54 INFO - [mozharness: 2016-08-30 05:48:54.010946Z] Finished read-buildbot-config step (success) 22:48:54 INFO - [mozharness: 2016-08-30 05:48:54.011035Z] Running download-and-extract step. 22:48:54 INFO - Running pre-action listener: _resource_record_pre_action 22:48:54 INFO - Running main action method: download_and_extract 22:48:54 INFO - mkdir: /builds/slave/test/build/tests 22:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:54 INFO - trying https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:48:54 INFO - Downloading https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:48:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:48:54 INFO - Downloaded 1361 bytes. 22:48:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:48:54 INFO - Using the following test package requirements: 22:48:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:48:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:48:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:48:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'jsshell-linux-x86_64.zip'], 22:48:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:48:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:48:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:48:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:48:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:48:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:48:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:48:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:54 INFO - trying https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:48:54 INFO - Downloading https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:48:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:48:55 INFO - Downloaded 20315589 bytes. 22:48:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 22:48:59 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 22:48:59 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')]} 22:48:59 INFO - trying https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:48:59 INFO - Downloading https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:48:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:49:00 INFO - Downloaded 20315589 bytes. 22:49:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 22:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:05 INFO - trying https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:49:05 INFO - Downloading https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:49:06 INFO - Downloaded 14413998 bytes. 22:49:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 22:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:06 INFO - trying https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:49:06 INFO - Downloading https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:49:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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 22:49:07 INFO - Downloaded 55842051 bytes. 22:49:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:49:07 INFO - mkdir: /builds/slave/test/properties 22:49:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:49:07 INFO - Writing to file /builds/slave/test/properties/build_url 22:49:07 INFO - Contents: 22:49:07 INFO - build_url:https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:49:08 INFO - Running post-action listener: _resource_record_post_action 22:49:08 INFO - Running post-action listener: set_extra_try_arguments 22:49:08 INFO - [mozharness: 2016-08-30 05:49:08.260046Z] Finished download-and-extract step (success) 22:49:08 INFO - [mozharness: 2016-08-30 05:49:08.260316Z] Running populate-webroot step. 22:49:08 INFO - Running pre-action listener: _resource_record_pre_action 22:49:08 INFO - Running main action method: populate_webroot 22:49:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:49:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:49:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:49:08 INFO - '--tppagecycles', 22:49:08 INFO - '7'], 22:49:08 INFO - 'tests': ['tsvgm']}}, 22:49:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:49:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:49:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:49:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:49:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['damp', 'tps']}, 22:49:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'tests': ['damp', 'tps']}, 22:49:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['dromaeo_dom']}, 22:49:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:49:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['basic_compositor_video']}, 22:49:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:49:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['a11yr', 22:49:08 INFO - 'ts_paint', 22:49:08 INFO - 'tpaint', 22:49:08 INFO - 'sessionrestore', 22:49:08 INFO - 'sessionrestore_no_auto_restore', 22:49:08 INFO - 'tabpaint']}, 22:49:08 INFO - 'other-e10s': {'tests': ['a11yr', 22:49:08 INFO - 'ts_paint', 22:49:08 INFO - 'tpaint', 22:49:08 INFO - 'sessionrestore', 22:49:08 INFO - 'sessionrestore_no_auto_restore', 22:49:08 INFO - 'tabpaint']}, 22:49:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:49:08 INFO - 'ts_paint', 22:49:08 INFO - 'tpaint', 22:49:08 INFO - 'sessionrestore', 22:49:08 INFO - 'sessionrestore_no_auto_restore', 22:49:08 INFO - 'tabpaint']}, 22:49:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:49:08 INFO - 'ts_paint', 22:49:08 INFO - 'tpaint', 22:49:08 INFO - 'sessionrestore', 22:49:08 INFO - 'sessionrestore_no_auto_restore', 22:49:08 INFO - 'tabpaint']}, 22:49:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['a11yr', 22:49:08 INFO - 'ts_paint', 22:49:08 INFO - 'tpaint', 22:49:08 INFO - 'sessionrestore', 22:49:08 INFO - 'sessionrestore_no_auto_restore', 22:49:08 INFO - 'tabpaint']}, 22:49:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['a11yr', 22:49:08 INFO - 'ts_paint', 22:49:08 INFO - 'tpaint', 22:49:08 INFO - 'sessionrestore', 22:49:08 INFO - 'sessionrestore_no_auto_restore', 22:49:08 INFO - 'tabpaint']}, 22:49:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['tsvgx', 22:49:08 INFO - 'tsvgr_opacity', 22:49:08 INFO - 'tart', 22:49:08 INFO - 'tscrollx', 22:49:08 INFO - 'cart']}, 22:49:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:49:08 INFO - 'tsvgr_opacity', 22:49:08 INFO - 'tart', 22:49:08 INFO - 'tscrollx', 22:49:08 INFO - 'cart']}, 22:49:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'talos_options': ['--disable-e10s'], 22:49:08 INFO - 'tests': ['tp5o']}, 22:49:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'tests': ['tp5o']}, 22:49:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'talos_options': ['--disable-e10s', 22:49:08 INFO - '--xperf_path', 22:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:08 INFO - 'tests': ['tp5n']}, 22:49:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:08 INFO - 'talos_options': ['--xperf_path', 22:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:08 INFO - 'tests': ['tp5n']}}, 22:49:08 INFO - 'talos.zip': {'path': '', 22:49:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:49:08 INFO - Running post-action listener: _resource_record_post_action 22:49:08 INFO - [mozharness: 2016-08-30 05:49:08.276227Z] Finished populate-webroot step (success) 22:49:08 INFO - [mozharness: 2016-08-30 05:49:08.276330Z] Running create-virtualenv step. 22:49:08 INFO - Running pre-action listener: _resource_record_pre_action 22:49:08 INFO - Running main action method: create_virtualenv 22:49:08 INFO - Creating virtualenv /builds/slave/test/build/venv 22:49:08 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 22:49:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:49:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:49:08 INFO - Using real prefix '/usr' 22:49:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:49:08 INFO - Installing distribute.............................................................................................................................................................................................done. 22:49:10 INFO - Installing pip.................done. 22:49:10 INFO - Return code: 0 22:49:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:49:10 INFO - Using env: {'DISPLAY': ':0', 22:49:10 INFO - 'HOME': '/home/cltbld', 22:49:10 INFO - 'LANG': 'en_US.UTF-8', 22:49:10 INFO - 'LANGUAGE': 'en_US:en', 22:49:10 INFO - 'LOGNAME': 'cltbld', 22:49:10 INFO - 'MAIL': '/var/mail/cltbld', 22:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:10 INFO - 'MOZ_NO_REMOTE': '1', 22:49:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:10 INFO - 'NO_EM_RESTART': '1', 22:49:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:10 INFO - 'PWD': '/builds/slave/test', 22:49:10 INFO - 'SHELL': '/bin/bash', 22:49:10 INFO - 'SHLVL': '1', 22:49:10 INFO - 'TERM': 'linux', 22:49:10 INFO - 'TMOUT': '86400', 22:49:10 INFO - 'USER': 'cltbld', 22:49:10 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:10 INFO - '_': '/tools/buildbot/bin/python'} 22:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:11 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 22:49:11 INFO - Cleaning up... 22:49:11 INFO - Return code: 0 22:49:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:49:11 INFO - Using env: {'DISPLAY': ':0', 22:49:11 INFO - 'HOME': '/home/cltbld', 22:49:11 INFO - 'LANG': 'en_US.UTF-8', 22:49:11 INFO - 'LANGUAGE': 'en_US:en', 22:49:11 INFO - 'LOGNAME': 'cltbld', 22:49:11 INFO - 'MAIL': '/var/mail/cltbld', 22:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:11 INFO - 'MOZ_NO_REMOTE': '1', 22:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:11 INFO - 'NO_EM_RESTART': '1', 22:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:11 INFO - 'PWD': '/builds/slave/test', 22:49:11 INFO - 'SHELL': '/bin/bash', 22:49:11 INFO - 'SHLVL': '1', 22:49:11 INFO - 'TERM': 'linux', 22:49:11 INFO - 'TMOUT': '86400', 22:49:11 INFO - 'USER': 'cltbld', 22:49:11 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:11 INFO - '_': '/tools/buildbot/bin/python'} 22:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:11 INFO - Downloading/unpacking psutil>=3.1.1 22:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:49:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:13 INFO - Installing collected packages: psutil 22:49:13 INFO - Running setup.py install for psutil 22:49:13 INFO - building 'psutil._psutil_linux' extension 22:49:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:49:13 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 22:49:13 INFO - building 'psutil._psutil_posix' extension 22:49:13 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 22:49:13 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 22:49:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:13 INFO - Successfully installed psutil 22:49:13 INFO - Cleaning up... 22:49:13 INFO - Return code: 0 22:49:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:49:13 INFO - Using env: {'DISPLAY': ':0', 22:49:13 INFO - 'HOME': '/home/cltbld', 22:49:13 INFO - 'LANG': 'en_US.UTF-8', 22:49:13 INFO - 'LANGUAGE': 'en_US:en', 22:49:13 INFO - 'LOGNAME': 'cltbld', 22:49:13 INFO - 'MAIL': '/var/mail/cltbld', 22:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:13 INFO - 'MOZ_NO_REMOTE': '1', 22:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:13 INFO - 'NO_EM_RESTART': '1', 22:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:13 INFO - 'PWD': '/builds/slave/test', 22:49:13 INFO - 'SHELL': '/bin/bash', 22:49:13 INFO - 'SHLVL': '1', 22:49:13 INFO - 'TERM': 'linux', 22:49:13 INFO - 'TMOUT': '86400', 22:49:13 INFO - 'USER': 'cltbld', 22:49:13 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:13 INFO - '_': '/tools/buildbot/bin/python'} 22:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:49:15 INFO - Installing collected packages: mozsystemmonitor 22:49:15 INFO - Running setup.py install for mozsystemmonitor 22:49:16 INFO - Successfully installed mozsystemmonitor 22:49:16 INFO - Cleaning up... 22:49:16 INFO - Return code: 0 22:49:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:49:16 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')]} 22:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:16 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')]} 22:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:16 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 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:16 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 22:49:16 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 22:49:16 INFO - Using env: {'DISPLAY': ':0', 22:49:16 INFO - 'HOME': '/home/cltbld', 22:49:16 INFO - 'LANG': 'en_US.UTF-8', 22:49:16 INFO - 'LANGUAGE': 'en_US:en', 22:49:16 INFO - 'LOGNAME': 'cltbld', 22:49:16 INFO - 'MAIL': '/var/mail/cltbld', 22:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:16 INFO - 'MOZ_NO_REMOTE': '1', 22:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:16 INFO - 'NO_EM_RESTART': '1', 22:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:16 INFO - 'PWD': '/builds/slave/test', 22:49:16 INFO - 'SHELL': '/bin/bash', 22:49:16 INFO - 'SHLVL': '1', 22:49:16 INFO - 'TERM': 'linux', 22:49:16 INFO - 'TMOUT': '86400', 22:49:16 INFO - 'USER': 'cltbld', 22:49:16 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:16 INFO - '_': '/tools/buildbot/bin/python'} 22:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:16 INFO - Downloading/unpacking jsonschema==2.5.1 22:49:16 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 22:49:16 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 22:49:16 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 22:49:16 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 22:49:16 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 22:49:16 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 22:49:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:49:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:18 INFO - Downloading functools32-3.2.3-2.tar.gz 22:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:49:18 INFO - warning: no files found matching '*.txt' 22:49:18 INFO - no previously-included directories found matching 'build' 22:49:18 INFO - no previously-included directories found matching 'dist' 22:49:18 INFO - no previously-included directories found matching '.git*' 22:49:18 INFO - Installing collected packages: jsonschema, functools32 22:49:18 INFO - Running setup.py install for functools32 22:49:18 INFO - warning: no files found matching '*.txt' 22:49:18 INFO - no previously-included directories found matching 'build' 22:49:18 INFO - no previously-included directories found matching 'dist' 22:49:18 INFO - no previously-included directories found matching '.git*' 22:49:18 INFO - Successfully installed jsonschema functools32 22:49:18 INFO - Cleaning up... 22:49:18 INFO - Return code: 0 22:49:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:49:18 INFO - Using env: {'DISPLAY': ':0', 22:49:18 INFO - 'HOME': '/home/cltbld', 22:49:18 INFO - 'LANG': 'en_US.UTF-8', 22:49:18 INFO - 'LANGUAGE': 'en_US:en', 22:49:18 INFO - 'LOGNAME': 'cltbld', 22:49:18 INFO - 'MAIL': '/var/mail/cltbld', 22:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:18 INFO - 'MOZ_NO_REMOTE': '1', 22:49:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:18 INFO - 'NO_EM_RESTART': '1', 22:49:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:18 INFO - 'PWD': '/builds/slave/test', 22:49:18 INFO - 'SHELL': '/bin/bash', 22:49:18 INFO - 'SHLVL': '1', 22:49:18 INFO - 'TERM': 'linux', 22:49:18 INFO - 'TMOUT': '86400', 22:49:18 INFO - 'USER': 'cltbld', 22:49:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:18 INFO - '_': '/tools/buildbot/bin/python'} 22:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:49:18 INFO - Cleaning up... 22:49:18 INFO - Return code: 0 22:49:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:49:18 INFO - Using env: {'DISPLAY': ':0', 22:49:18 INFO - 'HOME': '/home/cltbld', 22:49:18 INFO - 'LANG': 'en_US.UTF-8', 22:49:18 INFO - 'LANGUAGE': 'en_US:en', 22:49:18 INFO - 'LOGNAME': 'cltbld', 22:49:18 INFO - 'MAIL': '/var/mail/cltbld', 22:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:18 INFO - 'MOZ_NO_REMOTE': '1', 22:49:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:18 INFO - 'NO_EM_RESTART': '1', 22:49:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:18 INFO - 'PWD': '/builds/slave/test', 22:49:18 INFO - 'SHELL': '/bin/bash', 22:49:18 INFO - 'SHLVL': '1', 22:49:18 INFO - 'TERM': 'linux', 22:49:18 INFO - 'TMOUT': '86400', 22:49:18 INFO - 'USER': 'cltbld', 22:49:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:18 INFO - '_': '/tools/buildbot/bin/python'} 22:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:19 INFO - Downloading/unpacking blobuploader==1.2.4 22:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:20 INFO - Downloading blobuploader-1.2.4.tar.gz 22:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:49:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:49:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:21 INFO - Downloading docopt-0.6.1.tar.gz 22:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:49:21 INFO - Installing collected packages: blobuploader, requests, docopt 22:49:21 INFO - Running setup.py install for blobuploader 22:49:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:49:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:49:21 INFO - Running setup.py install for requests 22:49:22 INFO - Running setup.py install for docopt 22:49:22 INFO - Successfully installed blobuploader requests docopt 22:49:22 INFO - Cleaning up... 22:49:22 INFO - Return code: 0 22:49:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22: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')]} 22:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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')]} 22:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:22 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 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:22 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 22:49:22 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 22:49:22 INFO - Using env: {'DISPLAY': ':0', 22:49:22 INFO - 'HOME': '/home/cltbld', 22:49:22 INFO - 'LANG': 'en_US.UTF-8', 22:49:22 INFO - 'LANGUAGE': 'en_US:en', 22:49:22 INFO - 'LOGNAME': 'cltbld', 22:49:22 INFO - 'MAIL': '/var/mail/cltbld', 22:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:22 INFO - 'MOZ_NO_REMOTE': '1', 22:49:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:22 INFO - 'NO_EM_RESTART': '1', 22:49:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:22 INFO - 'PWD': '/builds/slave/test', 22:49:22 INFO - 'SHELL': '/bin/bash', 22:49:22 INFO - 'SHLVL': '1', 22:49:22 INFO - 'TERM': 'linux', 22:49:22 INFO - 'TMOUT': '86400', 22:49:22 INFO - 'USER': 'cltbld', 22:49:22 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:22 INFO - '_': '/tools/buildbot/bin/python'} 22:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:49:22 INFO - Running setup.py (path:/tmp/pip-DVOxMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:49:22 INFO - Running setup.py (path:/tmp/pip-8yURpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:49:22 INFO - Running setup.py (path:/tmp/pip-nu2XPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:49:22 INFO - Running setup.py (path:/tmp/pip-qVo7Z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:49:22 INFO - Running setup.py (path:/tmp/pip-emZB5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:49:22 INFO - Running setup.py (path:/tmp/pip-Qr2Zxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:49:22 INFO - Running setup.py (path:/tmp/pip-QzWNz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:49:23 INFO - Running setup.py (path:/tmp/pip-9BpMae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:49:23 INFO - Running setup.py (path:/tmp/pip-h3OpID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:23 INFO - Running setup.py (path:/tmp/pip-KeTYNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:23 INFO - Running setup.py (path:/tmp/pip-7IeQnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:23 INFO - Running setup.py (path:/tmp/pip-PNIX9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:23 INFO - Running setup.py (path:/tmp/pip-LoNWym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:23 INFO - Running setup.py (path:/tmp/pip-7b3DCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:23 INFO - Running setup.py (path:/tmp/pip-Z2qybE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:23 INFO - Running setup.py (path:/tmp/pip-fFm988-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:23 INFO - Running setup.py (path:/tmp/pip-CoJffi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:49:23 INFO - Running setup.py install for manifestparser 22:49:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:49:23 INFO - Running setup.py install for mozcrash 22:49:23 INFO - Running setup.py install for mozdebug 22:49:24 INFO - Running setup.py install for mozdevice 22:49:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for mozfile 22:49:24 INFO - Running setup.py install for mozhttpd 22:49:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for mozinfo 22:49:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for mozInstall 22:49:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for mozleak 22:49:24 INFO - Running setup.py install for mozlog 22:49:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for moznetwork 22:49:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for mozprocess 22:49:25 INFO - Running setup.py install for mozprofile 22:49:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Running setup.py install for mozrunner 22:49:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Running setup.py install for mozscreenshot 22:49:25 INFO - Running setup.py install for moztest 22:49:25 INFO - Running setup.py install for mozversion 22:49:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:49:25 INFO - Cleaning up... 22:49:25 INFO - Return code: 0 22:49:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:25 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')]} 22:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:25 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')]} 22:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:25 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 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:25 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 22:49:25 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 22:49:25 INFO - Using env: {'DISPLAY': ':0', 22:49:25 INFO - 'HOME': '/home/cltbld', 22:49:25 INFO - 'LANG': 'en_US.UTF-8', 22:49:25 INFO - 'LANGUAGE': 'en_US:en', 22:49:25 INFO - 'LOGNAME': 'cltbld', 22:49:25 INFO - 'MAIL': '/var/mail/cltbld', 22:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:25 INFO - 'MOZ_NO_REMOTE': '1', 22:49:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:25 INFO - 'NO_EM_RESTART': '1', 22:49:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:25 INFO - 'PWD': '/builds/slave/test', 22:49:25 INFO - 'SHELL': '/bin/bash', 22:49:25 INFO - 'SHLVL': '1', 22:49:25 INFO - 'TERM': 'linux', 22:49:25 INFO - 'TMOUT': '86400', 22:49:25 INFO - 'USER': 'cltbld', 22:49:25 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:25 INFO - '_': '/tools/buildbot/bin/python'} 22:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:49:25 INFO - Running setup.py (path:/tmp/pip-i0Quye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:49:25 INFO - Running setup.py (path:/tmp/pip-A9ZybY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:49:25 INFO - Running setup.py (path:/tmp/pip-zDmoG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:49:26 INFO - Running setup.py (path:/tmp/pip-Zqm7gU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:49:26 INFO - Running setup.py (path:/tmp/pip-eIFTMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:49:26 INFO - Running setup.py (path:/tmp/pip-yK7TRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:49:26 INFO - Running setup.py (path:/tmp/pip-5RZC6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:49:26 INFO - Running setup.py (path:/tmp/pip-iWc3DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:49:26 INFO - Running setup.py (path:/tmp/pip-WRzZ3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:26 INFO - Running setup.py (path:/tmp/pip-T4d9sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:26 INFO - Running setup.py (path:/tmp/pip-fNeHI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:26 INFO - Running setup.py (path:/tmp/pip-gdYVko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:26 INFO - Running setup.py (path:/tmp/pip-DWm55z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:26 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)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:26 INFO - Running setup.py (path:/tmp/pip-Hg8qNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:26 INFO - Running setup.py (path:/tmp/pip-8IgMMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:26 INFO - Running setup.py (path:/tmp/pip-zc87AM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:26 INFO - Running setup.py (path:/tmp/pip-ooHc_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:27 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)) 22:49:27 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)) 22:49:27 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)) 22:49:27 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)) 22:49:27 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)) 22:49:27 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)) 22:49:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:49:27 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 22:49:27 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 22:49:27 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 22:49:27 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 22:49:27 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 22:49:27 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 22:49:28 INFO - Downloading blessings-1.6.tar.gz 22:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:49:28 INFO - Installing collected packages: blessings 22:49:28 INFO - Running setup.py install for blessings 22:49:29 INFO - Successfully installed blessings 22:49:29 INFO - Cleaning up... 22:49:29 INFO - Return code: 0 22:49:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:49:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:29 INFO - Reading from file tmpfile_stdout 22:49:29 INFO - Current package versions: 22:49:29 INFO - argparse == 1.2.1 22:49:29 INFO - blessings == 1.6 22:49:29 INFO - blobuploader == 1.2.4 22:49:29 INFO - docopt == 0.6.1 22:49:29 INFO - functools32 == 3.2.3-2 22:49:29 INFO - jsonschema == 2.5.1 22:49:29 INFO - manifestparser == 1.1 22:49:29 INFO - mozInstall == 1.12 22:49:29 INFO - mozcrash == 0.17 22:49:29 INFO - mozdebug == 0.1 22:49:29 INFO - mozdevice == 0.48 22:49:29 INFO - mozfile == 1.2 22:49:29 INFO - mozhttpd == 0.7 22:49:29 INFO - mozinfo == 0.9 22:49:29 INFO - mozleak == 0.1 22:49:29 INFO - mozlog == 3.3 22:49:29 INFO - moznetwork == 0.27 22:49:29 INFO - mozprocess == 0.23 22:49:29 INFO - mozprofile == 0.28 22:49:29 INFO - mozrunner == 6.12 22:49:29 INFO - mozscreenshot == 0.1 22:49:29 INFO - mozsystemmonitor == 0.3 22:49:29 INFO - moztest == 0.7 22:49:29 INFO - mozversion == 1.4 22:49:29 INFO - psutil == 3.1.1 22:49:29 INFO - requests == 1.2.3 22:49:29 INFO - wsgiref == 0.1.2 22:49:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:29 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')]} 22:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:29 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')]} 22:49:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:29 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 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:29 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 22:49:29 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 22:49:29 INFO - Using env: {'DISPLAY': ':0', 22:49:29 INFO - 'HOME': '/home/cltbld', 22:49:29 INFO - 'LANG': 'en_US.UTF-8', 22:49:29 INFO - 'LANGUAGE': 'en_US:en', 22:49:29 INFO - 'LOGNAME': 'cltbld', 22:49:29 INFO - 'MAIL': '/var/mail/cltbld', 22:49:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:29 INFO - 'MOZ_NO_REMOTE': '1', 22:49:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:29 INFO - 'NO_EM_RESTART': '1', 22:49:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:29 INFO - 'PWD': '/builds/slave/test', 22:49:29 INFO - 'SHELL': '/bin/bash', 22:49:29 INFO - 'SHLVL': '1', 22:49:29 INFO - 'TERM': 'linux', 22:49:29 INFO - 'TMOUT': '86400', 22:49:29 INFO - 'USER': 'cltbld', 22:49:29 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:29 INFO - '_': '/tools/buildbot/bin/python'} 22:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 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)) 22:49:29 INFO - Cleaning up... 22:49:29 INFO - Return code: 0 22:49:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:49:29 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')]} 22:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:29 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')]} 22:49:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:29 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 0x7f4cea35af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b61230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, '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': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', '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 22:49:29 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 22:49:29 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 22:49:29 INFO - Using env: {'DISPLAY': ':0', 22:49:29 INFO - 'HOME': '/home/cltbld', 22:49:29 INFO - 'LANG': 'en_US.UTF-8', 22:49:29 INFO - 'LANGUAGE': 'en_US:en', 22:49:29 INFO - 'LOGNAME': 'cltbld', 22:49:29 INFO - 'MAIL': '/var/mail/cltbld', 22:49:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:29 INFO - 'MOZ_NO_REMOTE': '1', 22:49:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:29 INFO - 'NO_EM_RESTART': '1', 22:49:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:29 INFO - 'PWD': '/builds/slave/test', 22:49:29 INFO - 'SHELL': '/bin/bash', 22:49:29 INFO - 'SHLVL': '1', 22:49:29 INFO - 'TERM': 'linux', 22:49:29 INFO - 'TMOUT': '86400', 22:49:29 INFO - 'USER': 'cltbld', 22:49:29 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:29 INFO - '_': '/tools/buildbot/bin/python'} 22:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:49:29 INFO - Cleaning up... 22:49:29 INFO - Return code: 0 22:49:29 INFO - Running post-action listener: _resource_record_post_action 22:49:29 INFO - Running post-action listener: _start_resource_monitoring 22:49:29 INFO - Starting resource monitoring. 22:49:29 INFO - [mozharness: 2016-08-30 05:49:29.842347Z] Finished create-virtualenv step (success) 22:49:29 INFO - [mozharness: 2016-08-30 05:49:29.842813Z] Running install step. 22:49:29 INFO - Running pre-action listener: _resource_record_pre_action 22:49:29 INFO - Running main action method: install 22:49:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:30 INFO - Reading from file tmpfile_stdout 22:49:30 INFO - Detecting whether we're running mozinstall >=1.0... 22:49:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:49:30 INFO - Reading from file tmpfile_stdout 22:49:30 INFO - Output received: 22:49:30 INFO - Usage: mozinstall [options] installer 22:49:30 INFO - Options: 22:49:30 INFO - -h, --help show this help message and exit 22:49:30 INFO - -d DEST, --destination=DEST 22:49:30 INFO - Directory to install application into. [default: 22:49:30 INFO - "/builds/slave/test"] 22:49:30 INFO - --app=APP Application being installed. [default: firefox] 22:49:30 INFO - mkdir: /builds/slave/test/build/application 22:49:30 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'] 22:49:30 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 22:49:46 INFO - Reading from file tmpfile_stdout 22:49:46 INFO - Output received: 22:49:46 INFO - /builds/slave/test/build/application/firefox/firefox 22:49:46 INFO - Running post-action listener: _resource_record_post_action 22:49:46 INFO - [mozharness: 2016-08-30 05:49:46.264850Z] Finished install step (success) 22:49:46 INFO - [mozharness: 2016-08-30 05:49:46.265133Z] Running run-tests step. 22:49:46 INFO - Running pre-action listener: _resource_record_pre_action 22:49:46 INFO - Running main action method: run_tests 22:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:49:46 INFO - Python 2.7.3 22:49:46 INFO - Return code: 0 22:49:46 INFO - grabbing minidump binary from tooltool 22:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5b090>, '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 0x2b5e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b5ee40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:49:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:49:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:49:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:49:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:49:46 INFO - Return code: 0 22:49:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:49:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:49:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:49:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:49:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 22:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 22:49:46 INFO - Using env: {'DISPLAY': ':0', 22:49:46 INFO - 'HOME': '/home/cltbld', 22:49:46 INFO - 'LANG': 'en_US.UTF-8', 22:49:46 INFO - 'LANGUAGE': 'en_US:en', 22:49:46 INFO - 'LOGNAME': 'cltbld', 22:49:46 INFO - 'MAIL': '/var/mail/cltbld', 22:49:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:49:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:46 INFO - 'MOZ_NO_REMOTE': '1', 22:49:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:46 INFO - 'NO_EM_RESTART': '1', 22:49:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:46 INFO - 'PWD': '/builds/slave/test', 22:49:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:49:46 INFO - 'SHELL': '/bin/bash', 22:49:46 INFO - 'SHLVL': '1', 22:49:46 INFO - 'TERM': 'linux', 22:49:46 INFO - 'TMOUT': '86400', 22:49:46 INFO - 'USER': 'cltbld', 22:49:46 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584', 22:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:46 INFO - '_': '/tools/buildbot/bin/python'} 22:49:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 22:49:46 INFO - mozversion application_buildid: 20160829212920 22:49:46 INFO - mozversion application_changeset: 26e22af660e543ebb69930f082188b69ec756185 22:49:46 INFO - mozversion application_display_name: Nightly 22:49:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:49:46 INFO - mozversion application_name: Firefox 22:49:46 INFO - mozversion application_remotingname: firefox 22:49:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:49:46 INFO - mozversion application_vendor: Mozilla 22:49:46 INFO - mozversion application_version: 51.0a1 22:49:46 INFO - mozversion platform_buildid: 20160829212920 22:49:46 INFO - mozversion platform_changeset: 26e22af660e543ebb69930f082188b69ec756185 22:49:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:49:46 INFO - mozversion platform_version: 51.0a1 22:49:46 INFO - using testdate: 1472536186 22:49:46 INFO - actual date: 1472536186 22:49:46 INFO - starting webserver on 'localhost:53352' 22:49:46 INFO - SUITE-START | Running 2 tests 22:49:46 INFO - TEST-START | dromaeo_css 22:49:46 INFO - operating with platform_type : linux_ 22:49:46 INFO - Initialising browser for dromaeo_css test... 22:49:46 INFO - TEST-INFO | started process 31665 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqEqh1d/profile http://localhost:53352/getInfo.html) 22:49:50 INFO - PROCESS | 31665 | __metrics Screen width/height:1600/1200 22:49:50 INFO - PROCESS | 31665 | colorDepth:24 22:49:50 INFO - PROCESS | 31665 | Browser inner width/height: 1024/697 22:49:50 INFO - PROCESS | 31665 | __metrics 22:49:51 INFO - PROCESS | 31665 | 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" 22:49:51 INFO - PROCESS | 31665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:49:51 INFO - PROCESS | 31665 | console.error: PushService: 22:49:51 INFO - PROCESS | 31665 | stateChangeProcessEnqueue: Error transitioning state 22:49:51 INFO - PROCESS | 31665 | UnknownError 22:49:52 INFO - TEST-INFO | 31665: exit 0 22:49:52 INFO - Browser initialized. 22:49:52 INFO - Running cycle 1/1 for dromaeo_css test... 22:49:52 INFO - TEST-INFO | started process 31745 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqEqh1d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:04 INFO - PROCESS | 31745 | RSS: Main: 182513664 22:50:04 INFO - PROCESS | 31745 | 22:50:55 INFO - PROCESS | 31745 | 1472536255834 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] 22:53:08 INFO - PROCESS | 31745 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53352/tests/dromaeo/cssquery-ext.html) 22:53:10 INFO - PROCESS | 31745 | RSS: Main: 283332608 22:53:10 INFO - PROCESS | 31745 | 22:56:12 INFO - PROCESS | 31745 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-ext.html (next: http://localhost:53352/tests/dromaeo/cssquery-jquery.html) 22:56:13 INFO - PROCESS | 31745 | RSS: Main: 278384640 22:56:13 INFO - PROCESS | 31745 | 22:59:15 INFO - PROCESS | 31745 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53352/tests/dromaeo/cssquery-mootools.html) 22:59:16 INFO - PROCESS | 31745 | RSS: Main: 257884160 22:59:16 INFO - PROCESS | 31745 | 23:02:18 INFO - PROCESS | 31745 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53352/tests/dromaeo/cssquery-prototype.html) 23:02:19 INFO - PROCESS | 31745 | RSS: Main: 220332032 23:02:19 INFO - PROCESS | 31745 | 23:05:22 INFO - PROCESS | 31745 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53352/tests/dromaeo/cssquery-yui.html) 23:05:23 INFO - PROCESS | 31745 | RSS: Main: 220057600 23:05:23 INFO - PROCESS | 31745 | 23:08:25 INFO - PROCESS | 31745 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-yui.html (next: http://localhost:53352/tests/dromaeo/cssquery-dojo.html) 23:08:26 INFO - PROCESS | 31745 | RSS: Main: 310501376 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | __start_tp_report 23:08:26 INFO - PROCESS | 31745 | _x_x_mozilla_page_load 23:08:26 INFO - PROCESS | 31745 | _x_x_mozilla_page_load_details 23:08:26 INFO - PROCESS | 31745 | |i|pagename|runs| 23:08:26 INFO - PROCESS | 31745 | |0;dojo.html;2616.672915885514;2619.9345016374587;2635.5651317532606;2675.8795854186574;2685.624012638231;4658.79035443405;4696.037312350968;4709.128811169936;4794.400699912507;4848.006158737455;4688.648351373652;4745.549172828584;4745.8813529661775;4749.097671442423;4799.232122860334;4309.332053531708;4322.394864718942;4389.056352884129;4449.532799056101;4514.232580461329;4385.407969924067;4443.289073748201;4458.710183838057;4484.349769283452;4496.820127194925;4323.437953066082;4351.19502891966;4355.5644435556505;4362;4411.889702757457;4337;4344.196323680129;4402.031553058322;4419.602235798761;4476.149531588988;4263.104748002923;4344.066025804467;4345.543717909605;4356.237658409797;4401.977990110061;4687.929681054787;4731.976340118277;4739.0758802033615;4743.051389722069;4796.160671882441;4957.677750946177;5022.748862556858;5074.898502029976;5076.289319495274;5104.259882317043;5076.36545431821;5105.617078719111;5107.1828507439;5114.33513643224;5115.820946266863;4613.123506533748;4800.135975524439;4808.8797780055775;4847.684900481493;4860.392450943632;5173.81891633791;5188.610854185876;5201.3238279024245;5201.661891977009;5274.393444753826;4994.4755800640405;5007.3240112584335;5007.549320561167;5011.072951503984;5071.949280507148;4937;5084.110280700863;5086.415062267813;5090.10923088458;5094.2103973884105;4963.329950456714;5023.598112151092;5027.899442011139;5051.62112841531;5111.207762796773;5018.021485810232;5028.79884804611;5031.723255220998;5032.572231360302;5088.363954505687;4151.3772934059525;4218.641415479658;4221.2401767681495;4230.915381692349;4276.935845962313;3673.1551743099235;3702.7037836972977;3726.4410338448906;3770.792606406674;3824.158685089276;4435.733855968652;4461.57615026572;4466.7766611669285;4469.08383781324;4527.1172121436;5130.769115389817;5143.12566863634;5148.922766158511;5148.995945790535;5211.348581427322;4356.520782713899;4411;4431.446069241345;4436.0462500562535;4505.61702255312;4540.77296135192;4544.477385100756;4553.248713962159;4554;4612.377329060601;4805.471398146201;4818.6626936114835;4827.879303017453;4835.540623640749;4880.853574392774;5030.899382012266;5067.543921047123;5101.719405432588;5110.744462776921;5176.50823171798;4778.617710583215;4807.4471435783935;4814.325994360863;4818.927716084331;4865.1485989952325;5016.498350164954;5034.219695947134;5037.899242015066;5046.6719663221775;5108.463611320906;4963.528464795839;5088.821891233789;5089.287499750112;5129.717865517432;5151.072806894795;4998.925016124834;5006.874828129326;5013.172826483736;5016.849494515171;5053.924191137063;4963.5780958618925;4965.205567109389;4972.105021096238;4974.204127339608;5066.240063990431;4807.399075115611;4862.902741945212;4891.755412229445;4899.265110233494;4946.35697359341;4952.8761780955765;5007.974960125176;5026.145555255543;5026.5727413168825;5033.169527028;4820.25286080658;4824.179889418737;4824.975875120602;4834.081524510332;4887.511248875084 23:08:26 INFO - PROCESS | 31745 | |1;ext.html;575.0368133376594;577.4283459375217;582.877595704902;586.4487381861051;588.0003993211538;27795.305117372074;38599.80700096499;40246.59753402465;40316.19367612646;43855.561444385545;41014.38478422826;41355.793221033855;41387.58612413875;41453.58546414535;41461.58538414615;7172.139343278814;7674.539527628346;7811.687532498693;7825.217478252186;7825.765227043198;9610.711678649652;9901.059399357086;9907.950460247688;10243.590256389736;10251.89748102517;9801;9831.31180817343;9916.355436896614;9917.801643967116;9923.553440095176;9927.553260103312;9993.750156246078;9997.600095996151;10007.099361057468;10008.849867251996;9289.674861379819;9333.813323733555;9342.57958391874;9348.766280842965;9350.859737103949;37537.81231093854;37833.810830945666;38056.80971595152;38061;38110.04724881872;2623.226148286241;2715.253305341027;2753.4630747004344;3004.489236829744;3220.6940340667597;25967.091151809786;25974.87012564944;26434.6034809478;26822.73177268222;29493;3023.667396586374;3090.2428904918215;3123.3909387669414;3126.5935428394164;3147.464930961719;249129;249280;249425;249902;250023.74988125125;28022.159335219974;28688.713112868605;51275.7436212824;51692.48307516952;51716.48283517117;33060.17349566232;33308.167295817315;33308.833455832566;33310.83344583261;33320.833395832866;12788.232706037668;13044;13044.152130111703;13044.347782610832;13045.478180872658;8766.430182038148;10026.548805303779;10143.137833284105;10461.895381046092;10705.1435885129;7772.45592808498;7774.611269436618;7820.921790782133;7843.215678432111;7869.567173805382;3045.9238519037203;3078.1227350205086;3100.3489267253676;3116.6727493612843;3120.3135310231714;764.8699721047435;811.6428771340709;867.0549101479418;954.7899462118462;983.5868935047297;26230.344241393737;27408.45183096327;27920;28670.283242919093;28765.28086797847;886.1094599927161;895.6506962284714;923.2244914272044;952.4095061062186;964.6961207219844;13963.441462341596;14757.262136893112;14971.475998340005;15342.309596068204;15397.23013849303;353958;354660;354943;355505;357252;48317.75841120772;48759.75620121877;48800.26799598009;48860.75569622129;48876;1043.6138628707427;1107.6234080412698;1120.277421063426;1121.9775604487866;1129;26690.866545667148;28094.578581321704;29244.7075529242;29683.851580742823;31061.564226259863;46700.532994670975;52301.47698522966;52403.73798130985;52415.47584524258;52471.475285246655;350777;354147;354159;355775;366138;35652;39600.80199599099;39632.60367396289;39918.40122398225;40219.1956160881;43662.78168609264;43864.780676096416;44024.779876100416;44156.23375064961;44202;37233;37886.62113378831;38012.23975520529;38356.61643383642;39478.21043579055;37822.43266350952;37885.81057094697;38078.80960595179;39708.60291397049;39758.403623946244;36551.45172822357;37123.81438092792;37548;37925.24149516939;38072.238555228185;32073.556689948542;34013.48979765256;34679.826600866836;37074;37974;26104.216873493824;26117.477650446504;26208.606870897333;26239.60640590431;26426.60360094562 23:08:26 INFO - PROCESS | 31745 | |2;jquery.html;344.77245018287937;362.3821384539361;367.5020347429233;368.3535395381107;380.63839352615;12612.684682882933;16034.59913502163;16547.42084027059;17849.643007139883;18022.18900149493;17002.3199072037;17028.999999999967;17168.22742976566;17255;17271;5029.1199040168;5063.34176557048;5253.133233016548;5287.762050707708;5399;3354.7316214702764;5303.549198318147;5341.572674186055;5347.304850369446;5720.170575266584;6004.639721616695;6080.483158931496;6084.361142080084;6103.908441373383;6109.053096770008;5897.4692277694785;5978.282606087254;6047.334793172748;6062.272527296708;6101.237345331833;5359.598030147755;5364.0612892744;5366.302380690524;5387.78448862045;5395.676259424447;12600.68498287541;14360.640983975483;20181.798182018138;20318;20318.28885988983;1701.3449821818565;1728.3605066125615;1728.5246557196747;1741.2077504735314;1743.4159556548445;8616.439931404439;9038.502882341469;9177.128172823572;10291.125254353388;12427.627171184879;1612.991935040329;1671.0432084029712;1673.1383337581033;1703.3101593854508;1723.3623559283021;67309.32690673128;71952.64023679848;71969.6401517989;71984.6400767998;73825.6308718453;9823.410595364301;9982.158653053264;9993.950030249947;10256;10269.897301027044;13988.440462381594;14011.649708757363;14019.228942408265;14020.158790472604;14020.228887411291;6969.47728920333;7039.366457018893;7097.92902070983;7167.498275120692;7392.075990501187;6551.148350714472;6673.299303573152;6820.454363650995;6998.75504357345;7015.9298407016295;4138.882501724517;4303.978480107579;4333.501647310537;4409.889752756143;4430.113977204572;1777.9288828446977;1805.7562229099167;1812.1482903035817;1828.87197896146;1858.7676540432446;639.8080575827233;759.9772006839804;788.9703936363102;847.9788005299794;868.6829307302789;15933.247342792283;17149.314027439013;17376;17469.38857139994;17565.912170439067;807.3823525003377;809.514291425152;819;831.339085427087;877.644553955649;8142;9455.716328510156;9462.479563624067;9690.224782017422;9697.127258546765;282352;284094;284155;378122;388683;54452.727736361856;71456.285437146;79160.60419697865;80345.59827200827;81661;893.5889490834159;907.832051070554;950.9714708558754;955.8375076237056;956.3974695941652;15478;15544.766828497808;15851.445199417965;16434.506964791075;16438.917805411376;36831.21194244731;36924.815375922946;37365.626343736214;37428.251434971695;37637.435438467895;47957.76021119872;48655;50367.49632503628;52925;52978;7103.680334384861;7324.670389832577;7398.667059982206;7468.066491688539;7554.962225188839;11139.220254582102;12002.066238849222;12418.316992565495;12450.75098498043;12457.937710311391;6964.686589103604;7176.921194688619;7180.820479487846;8425.95787021061;10370.792584148425;9861.309708320348;9979;10575.312604680672;10586;10718.624848130277;10379.636712715019;10632.734181645521;10644.680659580225;10852.294600850919;10939.343639381663;7348.301911318416;7413.925860741324;7562.470627056054;7563.621818909142;7728.574928378768;9827.852582211415;10330.638427654996;10485.26603137773;10645.733856653645;11043.779124417306 23:08:26 INFO - PROCESS | 31745 | |3;mootools.html;1342.6173540540942;1394.7907813827926;1444.4872070415001;1641.1302009934739;1678.0686718871023;6882.931170688292;7465.738699145531;7513.173550909396;8054.033515978083;8058.355331573474;7879.448438609285;7968;7972.760817175494;7982.082060563022;7988.600569971507;2193.7806219378094;2216.047099747108;2221.711177546925;2223.7442694090223;2223.7442694090223;1848.0944337274707;2052.887091209983;2141.7429908411013;2144.324537770597;2168.7831216878344;2188.9014994325294;2212.590670725921;2216.0692509146197;2216.6785816056663;2219.678146668732;2094.7172131762322;2102.95903527754;2147.3879944215823;2153.558520503293;2153.8169255613293;2050.620635182496;2054.9486262843398;2055.2601063617085;2058.125296748876;2134.647783115784;7961.1242763295995;8024.63889124985;8040.638171282307;8061.193880611951;8061.717839875635;4436.55634436557;4656.184088205602;4661.953380466186;4669.439667239919;4670.836520721792;2820.3372207531215;2946.2928897064767;2988.417258634567;2997.68524304947;2999.2202027472795;892.638481415028;925.5048549026305;940.4545363689044;942.3639043645512;943.7499062748377;10436.843447348296;10438.530266138043;10448.999999999924;10458.424786636731;10465.058144766932;3683.2817600567632;4058.0724156025653;4209.810558524874;4214.726042807208;4217.451731274915;9052;9072.364934454657;9073.863892041625;9079.092090790868;9080.500572468445;4564.360989461478;4590.977045114753;4594.770261486912;4597.172508948354;4598.977005114953;4119.670426365884;4176.561461046546;4180.310248808913;4184.476940382452;4184.707070505096;3543.557055368079;3645.5078564393466;3672.7245456590863;3699.5930447650735;3703.611170794822;1079.865016872891;1089.3627228071666;1102.1843835561626;1185.8576970763563;1223.4095675621656;584.4097461563852;672.1128110893671;694.1912671737471;697.7383481194552;700.58315302395;4612.192866248393;4686.203345431285;4689.882752931204;4788.1381351356395;4850.611951043908;813.4712436915934;822.7326119011345;822.9711960081369;825.9463134896333;842.5197637346654;3272.4109660261383;3305.5702758641223;3325.3016866458315;3344;3404.4382676858536;9591.184749296248;9738.367006144577;9761.609535618638;9766.902330976742;9828.115469607626;9696.17582505495;9710.126088652056;9712.417254964725;9718.202132605491;9721.368111072758;7010.754623588252;7303.269673032654;7304.196538380774;7476.177620461636;7481.139668938142;5115.1559992600805;5307.336582927134;5332.946670533245;5379.757910893941;5475;8757.47455152693;8995.505247211466;9030.322725795722;9063.818723625622;9159.084091590788;8928.330375221754;9149.908500914906;9323.86014209801;9387.530623468936;9393.154616084312;6474.1907261592305;6511.9348806511325;6562.5406221564035;6564.343565643398;6564.737410503717;7310.81722956909;7356.485046046725;7358.374538164316;7364.263573642593;7376.4836461447185;6300.369963003663;6434.388733070351;6436.742530298736;6448.290688024407;6563.901541476786;6130.509559235161;6131.724072416841;6259.123722678738;6300.810975670737;6452.322506136976;5980.491658209101;6049.0926361046195;6050.21347224858;6058.818235452944;6068.544859135494;4380.8685739427865;4497.865064048084;4531.728096314232;4537.8411755588395;4566.862994110182;4444.28891377378;4526.32105184225;4615.74613396253;4616.076784643018;4745.193317136026 23:08:26 INFO - PROCESS | 31745 | |4;prototype.html;272.6088063628692;273.7481517004356;281.3164011452172;282.1971491107798;285.1729983049157;4798.8242880494245;4841.56425921667;4883.780229889655;4890.239513775047;4891.951080489194;4820.710757354561;4832.903341933159;4837;4843.927341089878;4851.1025460289875;3524.9118772030647;3576;3743.5133432653715;3765.548134223897;3771.1703425246537;3752.2307926874923;3754.4180651998986;3758.81597296852;3773.415120656303;3782.6973842092566;3706.7219958503083;3726.813659317037;3735.1595890924596;3737.2712321097397;3741.5136032315895;3506.736994725393;3588.977141514669;3709.406494960793;3717.126475278307;3725.217704282104;3252.447083995726;3254.7721659483846;3258.608966923984;3261.4129456697783;3271.525628783837;4676.134915040729;4685.672002959795;4686.179918514246;4689.303650401033;4695.319178719066;4114.362273847559;4192.496900371944;4224.957750422487;4227.87316380506;4240.787960601958;4966.180580204262;4980.203167493184;5005.849824505271;5008.774605142741;5009.4239162496415;3572.7141828653644;3573.2674801665858;3573.6605022523077;3573.749187784281;3580.1765593913287;4972.279019542182;5185.5333020028;5198.662086964335;5236.371635403776;5250.264962905197;3471;3475.5134281200653;3479.6520347965507;3519.8240087995496;3527.3474407234744;3564.180238545149;3568.1793187567005;3568.91077723059;3582.5163602913794;3589.7487175897436;4144.233316836395;4146.253674338588;4166.812493437803;4169.874903752892;4173.686973477002;4846.927296090562;5030.673006254582;5037.194048952223;5044.117279476077;5093.516115968977;1224.12475080318;1233.3894722112611;1234.5493894728359;1237.1649136832602;1238.2013601227159;751.2787723785227;773.9071311442663;790.083503136359;790.6955822008568;796.4225936196327;4324.632406245441;4392.802323895432;4484.910301793946;4501.18978583858;4504.707194032378;5194.3247377840635;5194.454582268883;5198.376194856566;5199.896002080013;5204.635675502755;4527.501974782771;4528.547145285445;4535.455745310584;4537.523560026149;4543.182227199136;2606.5829467285143;2743.8750112454195;2752.21106280919;2756.834589924611;2757.420941602246;4888.388951381077;4893.388326459192;4902.338184345068;4909.214525675875;4916.287138364917;5140.922886156711;5272.205730962519;5272.578193744568;5319.893602127936;5320.813771517978;4865.9756701216265;4897.608191344612;4907.5337842904155;4910.9932463844825;4916.164252077156;5113.181890897438;5148.845534633967;5187.351581052369;5191.844244672666;5200.817971370984;4943.975280123576;5013.941495687554;5027;5077.517635824591;5085.152314703778;4798.088363210979;4824.421069471686;4861.593511211831;4882.267659850909;4891.926621100615;3511.543499345069;3532.3111993161087;3534.416821224469;3535.9646403535635;3537.345591065661;5003.949960500494;5111.667741596784;5130.333056702605;5132.999065182403;5152.613553983541;4960.578350840219;4978.775955081957;4995.550400463976;4999.550040496373;5002.749862506933;4973.676711013773;4993.850184494326;4998.825041123543;5007.449180590205;5021.974890125526;4708.92936605958;4929.531694489017;4947.381577302837;4951.10880041596;4955.876103097451;4793.257045157867;4905.264210368473;4999.875003124951;5035.395752509576;5053.393592768891;4845.345878306524;4871.585915197248;4892.407024277139;4904.485029071896;4935.457099718957 23:08:26 INFO - PROCESS | 31745 | |5;yui.html;538.6498775795732;556.4046470276805;580.4166812353585;590.7666471743661;613.454025916934;11822.704432389195;12550.925023369287;12948.870511294885;12960.41678124483;12987.480500779957;13043.934780326108;13061.542846000391;13121.54074607389;13129.606111816636;13140.671483212924;610.5024405109822;743.2790193512297;760.6082867323305;762.4929421934407;762.8474305138994;732.4470025131019;756.6632848382473;761.5430741555078;764.9847003059936;766.4251811141644;791.4301702773994;795.0499153511556;795.2524626850742;795.2683531151355;795.3001358804273;630.4483576870239;792.029763539665;793.6031984007996;793.7658390774735;793.9087004994442;552.2296396526823;566.5354409384265;568.2555851834111;568.9203511508392;569.0468465320572;12150.5747298845;12199.329036902967;12224.755504889941;12230.633081007583;12270.32513211773;11444.828327575093;12077.516899324017;12300.507979680802;12368.752624947541;12555.435005424686;11145.066328997793;11293.435328233638;11363;11470.885291147066;11557.19099663024;3944.7238693291483;4041.959580404188;4063.6952228582677;4102.528209255944;4237.809298581571;64404;64427.999999999534;64889;65345;65542;8933.126095966903;9122.908770912338;9160.633574656938;9240.21458176049;9401.10689484498;16801.66396672084;17526.561835954202;17537.736933946002;17541.82458175402;17597.12014399275;6815.727370905208;6974.581525108409;7010.579365238102;7043.894341584778;7113;3507.2108775525303;3553.4492153716214;3631;3637.217998130415;3668.137987572918;2941.3529023615197;3077.6614572397466;3081.7226449619643;3082.597418174725;3093.6751641077362;476.3307552888198;485.8178183181307;491.6754941738507;496.1119595923329;505.9848204553869;386.18514933490314;389.8284754707976;451.26669162610756;484.7551986246923;502.22406382139684;7132.17979932304;7586.81032974169;7762.961185194151;7839.764807055797;8040.758777236575;388.0183136664244;412.6080223787414;418.9036521600049;521.3040590811233;522.4148953172471;3314.6519615440516;3771.773693578339;3804.3722785740597;3878.45701601776;3902.087976891303;92337;95442.522787387;96960;97834;98058;80632.19367806365;82116.17883821204;82236.58881705553;83524;84392.57803711065;71165;71228.2877171232;72126;72862.27137728661;73237.26762732411;7064.326550193768;7590.81022974408;7810.375169986274;7816.218378162365;7851.136374998631;31151.221219469695;34147.65852341544;41526.792366037975;42502;43286.350704740085;36394.8180259097;39953.00587426572;39966.600333996284;58761;61640.38359616346;7917.830273998534;9356.298277629068;9605.759856003377;9682.467464289532;9925.156361709336;10328.896711032794;10435;10467.94766026165;10594.629187978384;11289.37908415045;9198.586063627286;9310.906890931004;9566.186874115778;9621.27840411958;9898.10917017472;9126.224270936778;9175;9232.584533696134;9275.814483710154;9357.53212339394;8701.43440676354;8710.433821801562;8728.432651877607;8954.149355811187;9212.907870921204;8211.876821847796;8233.547154906299;8639.09289524616;8661;8768.298536116969;8395.706150284956;8857.839058007496;8918.152775486318;9029.503377314311;9198.126178013077 23:08:26 INFO - PROCESS | 31745 | __end_tp_report 23:08:26 INFO - PROCESS | 31745 | __start_cc_report 23:08:26 INFO - PROCESS | 31745 | _x_x_mozilla_cycle_collect,974 23:08:26 INFO - PROCESS | 31745 | __end_cc_report 23:08:26 INFO - PROCESS | 31745 | __startTimestamp1472537306111__endTimestamp 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | ------- Summary: start ------- 23:08:26 INFO - PROCESS | 31745 | Number of tests: 6 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | [#0] dojo.html Cycles:165 Average:4706.48 Median:4827.88 stddev:496.23 (10.3%) stddev-sans-first:469.90 23:08:26 INFO - PROCESS | 31745 | Values: 2616.7 2619.9 2635.6 2675.9 2685.6 4658.8 4696.0 4709.1 4794.4 4848.0 4688.6 4745.5 4745.9 4749.1 4799.2 4309.3 4322.4 4389.1 4449.5 4514.2 4385.4 4443.3 4458.7 4484.3 4496.8 4323.4 4351.2 4355.6 4362.0 4411.9 4337.0 4344.2 4402.0 4419.6 4476.1 4263.1 4344.1 4345.5 4356.2 4402.0 4687.9 4732.0 4739.1 4743.1 4796.2 4957.7 5022.7 5074.9 5076.3 5104.3 5076.4 5105.6 5107.2 5114.3 5115.8 4613.1 4800.1 4808.9 4847.7 4860.4 5173.8 5188.6 5201.3 5201.7 5274.4 4994.5 5007.3 5007.5 5011.1 5071.9 4937.0 5084.1 5086.4 5090.1 5094.2 4963.3 5023.6 5027.9 5051.6 5111.2 5018.0 5028.8 5031.7 5032.6 5088.4 4151.4 4218.6 4221.2 4230.9 4276.9 3673.2 3702.7 3726.4 3770.8 3824.2 4435.7 4461.6 4466.8 4469.1 4527.1 5130.8 5143.1 5148.9 5149.0 5211.3 4356.5 4411.0 4431.4 4436.0 4505.6 4540.8 4544.5 4553.2 4554.0 4612.4 4805.5 4818.7 4827.9 4835.5 4880.9 5030.9 5067.5 5101.7 5110.7 5176.5 4778.6 4807.4 4814.3 4818.9 4865.1 5016.5 5034.2 5037.9 5046.7 5108.5 4963.5 5088.8 5089.3 5129.7 5151.1 4998.9 5006.9 5013.2 5016.8 5053.9 4963.6 4965.2 4972.1 4974.2 5066.2 4807.4 4862.9 4891.8 4899.3 4946.4 4952.9 5008.0 5026.1 5026.6 5033.2 4820.3 4824.2 4825.0 4834.1 4887.5 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | [#1] ext.html Cycles:180 Average:47261.35 Median:37685.22 stddev:85344.83 (226.5%) stddev-sans-first:85512.26 23:08:26 INFO - PROCESS | 31745 | Values: 575.0 577.4 582.9 586.4 588.0 27795.3 38599.8 40246.6 40316.2 43855.6 41014.4 41355.8 41387.6 41453.6 41461.6 7172.1 7674.5 7811.7 7825.2 7825.8 9610.7 9901.1 9908.0 10243.6 10251.9 9801.0 9831.3 9916.4 9917.8 9923.6 9927.6 9993.8 9997.6 10007.1 10008.8 9289.7 9333.8 9342.6 9348.8 9350.9 37537.8 37833.8 38056.8 38061.0 38110.0 2623.2 2715.3 2753.5 3004.5 3220.7 25967.1 25974.9 26434.6 26822.7 29493.0 3023.7 3090.2 3123.4 3126.6 3147.5 249129.0 249280.0 249425.0 249902.0 250023.7 28022.2 28688.7 51275.7 51692.5 51716.5 33060.2 33308.2 33308.8 33310.8 33320.8 12788.2 13044.0 13044.2 13044.3 13045.5 8766.4 10026.5 10143.1 10461.9 10705.1 7772.5 7774.6 7820.9 7843.2 7869.6 3045.9 3078.1 3100.3 3116.7 3120.3 764.9 811.6 867.1 954.8 983.6 26230.3 27408.5 27920.0 28670.3 28765.3 886.1 895.7 923.2 952.4 964.7 13963.4 14757.3 14971.5 15342.3 15397.2 353958.0 354660.0 354943.0 355505.0 357252.0 48317.8 48759.8 48800.3 48860.8 48876.0 1043.6 1107.6 1120.3 1122.0 1129.0 26690.9 28094.6 29244.7 29683.9 31061.6 46700.5 52301.5 52403.7 52415.5 52471.5 350777.0 354147.0 354159.0 355775.0 366138.0 35652.0 39600.8 39632.6 39918.4 40219.2 43662.8 43864.8 44024.8 44156.2 44202.0 37233.0 37886.6 38012.2 38356.6 39478.2 37822.4 37885.8 38078.8 39708.6 39758.4 36551.5 37123.8 37548.0 37925.2 38072.2 32073.6 34013.5 34679.8 37074.0 37974.0 26104.2 26117.5 26208.6 26239.6 26426.6 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | [#2] jquery.html Cycles:180 Average:22332.12 Median:27698.31 stddev:54538.60 (196.9%) stddev-sans-first:54666.60 23:08:26 INFO - PROCESS | 31745 | Values: 344.8 362.4 367.5 368.4 380.6 12612.7 16034.6 16547.4 17849.6 18022.2 17002.3 17029.0 17168.2 17255.0 17271.0 5029.1 5063.3 5253.1 5287.8 5399.0 3354.7 5303.5 5341.6 5347.3 5720.2 6004.6 6080.5 6084.4 6103.9 6109.1 5897.5 5978.3 6047.3 6062.3 6101.2 5359.6 5364.1 5366.3 5387.8 5395.7 12600.7 14360.6 20181.8 20318.0 20318.3 1701.3 1728.4 1728.5 1741.2 1743.4 8616.4 9038.5 9177.1 10291.1 12427.6 1613.0 1671.0 1673.1 1703.3 1723.4 67309.3 71952.6 71969.6 71984.6 73825.6 9823.4 9982.2 9994.0 10256.0 10269.9 13988.4 14011.6 14019.2 14020.2 14020.2 6969.5 7039.4 7097.9 7167.5 7392.1 6551.1 6673.3 6820.5 6998.8 7015.9 4138.9 4304.0 4333.5 4409.9 4430.1 1777.9 1805.8 1812.1 1828.9 1858.8 639.8 760.0 789.0 848.0 868.7 15933.2 17149.3 17376.0 17469.4 17565.9 807.4 809.5 819.0 831.3 877.6 8142.0 9455.7 9462.5 9690.2 9697.1 282352.0 284094.0 284155.0 378122.0 388683.0 54452.7 71456.3 79160.6 80345.6 81661.0 893.6 907.8 951.0 955.8 956.4 15478.0 15544.8 15851.4 16434.5 16438.9 36831.2 36924.8 37365.6 37428.3 37637.4 47957.8 48655.0 50367.5 52925.0 52978.0 7103.7 7324.7 7398.7 7468.1 7555.0 11139.2 12002.1 12418.3 12450.8 12457.9 6964.7 7176.9 7180.8 8426.0 10370.8 9861.3 9979.0 10575.3 10586.0 10718.6 10379.6 10632.7 10644.7 10852.3 10939.3 7348.3 7413.9 7562.5 7563.6 7728.6 9827.9 10330.6 10485.3 10645.7 11043.8 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | [#3] mootools.html Cycles:180 Average:5083.75 Median:5211.25 stddev:2893.90 (55.5%) stddev-sans-first:2888.36 23:08:26 INFO - PROCESS | 31745 | Values: 1342.6 1394.8 1444.5 1641.1 1678.1 6882.9 7465.7 7513.2 8054.0 8058.4 7879.4 7968.0 7972.8 7982.1 7988.6 2193.8 2216.0 2221.7 2223.7 2223.7 1848.1 2052.9 2141.7 2144.3 2168.8 2188.9 2212.6 2216.1 2216.7 2219.7 2094.7 2103.0 2147.4 2153.6 2153.8 2050.6 2054.9 2055.3 2058.1 2134.6 7961.1 8024.6 8040.6 8061.2 8061.7 4436.6 4656.2 4662.0 4669.4 4670.8 2820.3 2946.3 2988.4 2997.7 2999.2 892.6 925.5 940.5 942.4 943.7 10436.8 10438.5 10449.0 10458.4 10465.1 3683.3 4058.1 4209.8 4214.7 4217.5 9052.0 9072.4 9073.9 9079.1 9080.5 4564.4 4591.0 4594.8 4597.2 4599.0 4119.7 4176.6 4180.3 4184.5 4184.7 3543.6 3645.5 3672.7 3699.6 3703.6 1079.9 1089.4 1102.2 1185.9 1223.4 584.4 672.1 694.2 697.7 700.6 4612.2 4686.2 4689.9 4788.1 4850.6 813.5 822.7 823.0 825.9 842.5 3272.4 3305.6 3325.3 3344.0 3404.4 9591.2 9738.4 9761.6 9766.9 9828.1 9696.2 9710.1 9712.4 9718.2 9721.4 7010.8 7303.3 7304.2 7476.2 7481.1 5115.2 5307.3 5332.9 5379.8 5475.0 8757.5 8995.5 9030.3 9063.8 9159.1 8928.3 9149.9 9323.9 9387.5 9393.2 6474.2 6511.9 6562.5 6564.3 6564.7 7310.8 7356.5 7358.4 7364.3 7376.5 6300.4 6434.4 6436.7 6448.3 6563.9 6130.5 6131.7 6259.1 6300.8 6452.3 5980.5 6049.1 6050.2 6058.8 6068.5 4380.9 4497.9 4531.7 4537.8 4566.9 4444.3 4526.3 4615.7 4616.1 4745.2 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | [#4] prototype.html Cycles:180 Average:4147.72 Median:4828.66 stddev:1226.93 (25.4%) stddev-sans-first:1195.40 23:08:26 INFO - PROCESS | 31745 | Values: 272.6 273.7 281.3 282.2 285.2 4798.8 4841.6 4883.8 4890.2 4892.0 4820.7 4832.9 4837.0 4843.9 4851.1 3524.9 3576.0 3743.5 3765.5 3771.2 3752.2 3754.4 3758.8 3773.4 3782.7 3706.7 3726.8 3735.2 3737.3 3741.5 3506.7 3589.0 3709.4 3717.1 3725.2 3252.4 3254.8 3258.6 3261.4 3271.5 4676.1 4685.7 4686.2 4689.3 4695.3 4114.4 4192.5 4225.0 4227.9 4240.8 4966.2 4980.2 5005.8 5008.8 5009.4 3572.7 3573.3 3573.7 3573.7 3580.2 4972.3 5185.5 5198.7 5236.4 5250.3 3471.0 3475.5 3479.7 3519.8 3527.3 3564.2 3568.2 3568.9 3582.5 3589.7 4144.2 4146.3 4166.8 4169.9 4173.7 4846.9 5030.7 5037.2 5044.1 5093.5 1224.1 1233.4 1234.5 1237.2 1238.2 751.3 773.9 790.1 790.7 796.4 4324.6 4392.8 4484.9 4501.2 4504.7 5194.3 5194.5 5198.4 5199.9 5204.6 4527.5 4528.5 4535.5 4537.5 4543.2 2606.6 2743.9 2752.2 2756.8 2757.4 4888.4 4893.4 4902.3 4909.2 4916.3 5140.9 5272.2 5272.6 5319.9 5320.8 4866.0 4897.6 4907.5 4911.0 4916.2 5113.2 5148.8 5187.4 5191.8 5200.8 4944.0 5013.9 5027.0 5077.5 5085.2 4798.1 4824.4 4861.6 4882.3 4891.9 3511.5 3532.3 3534.4 3536.0 3537.3 5003.9 5111.7 5130.3 5133.0 5152.6 4960.6 4978.8 4995.6 4999.6 5002.7 4973.7 4993.9 4998.8 5007.4 5022.0 4708.9 4929.5 4947.4 4951.1 4955.9 4793.3 4905.3 4999.9 5035.4 5053.4 4845.3 4871.6 4892.4 4904.5 4935.5 23:08:26 INFO - PROCESS | 31745 | 23:08:26 INFO - PROCESS | 31745 | [#5] yui.html Cycles:180 Average:16581.02 Median:31135.42 stddev:24461.66 (78.6%) stddev-sans-first:24500.62 23:08:26 INFO - PROCESS | 31745 | Values: 538.6 556.4 580.4 590.8 613.5 11822.7 12550.9 12948.9 12960.4 12987.5 13043.9 13061.5 13121.5 13129.6 13140.7 610.5 743.3 760.6 762.5 762.8 732.4 756.7 761.5 765.0 766.4 791.4 795.0 795.3 795.3 795.3 630.4 792.0 793.6 793.8 793.9 552.2 566.5 568.3 568.9 569.0 12150.6 12199.3 12224.8 12230.6 12270.3 11444.8 12077.5 12300.5 12368.8 12555.4 11145.1 11293.4 11363.0 11470.9 11557.2 3944.7 4042.0 4063.7 4102.5 4237.8 64404.0 64428.0 64889.0 65345.0 65542.0 8933.1 9122.9 9160.6 9240.2 9401.1 16801.7 17526.6 17537.7 17541.8 17597.1 6815.7 6974.6 7010.6 7043.9 7113.0 3507.2 3553.4 3631.0 3637.2 3668.1 2941.4 3077.7 3081.7 3082.6 3093.7 476.3 485.8 491.7 496.1 506.0 386.2 389.8 451.3 484.8 502.2 7132.2 7586.8 7763.0 7839.8 8040.8 388.0 412.6 418.9 521.3 522.4 3314.7 3771.8 3804.4 3878.5 3902.1 92337.0 95442.5 96960.0 97834.0 98058.0 80632.2 82116.2 82236.6 83524.0 84392.6 71165.0 71228.3 72126.0 72862.3 73237.3 7064.3 7590.8 7810.4 7816.2 7851.1 31151.2 34147.7 41526.8 42502.0 43286.4 36394.8 39953.0 39966.6 58761.0 61640.4 7917.8 9356.3 9605.8 9682.5 9925.2 10328.9 10435.0 10467.9 10594.6 11289.4 9198.6 9310.9 9566.2 9621.3 9898.1 9126.2 9175.0 9232.6 9275.8 9357.5 8701.4 8710.4 8728.4 8954.1 9212.9 8211.9 8233.5 8639.1 8661.0 8768.3 8395.7 8857.8 8918.2 9029.5 9198.1 23:08:26 INFO - PROCESS | 31745 | -------- Summary: end -------- 23:08:26 INFO - PROCESS | 31745 | 23:08:27 INFO - TEST-INFO | 31745: exit 0 23:08:27 INFO - TEST-OK | dromaeo_css | took 1120577ms 23:08:27 INFO - TEST-START | kraken 23:08:27 INFO - operating with platform_type : linux_ 23:08:27 INFO - Initialising browser for kraken test... 23:08:27 INFO - TEST-INFO | started process 32046 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpackF1W/profile http://localhost:53352/getInfo.html) 23:08:31 INFO - PROCESS | 32046 | __metrics Screen width/height:1600/1200 23:08:31 INFO - PROCESS | 32046 | colorDepth:24 23:08:31 INFO - PROCESS | 32046 | Browser inner width/height: 1024/697 23:08:31 INFO - PROCESS | 32046 | __metrics 23:08:31 INFO - PROCESS | 32046 | 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" 23:08:31 INFO - PROCESS | 32046 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:08:32 INFO - PROCESS | 32046 | console.error: PushService: 23:08:32 INFO - PROCESS | 32046 | stateChangeProcessEnqueue: Error transitioning state 23:08:32 INFO - PROCESS | 32046 | UnknownError 23:08:33 INFO - TEST-INFO | 32046: exit 0 23:08:33 INFO - Browser initialized. 23:08:33 INFO - Running cycle 1/1 for kraken test... 23:08:33 INFO - TEST-INFO | started process 32127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpackF1W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:08:45 INFO - PROCESS | 32127 | RSS: Main: 182202368 23:08:45 INFO - PROCESS | 32127 | 23:08:48 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-beat-detection) 23:08:49 INFO - PROCESS | 32127 | RSS: Main: 235188224 23:08:49 INFO - PROCESS | 32127 | 23:08:51 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-dft) 23:08:52 INFO - PROCESS | 32127 | RSS: Main: 226275328 23:08:52 INFO - PROCESS | 32127 | 23:08:55 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-fft) 23:08:56 INFO - PROCESS | 32127 | RSS: Main: 303742976 23:08:56 INFO - PROCESS | 32127 | 23:08:58 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-oscillator) 23:08:58 INFO - PROCESS | 32127 | RSS: Main: 213639168 23:08:58 INFO - PROCESS | 32127 | 23:09:01 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53352/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:09:01 INFO - PROCESS | 32127 | RSS: Main: 215355392 23:09:01 INFO - PROCESS | 32127 | 23:09:04 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53352/tests/kraken/driver.html?testName=imaging-darkroom) 23:09:05 INFO - PROCESS | 32127 | RSS: Main: 253632512 23:09:05 INFO - PROCESS | 32127 | 23:09:09 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53352/tests/kraken/driver.html?testName=imaging-desaturate) 23:09:09 INFO - PROCESS | 32127 | RSS: Main: 248209408 23:09:09 INFO - PROCESS | 32127 | 23:09:12 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53352/tests/kraken/driver.html?testName=json-parse-financial) 23:09:13 INFO - PROCESS | 32127 | RSS: Main: 250945536 23:09:13 INFO - PROCESS | 32127 | 23:09:14 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53352/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:09:15 INFO - PROCESS | 32127 | RSS: Main: 214573056 23:09:15 INFO - PROCESS | 32127 | 23:09:17 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:09:18 INFO - PROCESS | 32127 | RSS: Main: 222953472 23:09:18 INFO - PROCESS | 32127 | 23:09:20 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:09:21 INFO - PROCESS | 32127 | RSS: Main: 223277056 23:09:21 INFO - PROCESS | 32127 | 23:09:23 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:09:24 INFO - PROCESS | 32127 | RSS: Main: 224595968 23:09:24 INFO - PROCESS | 32127 | 23:09:27 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:09:27 INFO - PROCESS | 32127 | RSS: Main: 222855168 23:09:27 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53352/tests/kraken/driver.html?testName=ai-astar) 23:09:29 INFO - PROCESS | 32127 | RSS: Main: 273031168 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | __start_tp_report 23:09:29 INFO - PROCESS | 32127 | _x_x_mozilla_page_load 23:09:29 INFO - PROCESS | 32127 | _x_x_mozilla_page_load_details 23:09:29 INFO - PROCESS | 32127 | |i|pagename|runs| 23:09:29 INFO - PROCESS | 32127 | |0;ai-astar;114;115;134;116;116;116;114;115;114;115 23:09:29 INFO - PROCESS | 32127 | |1;audio-beat-detection;158;123;147;173;126;153;156;128;178;123 23:09:29 INFO - PROCESS | 32127 | |2;audio-dft;162;162;161;163;162;160;161;160;161;161 23:09:29 INFO - PROCESS | 32127 | |3;audio-fft;75;76;76;167;78;75;75;76;173;77 23:09:29 INFO - PROCESS | 32127 | |4;audio-oscillator;84;83;142;83;84;84;138;84;88;140 23:09:29 INFO - PROCESS | 32127 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;116;117;117 23:09:29 INFO - PROCESS | 32127 | |6;imaging-darkroom;148;148;147;148;147;147;147;147;147;147 23:09:29 INFO - PROCESS | 32127 | |7;imaging-desaturate;95;95;95;95;95;95;95;97;95;95 23:09:29 INFO - PROCESS | 32127 | |8;json-parse-financial;64;65;65;64;67;66;66;66;66;66 23:09:29 INFO - PROCESS | 32127 | |9;json-stringify-tinderbox;67;67;66;66;66;66;67;66;65;67 23:09:29 INFO - PROCESS | 32127 | |10;stanford-crypto-aes;83;83;91;82;82;81;81;84;80;81 23:09:29 INFO - PROCESS | 32127 | |11;stanford-crypto-ccm;136;132;129;133;133;130;131;131;183;139 23:09:29 INFO - PROCESS | 32127 | |12;stanford-crypto-pbkdf2;172;174;172;173;170;171;171;172;171;173 23:09:29 INFO - PROCESS | 32127 | |13;stanford-crypto-sha256-iterative;70;68;68;69;67;69;71;67;67;72 23:09:29 INFO - PROCESS | 32127 | __end_tp_report 23:09:29 INFO - PROCESS | 32127 | __start_cc_report 23:09:29 INFO - PROCESS | 32127 | _x_x_mozilla_cycle_collect,2005 23:09:29 INFO - PROCESS | 32127 | __end_cc_report 23:09:29 INFO - PROCESS | 32127 | __startTimestamp1472537369970__endTimestamp 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | ------- Summary: start ------- 23:09:29 INFO - PROCESS | 32127 | Number of tests: 14 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#0] ai-astar Cycles:10 Average:116.90 Median:115.50 stddev:6.06 (5.2%) stddev-sans-first:6.34 23:09:29 INFO - PROCESS | 32127 | Values: 114.0 115.0 134.0 116.0 116.0 116.0 114.0 115.0 114.0 115.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#1] audio-beat-detection Cycles:10 Average:146.50 Median:154.50 stddev:20.62 (13.3%) stddev-sans-first:21.45 23:09:29 INFO - PROCESS | 32127 | Values: 158.0 123.0 147.0 173.0 126.0 153.0 156.0 128.0 178.0 123.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 23:09:29 INFO - PROCESS | 32127 | Values: 162.0 162.0 161.0 163.0 162.0 160.0 161.0 160.0 161.0 161.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#3] audio-fft Cycles:10 Average:94.80 Median:76.00 stddev:39.67 (52.2%) stddev-sans-first:41.42 23:09:29 INFO - PROCESS | 32127 | Values: 75.0 76.0 76.0 167.0 78.0 75.0 75.0 76.0 173.0 77.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#4] audio-oscillator Cycles:10 Average:101.00 Median:84.00 stddev:26.96 (32.1%) stddev-sans-first:27.89 23:09:29 INFO - PROCESS | 32127 | Values: 84.0 83.0 142.0 83.0 84.0 84.0 138.0 84.0 88.0 140.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:09:29 INFO - PROCESS | 32127 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 23:09:29 INFO - PROCESS | 32127 | Values: 148.0 148.0 147.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 23:09:29 INFO - PROCESS | 32127 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:0.87 23:09:29 INFO - PROCESS | 32127 | Values: 64.0 65.0 65.0 64.0 67.0 66.0 66.0 66.0 66.0 66.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 23:09:29 INFO - PROCESS | 32127 | Values: 67.0 67.0 66.0 66.0 66.0 66.0 67.0 66.0 65.0 67.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:82.50 stddev:3.12 (3.8%) stddev-sans-first:3.31 23:09:29 INFO - PROCESS | 32127 | Values: 83.0 83.0 91.0 82.0 82.0 81.0 81.0 84.0 80.0 81.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:133.00 stddev:16.19 (12.2%) stddev-sans-first:17.16 23:09:29 INFO - PROCESS | 32127 | Values: 136.0 132.0 129.0 133.0 133.0 130.0 131.0 131.0 183.0 139.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 23:09:29 INFO - PROCESS | 32127 | Values: 172.0 174.0 172.0 173.0 170.0 171.0 171.0 172.0 171.0 173.0 23:09:29 INFO - PROCESS | 32127 | 23:09:29 INFO - PROCESS | 32127 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.75 (2.5%) stddev-sans-first:1.80 23:09:29 INFO - PROCESS | 32127 | Values: 70.0 68.0 68.0 69.0 67.0 69.0 71.0 67.0 67.0 72.0 23:09:29 INFO - PROCESS | 32127 | -------- Summary: end -------- 23:09:29 INFO - PROCESS | 32127 | 23:09:31 INFO - TEST-INFO | 32127: exit 0 23:09:31 INFO - TEST-OK | kraken | took 64062ms 23:09:31 INFO - SUITE-END | took 1184s 23:09:31 INFO - Completed test suite (00:19:45) 23:09:31 INFO - javascript benchmark 23:09:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2616.672915885514, 2619.9345016374587, 2635.5651317532606, 2675.8795854186574, 2685.624012638231, 4658.79035443405, 4696.037312350968, 4709.128811169936, 4794.400699912507, 4848.006158737455, 4688.648351373652, 4745.549172828584, 4745.8813529661775, 4749.097671442423, 4799.232122860334, 4309.332053531708, 4322.394864718942, 4389.056352884129, 4449.532799056101, 4514.232580461329, 4385.407969924067, 4443.289073748201, 4458.710183838057, 4484.349769283452, 4496.820127194925, 4323.437953066082, 4351.19502891966, 4355.5644435556505, 4362.0, 4411.889702757457, 4337.0, 4344.196323680129, 4402.031553058322, 4419.602235798761, 4476.149531588988, 4263.104748002923, 4344.066025804467, 4345.543717909605, 4356.237658409797, 4401.977990110061, 4687.929681054787, 4731.976340118277, 4739.0758802033615, 4743.051389722069, 4796.160671882441, 4957.677750946177, 5022.748862556858, 5074.898502029976, 5076.289319495274, 5104.259882317043, 5076.36545431821, 5105.617078719111, 5107.1828507439, 5114.33513643224, 5115.820946266863, 4613.123506533748, 4800.135975524439, 4808.8797780055775, 4847.684900481493, 4860.392450943632, 5173.81891633791, 5188.610854185876, 5201.3238279024245, 5201.661891977009, 5274.393444753826, 4994.4755800640405, 5007.3240112584335, 5007.549320561167, 5011.072951503984, 5071.949280507148, 4937.0, 5084.110280700863, 5086.415062267813, 5090.10923088458, 5094.2103973884105, 4963.329950456714, 5023.598112151092, 5027.899442011139, 5051.62112841531, 5111.207762796773, 5018.021485810232, 5028.79884804611, 5031.723255220998, 5032.572231360302, 5088.363954505687, 4151.3772934059525, 4218.641415479658, 4221.2401767681495, 4230.915381692349, 4276.935845962313, 3673.1551743099235, 3702.7037836972977, 3726.4410338448906, 3770.792606406674, 3824.158685089276, 4435.733855968652, 4461.57615026572, 4466.7766611669285, 4469.08383781324, 4527.1172121436, 5130.769115389817, 5143.12566863634, 5148.922766158511, 5148.995945790535, 5211.348581427322, 4356.520782713899, 4411.0, 4431.446069241345, 4436.0462500562535, 4505.61702255312, 4540.77296135192, 4544.477385100756, 4553.248713962159, 4554.0, 4612.377329060601, 4805.471398146201, 4818.6626936114835, 4827.879303017453, 4835.540623640749, 4880.853574392774, 5030.899382012266, 5067.543921047123, 5101.719405432588, 5110.744462776921, 5176.50823171798, 4778.617710583215, 4807.4471435783935, 4814.325994360863, 4818.927716084331, 4865.1485989952325, 5016.498350164954, 5034.219695947134, 5037.899242015066, 5046.6719663221775, 5108.463611320906, 4963.528464795839, 5088.821891233789, 5089.287499750112, 5129.717865517432, 5151.072806894795, 4998.925016124834, 5006.874828129326, 5013.172826483736, 5016.849494515171, 5053.924191137063, 4963.5780958618925, 4965.205567109389, 4972.105021096238, 4974.204127339608, 5066.240063990431, 4807.399075115611, 4862.902741945212, 4891.755412229445, 4899.265110233494, 4946.35697359341, 4952.8761780955765, 5007.974960125176, 5026.145555255543, 5026.5727413168825, 5033.169527028, 4820.25286080658, 4824.179889418737, 4824.975875120602, 4834.081524510332, 4887.511248875084], "value": 4674.44228912157, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.0368133376594, 577.4283459375217, 582.877595704902, 586.4487381861051, 588.0003993211538, 27795.305117372074, 38599.80700096499, 40246.59753402465, 40316.19367612646, 43855.561444385545, 41014.38478422826, 41355.793221033855, 41387.58612413875, 41453.58546414535, 41461.58538414615, 7172.139343278814, 7674.539527628346, 7811.687532498693, 7825.217478252186, 7825.765227043198, 9610.711678649652, 9901.059399357086, 9907.950460247688, 10243.590256389736, 10251.89748102517, 9801.0, 9831.31180817343, 9916.355436896614, 9917.801643967116, 9923.553440095176, 9927.553260103312, 9993.750156246078, 9997.600095996151, 10007.099361057468, 10008.849867251996, 9289.674861379819, 9333.813323733555, 9342.57958391874, 9348.766280842965, 9350.859737103949, 37537.81231093854, 37833.810830945666, 38056.80971595152, 38061.0, 38110.04724881872, 2623.226148286241, 2715.253305341027, 2753.4630747004344, 3004.489236829744, 3220.6940340667597, 25967.091151809786, 25974.87012564944, 26434.6034809478, 26822.73177268222, 29493.0, 3023.667396586374, 3090.2428904918215, 3123.3909387669414, 3126.5935428394164, 3147.464930961719, 249129.0, 249280.0, 249425.0, 249902.0, 250023.74988125125, 28022.159335219974, 28688.713112868605, 51275.7436212824, 51692.48307516952, 51716.48283517117, 33060.17349566232, 33308.167295817315, 33308.833455832566, 33310.83344583261, 33320.833395832866, 12788.232706037668, 13044.0, 13044.152130111703, 13044.347782610832, 13045.478180872658, 8766.430182038148, 10026.548805303779, 10143.137833284105, 10461.895381046092, 10705.1435885129, 7772.45592808498, 7774.611269436618, 7820.921790782133, 7843.215678432111, 7869.567173805382, 3045.9238519037203, 3078.1227350205086, 3100.3489267253676, 3116.6727493612843, 3120.3135310231714, 764.8699721047435, 811.6428771340709, 867.0549101479418, 954.7899462118462, 983.5868935047297, 26230.344241393737, 27408.45183096327, 27920.0, 28670.283242919093, 28765.28086797847, 886.1094599927161, 895.6506962284714, 923.2244914272044, 952.4095061062186, 964.6961207219844, 13963.441462341596, 14757.262136893112, 14971.475998340005, 15342.309596068204, 15397.23013849303, 353958.0, 354660.0, 354943.0, 355505.0, 357252.0, 48317.75841120772, 48759.75620121877, 48800.26799598009, 48860.75569622129, 48876.0, 1043.6138628707427, 1107.6234080412698, 1120.277421063426, 1121.9775604487866, 1129.0, 26690.866545667148, 28094.578581321704, 29244.7075529242, 29683.851580742823, 31061.564226259863, 46700.532994670975, 52301.47698522966, 52403.73798130985, 52415.47584524258, 52471.475285246655, 350777.0, 354147.0, 354159.0, 355775.0, 366138.0, 35652.0, 39600.80199599099, 39632.60367396289, 39918.40122398225, 40219.1956160881, 43662.78168609264, 43864.780676096416, 44024.779876100416, 44156.23375064961, 44202.0, 37233.0, 37886.62113378831, 38012.23975520529, 38356.61643383642, 39478.21043579055, 37822.43266350952, 37885.81057094697, 38078.80960595179, 39708.60291397049, 39758.403623946244, 36551.45172822357, 37123.81438092792, 37548.0, 37925.24149516939, 38072.238555228185, 32073.556689948542, 34013.48979765256, 34679.826600866836, 37074.0, 37974.0, 26104.216873493824, 26117.477650446504, 26208.606870897333, 26239.60640590431, 26426.60360094562], "value": 16988.256972992494, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.77245018287937, 362.3821384539361, 367.5020347429233, 368.3535395381107, 380.63839352615, 12612.684682882933, 16034.59913502163, 16547.42084027059, 17849.643007139883, 18022.18900149493, 17002.3199072037, 17028.999999999967, 17168.22742976566, 17255.0, 17271.0, 5029.1199040168, 5063.34176557048, 5253.133233016548, 5287.762050707708, 5399.0, 3354.7316214702764, 5303.549198318147, 5341.572674186055, 5347.304850369446, 5720.170575266584, 6004.639721616695, 6080.483158931496, 6084.361142080084, 6103.908441373383, 6109.053096770008, 5897.4692277694785, 5978.282606087254, 6047.334793172748, 6062.272527296708, 6101.237345331833, 5359.598030147755, 5364.0612892744, 5366.302380690524, 5387.78448862045, 5395.676259424447, 12600.68498287541, 14360.640983975483, 20181.798182018138, 20318.0, 20318.28885988983, 1701.3449821818565, 1728.3605066125615, 1728.5246557196747, 1741.2077504735314, 1743.4159556548445, 8616.439931404439, 9038.502882341469, 9177.128172823572, 10291.125254353388, 12427.627171184879, 1612.991935040329, 1671.0432084029712, 1673.1383337581033, 1703.3101593854508, 1723.3623559283021, 67309.32690673128, 71952.64023679848, 71969.6401517989, 71984.6400767998, 73825.6308718453, 9823.410595364301, 9982.158653053264, 9993.950030249947, 10256.0, 10269.897301027044, 13988.440462381594, 14011.649708757363, 14019.228942408265, 14020.158790472604, 14020.228887411291, 6969.47728920333, 7039.366457018893, 7097.92902070983, 7167.498275120692, 7392.075990501187, 6551.148350714472, 6673.299303573152, 6820.454363650995, 6998.75504357345, 7015.9298407016295, 4138.882501724517, 4303.978480107579, 4333.501647310537, 4409.889752756143, 4430.113977204572, 1777.9288828446977, 1805.7562229099167, 1812.1482903035817, 1828.87197896146, 1858.7676540432446, 639.8080575827233, 759.9772006839804, 788.9703936363102, 847.9788005299794, 868.6829307302789, 15933.247342792283, 17149.314027439013, 17376.0, 17469.38857139994, 17565.912170439067, 807.3823525003377, 809.514291425152, 819.0, 831.339085427087, 877.644553955649, 8142.0, 9455.716328510156, 9462.479563624067, 9690.224782017422, 9697.127258546765, 282352.0, 284094.0, 284155.0, 378122.0, 388683.0, 54452.727736361856, 71456.285437146, 79160.60419697865, 80345.59827200827, 81661.0, 893.5889490834159, 907.832051070554, 950.9714708558754, 955.8375076237056, 956.3974695941652, 15478.0, 15544.766828497808, 15851.445199417965, 16434.506964791075, 16438.917805411376, 36831.21194244731, 36924.815375922946, 37365.626343736214, 37428.251434971695, 37637.435438467895, 47957.76021119872, 48655.0, 50367.49632503628, 52925.0, 52978.0, 7103.680334384861, 7324.670389832577, 7398.667059982206, 7468.066491688539, 7554.962225188839, 11139.220254582102, 12002.066238849222, 12418.316992565495, 12450.75098498043, 12457.937710311391, 6964.686589103604, 7176.921194688619, 7180.820479487846, 8425.95787021061, 10370.792584148425, 9861.309708320348, 9979.0, 10575.312604680672, 10586.0, 10718.624848130277, 10379.636712715019, 10632.734181645521, 10644.680659580225, 10852.294600850919, 10939.343639381663, 7348.301911318416, 7413.925860741324, 7562.470627056054, 7563.621818909142, 7728.574928378768, 9827.852582211415, 10330.638427654996, 10485.26603137773, 10645.733856653645, 11043.779124417306], "value": 8064.05252223585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.6173540540942, 1394.7907813827926, 1444.4872070415001, 1641.1302009934739, 1678.0686718871023, 6882.931170688292, 7465.738699145531, 7513.173550909396, 8054.033515978083, 8058.355331573474, 7879.448438609285, 7968.0, 7972.760817175494, 7982.082060563022, 7988.600569971507, 2193.7806219378094, 2216.047099747108, 2221.711177546925, 2223.7442694090223, 2223.7442694090223, 1848.0944337274707, 2052.887091209983, 2141.7429908411013, 2144.324537770597, 2168.7831216878344, 2188.9014994325294, 2212.590670725921, 2216.0692509146197, 2216.6785816056663, 2219.678146668732, 2094.7172131762322, 2102.95903527754, 2147.3879944215823, 2153.558520503293, 2153.8169255613293, 2050.620635182496, 2054.9486262843398, 2055.2601063617085, 2058.125296748876, 2134.647783115784, 7961.1242763295995, 8024.63889124985, 8040.638171282307, 8061.193880611951, 8061.717839875635, 4436.55634436557, 4656.184088205602, 4661.953380466186, 4669.439667239919, 4670.836520721792, 2820.3372207531215, 2946.2928897064767, 2988.417258634567, 2997.68524304947, 2999.2202027472795, 892.638481415028, 925.5048549026305, 940.4545363689044, 942.3639043645512, 943.7499062748377, 10436.843447348296, 10438.530266138043, 10448.999999999924, 10458.424786636731, 10465.058144766932, 3683.2817600567632, 4058.0724156025653, 4209.810558524874, 4214.726042807208, 4217.451731274915, 9052.0, 9072.364934454657, 9073.863892041625, 9079.092090790868, 9080.500572468445, 4564.360989461478, 4590.977045114753, 4594.770261486912, 4597.172508948354, 4598.977005114953, 4119.670426365884, 4176.561461046546, 4180.310248808913, 4184.476940382452, 4184.707070505096, 3543.557055368079, 3645.5078564393466, 3672.7245456590863, 3699.5930447650735, 3703.611170794822, 1079.865016872891, 1089.3627228071666, 1102.1843835561626, 1185.8576970763563, 1223.4095675621656, 584.4097461563852, 672.1128110893671, 694.1912671737471, 697.7383481194552, 700.58315302395, 4612.192866248393, 4686.203345431285, 4689.882752931204, 4788.1381351356395, 4850.611951043908, 813.4712436915934, 822.7326119011345, 822.9711960081369, 825.9463134896333, 842.5197637346654, 3272.4109660261383, 3305.5702758641223, 3325.3016866458315, 3344.0, 3404.4382676858536, 9591.184749296248, 9738.367006144577, 9761.609535618638, 9766.902330976742, 9828.115469607626, 9696.17582505495, 9710.126088652056, 9712.417254964725, 9718.202132605491, 9721.368111072758, 7010.754623588252, 7303.269673032654, 7304.196538380774, 7476.177620461636, 7481.139668938142, 5115.1559992600805, 5307.336582927134, 5332.946670533245, 5379.757910893941, 5475.0, 8757.47455152693, 8995.505247211466, 9030.322725795722, 9063.818723625622, 9159.084091590788, 8928.330375221754, 9149.908500914906, 9323.86014209801, 9387.530623468936, 9393.154616084312, 6474.1907261592305, 6511.9348806511325, 6562.5406221564035, 6564.343565643398, 6564.737410503717, 7310.81722956909, 7356.485046046725, 7358.374538164316, 7364.263573642593, 7376.4836461447185, 6300.369963003663, 6434.388733070351, 6436.742530298736, 6448.290688024407, 6563.901541476786, 6130.509559235161, 6131.724072416841, 6259.123722678738, 6300.810975670737, 6452.322506136976, 5980.491658209101, 6049.0926361046195, 6050.21347224858, 6058.818235452944, 6068.544859135494, 4380.8685739427865, 4497.865064048084, 4531.728096314232, 4537.8411755588395, 4566.862994110182, 4444.28891377378, 4526.32105184225, 4615.74613396253, 4616.076784643018, 4745.193317136026], "value": 4061.4303900416844, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.6088063628692, 273.7481517004356, 281.3164011452172, 282.1971491107798, 285.1729983049157, 4798.8242880494245, 4841.56425921667, 4883.780229889655, 4890.239513775047, 4891.951080489194, 4820.710757354561, 4832.903341933159, 4837.0, 4843.927341089878, 4851.1025460289875, 3524.9118772030647, 3576.0, 3743.5133432653715, 3765.548134223897, 3771.1703425246537, 3752.2307926874923, 3754.4180651998986, 3758.81597296852, 3773.415120656303, 3782.6973842092566, 3706.7219958503083, 3726.813659317037, 3735.1595890924596, 3737.2712321097397, 3741.5136032315895, 3506.736994725393, 3588.977141514669, 3709.406494960793, 3717.126475278307, 3725.217704282104, 3252.447083995726, 3254.7721659483846, 3258.608966923984, 3261.4129456697783, 3271.525628783837, 4676.134915040729, 4685.672002959795, 4686.179918514246, 4689.303650401033, 4695.319178719066, 4114.362273847559, 4192.496900371944, 4224.957750422487, 4227.87316380506, 4240.787960601958, 4966.180580204262, 4980.203167493184, 5005.849824505271, 5008.774605142741, 5009.4239162496415, 3572.7141828653644, 3573.2674801665858, 3573.6605022523077, 3573.749187784281, 3580.1765593913287, 4972.279019542182, 5185.5333020028, 5198.662086964335, 5236.371635403776, 5250.264962905197, 3471.0, 3475.5134281200653, 3479.6520347965507, 3519.8240087995496, 3527.3474407234744, 3564.180238545149, 3568.1793187567005, 3568.91077723059, 3582.5163602913794, 3589.7487175897436, 4144.233316836395, 4146.253674338588, 4166.812493437803, 4169.874903752892, 4173.686973477002, 4846.927296090562, 5030.673006254582, 5037.194048952223, 5044.117279476077, 5093.516115968977, 1224.12475080318, 1233.3894722112611, 1234.5493894728359, 1237.1649136832602, 1238.2013601227159, 751.2787723785227, 773.9071311442663, 790.083503136359, 790.6955822008568, 796.4225936196327, 4324.632406245441, 4392.802323895432, 4484.910301793946, 4501.18978583858, 4504.707194032378, 5194.3247377840635, 5194.454582268883, 5198.376194856566, 5199.896002080013, 5204.635675502755, 4527.501974782771, 4528.547145285445, 4535.455745310584, 4537.523560026149, 4543.182227199136, 2606.5829467285143, 2743.8750112454195, 2752.21106280919, 2756.834589924611, 2757.420941602246, 4888.388951381077, 4893.388326459192, 4902.338184345068, 4909.214525675875, 4916.287138364917, 5140.922886156711, 5272.205730962519, 5272.578193744568, 5319.893602127936, 5320.813771517978, 4865.9756701216265, 4897.608191344612, 4907.5337842904155, 4910.9932463844825, 4916.164252077156, 5113.181890897438, 5148.845534633967, 5187.351581052369, 5191.844244672666, 5200.817971370984, 4943.975280123576, 5013.941495687554, 5027.0, 5077.517635824591, 5085.152314703778, 4798.088363210979, 4824.421069471686, 4861.593511211831, 4882.267659850909, 4891.926621100615, 3511.543499345069, 3532.3111993161087, 3534.416821224469, 3535.9646403535635, 3537.345591065661, 5003.949960500494, 5111.667741596784, 5130.333056702605, 5132.999065182403, 5152.613553983541, 4960.578350840219, 4978.775955081957, 4995.550400463976, 4999.550040496373, 5002.749862506933, 4973.676711013773, 4993.850184494326, 4998.825041123543, 5007.449180590205, 5021.974890125526, 4708.92936605958, 4929.531694489017, 4947.381577302837, 4951.10880041596, 4955.876103097451, 4793.257045157867, 4905.264210368473, 4999.875003124951, 5035.395752509576, 5053.393592768891, 4845.345878306524, 4871.585915197248, 4892.407024277139, 4904.485029071896, 4935.457099718957], "value": 3746.4002557571357, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.6498775795732, 556.4046470276805, 580.4166812353585, 590.7666471743661, 613.454025916934, 11822.704432389195, 12550.925023369287, 12948.870511294885, 12960.41678124483, 12987.480500779957, 13043.934780326108, 13061.542846000391, 13121.54074607389, 13129.606111816636, 13140.671483212924, 610.5024405109822, 743.2790193512297, 760.6082867323305, 762.4929421934407, 762.8474305138994, 732.4470025131019, 756.6632848382473, 761.5430741555078, 764.9847003059936, 766.4251811141644, 791.4301702773994, 795.0499153511556, 795.2524626850742, 795.2683531151355, 795.3001358804273, 630.4483576870239, 792.029763539665, 793.6031984007996, 793.7658390774735, 793.9087004994442, 552.2296396526823, 566.5354409384265, 568.2555851834111, 568.9203511508392, 569.0468465320572, 12150.5747298845, 12199.329036902967, 12224.755504889941, 12230.633081007583, 12270.32513211773, 11444.828327575093, 12077.516899324017, 12300.507979680802, 12368.752624947541, 12555.435005424686, 11145.066328997793, 11293.435328233638, 11363.0, 11470.885291147066, 11557.19099663024, 3944.7238693291483, 4041.959580404188, 4063.6952228582677, 4102.528209255944, 4237.809298581571, 64404.0, 64427.999999999534, 64889.0, 65345.0, 65542.0, 8933.126095966903, 9122.908770912338, 9160.633574656938, 9240.21458176049, 9401.10689484498, 16801.66396672084, 17526.561835954202, 17537.736933946002, 17541.82458175402, 17597.12014399275, 6815.727370905208, 6974.581525108409, 7010.579365238102, 7043.894341584778, 7113.0, 3507.2108775525303, 3553.4492153716214, 3631.0, 3637.217998130415, 3668.137987572918, 2941.3529023615197, 3077.6614572397466, 3081.7226449619643, 3082.597418174725, 3093.6751641077362, 476.3307552888198, 485.8178183181307, 491.6754941738507, 496.1119595923329, 505.9848204553869, 386.18514933490314, 389.8284754707976, 451.26669162610756, 484.7551986246923, 502.22406382139684, 7132.17979932304, 7586.81032974169, 7762.961185194151, 7839.764807055797, 8040.758777236575, 388.0183136664244, 412.6080223787414, 418.9036521600049, 521.3040590811233, 522.4148953172471, 3314.6519615440516, 3771.773693578339, 3804.3722785740597, 3878.45701601776, 3902.087976891303, 92337.0, 95442.522787387, 96960.0, 97834.0, 98058.0, 80632.19367806365, 82116.17883821204, 82236.58881705553, 83524.0, 84392.57803711065, 71165.0, 71228.2877171232, 72126.0, 72862.27137728661, 73237.26762732411, 7064.326550193768, 7590.81022974408, 7810.375169986274, 7816.218378162365, 7851.136374998631, 31151.221219469695, 34147.65852341544, 41526.792366037975, 42502.0, 43286.350704740085, 36394.8180259097, 39953.00587426572, 39966.600333996284, 58761.0, 61640.38359616346, 7917.830273998534, 9356.298277629068, 9605.759856003377, 9682.467464289532, 9925.156361709336, 10328.896711032794, 10435.0, 10467.94766026165, 10594.629187978384, 11289.37908415045, 9198.586063627286, 9310.906890931004, 9566.186874115778, 9621.27840411958, 9898.10917017472, 9126.224270936778, 9175.0, 9232.584533696134, 9275.814483710154, 9357.53212339394, 8701.43440676354, 8710.433821801562, 8728.432651877607, 8954.149355811187, 9212.907870921204, 8211.876821847796, 8233.547154906299, 8639.09289524616, 8661.0, 8768.298536116969, 8395.706150284956, 8857.839058007496, 8918.152775486318, 9029.503377314311, 9198.126178013077], "value": 6038.621039404545, "unit": "score"}], "name": "dromaeo_css", "value": 6236.659515593666, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 134.0, 116.0, 116.0, 116.0, 114.0, 115.0, 114.0, 115.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 147.0, 173.0, 126.0, 153.0, 156.0, 128.0, 178.0, 123.0], "value": 146.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 163.0, 162.0, 160.0, 161.0, 160.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 167.0, 78.0, 75.0, 75.0, 76.0, 173.0, 77.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 142.0, 83.0, 84.0, 84.0, 138.0, 84.0, 88.0, 140.0], "value": 101.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 64.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 67.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 91.0, 82.0, 82.0, 81.0, 81.0, 84.0, 80.0, 81.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 132.0, 129.0, 133.0, 133.0, 130.0, 131.0, 131.0, 183.0, 139.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 172.0, 173.0, 170.0, 171.0, 171.0, 172.0, 171.0, 173.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 69.0, 67.0, 69.0, 71.0, 67.0, 67.0, 72.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1572.8, "alertThreshold": 2.0}]} 23:09:31 INFO - Return code: 0 23:09:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:09:31 INFO - # TBPL SUCCESS # 23:09:31 INFO - Running post-action listener: _resource_record_post_action 23:09:31 INFO - [mozharness: 2016-08-30 06:09:31.761559Z] Finished run-tests step (success) 23:09:31 INFO - Running post-run listener: _resource_record_post_run 23:09:31 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 16384; Write bytes: 405348352; Read time: 188; Write time: 271576 23:09:31 INFO - TinderboxPrint: CPU usage
13.0% 23:09:31 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 188 23:09:31 INFO - TinderboxPrint: I/O write bytes / time
405,348,352 / 271,576 23:09:31 INFO - TinderboxPrint: CPU idle
8,332.9 (86.9%) 23:09:31 INFO - TinderboxPrint: CPU user
1,202.1 (12.5%) 23:09:31 INFO - TinderboxPrint: Swap in / out
0 / 0 23:09:31 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 113618944; Read time: 92; Write time: 70860 23:09:31 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 291729408; Read time: 80; Write time: 200716 23:09:32 INFO - Running post-run listener: _upload_blobber_files 23:09:32 INFO - Blob upload gear active. 23:09:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:09:32 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 23:09:32 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:09:32 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:09:32 INFO - (blobuploader) - INFO - Open directory for files ... 23:09:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:09:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:09:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:33 INFO - (blobuploader) - INFO - Done attempting. 23:09:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:09:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:09:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:34 INFO - (blobuploader) - INFO - Done attempting. 23:09:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:09:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:09:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:39 INFO - (blobuploader) - INFO - Done attempting. 23:09:39 INFO - (blobuploader) - INFO - Iteration through files over. 23:09:39 INFO - Return code: 0 23:09:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:09:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:09:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/197c301c6902e82118be2fd1532f0c7239b798e57d23c501a8c15fa4d09bf60539d5b17e5cee1c214872cce4b76f86e417a44a0bd546c93e4f09e54a46cb53db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13168b356c9860b4a836f6dd136613db825a5f0414004ba5d19981d63c20476e56cbc0b39f9a42925b8b4ca695aebb0b93442d8fd61925ec58be8bce4e77beea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea1d2f2f8b042d0f658ff6bbfac64cb2691641f8f1e01eb48a3e035951208ef11b64721f00dc8ee511be5f828e17c9b4f880e5751ccb5dce9c734538456456bd"} 23:09:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:09:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:09:39 INFO - Contents: 23:09:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/197c301c6902e82118be2fd1532f0c7239b798e57d23c501a8c15fa4d09bf60539d5b17e5cee1c214872cce4b76f86e417a44a0bd546c93e4f09e54a46cb53db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13168b356c9860b4a836f6dd136613db825a5f0414004ba5d19981d63c20476e56cbc0b39f9a42925b8b4ca695aebb0b93442d8fd61925ec58be8bce4e77beea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea1d2f2f8b042d0f658ff6bbfac64cb2691641f8f1e01eb48a3e035951208ef11b64721f00dc8ee511be5f828e17c9b4f880e5751ccb5dce9c734538456456bd"} 23:09:39 INFO - Running post-run listener: copy_logs_to_upload_dir 23:09:39 INFO - Copying logs to upload dir... 23:09:39 INFO - mkdir: /builds/slave/test/build/upload/logs 23:09:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.353412 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-29 23:09:38.986388) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 23:09:38.990060) ========= 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=0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584 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-Non-PGO/sha512/197c301c6902e82118be2fd1532f0c7239b798e57d23c501a8c15fa4d09bf60539d5b17e5cee1c214872cce4b76f86e417a44a0bd546c93e4f09e54a46cb53db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13168b356c9860b4a836f6dd136613db825a5f0414004ba5d19981d63c20476e56cbc0b39f9a42925b8b4ca695aebb0b93442d8fd61925ec58be8bce4e77beea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea1d2f2f8b042d0f658ff6bbfac64cb2691641f8f1e01eb48a3e035951208ef11b64721f00dc8ee511be5f828e17c9b4f880e5751ccb5dce9c734538456456bd"} build_url:https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/P7H3xOCLT1yNthZmTTdfCw/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-Non-PGO/sha512/197c301c6902e82118be2fd1532f0c7239b798e57d23c501a8c15fa4d09bf60539d5b17e5cee1c214872cce4b76f86e417a44a0bd546c93e4f09e54a46cb53db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13168b356c9860b4a836f6dd136613db825a5f0414004ba5d19981d63c20476e56cbc0b39f9a42925b8b4ca695aebb0b93442d8fd61925ec58be8bce4e77beea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ea1d2f2f8b042d0f658ff6bbfac64cb2691641f8f1e01eb48a3e035951208ef11b64721f00dc8ee511be5f828e17c9b4f880e5751ccb5dce9c734538456456bd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 23:09:39.015738) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:09:39.016075) ========= 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=0333a256a5e4f052382dd180000001b1-1472535608.348549-572932584 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:09:39.101471) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 23:09:39.101791) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 23:09:39.102077) ========= ========= Total master_lag: 0.19 =========