builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-036 starttime: 1472750500.96 results: success (0) buildid: 20160901084920 builduid: c979e1a36aad46fcb8f098c78cf1ad62 revision: fc4a79cf9ac9681bfbeafb60f0056153b780e99e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:40.959326) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:40.959784) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:40.960110) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:40.974812) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:40.975183) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 10:21:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-09-01 10:21:41 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093373 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.082407) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.082720) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014977 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.131938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.132299) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc4a79cf9ac9681bfbeafb60f0056153b780e99e --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 fc4a79cf9ac9681bfbeafb60f0056153b780e99e --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=14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 10:21:41,234 truncating revision to first 12 chars 2016-09-01 10:21:41,234 Setting DEBUG logging. 2016-09-01 10:21:41,234 attempt 1/10 2016-09-01 10:21:41,234 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc4a79cf9ac9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 10:21:41,535 unpacking tar archive at: mozilla-central-fc4a79cf9ac9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636554 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.782439) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.782794) ========= script_repo_revision: fc4a79cf9ac9681bfbeafb60f0056153b780e99e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.783193) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.783480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 10:21:41.792496) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-01 10:21:41.792818) ========= /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=14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:21:41 INFO - MultiFileLogger online at 20160901 10:21:41 in /builds/slave/test 10:21:41 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 10:21:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:21:41 INFO - {'append_to_log': False, 10:21:41 INFO - 'base_work_dir': '/builds/slave/test', 10:21:41 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 10:21:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:21:41 INFO - 'branch': 'Firefox-Non-PGO', 10:21:41 INFO - 'buildbot_json_path': 'buildprops.json', 10:21:41 INFO - 'config_files': ('talos/linux_config.py',), 10:21:41 INFO - 'default_actions': ('clobber', 10:21:41 INFO - 'read-buildbot-config', 10:21:41 INFO - 'download-and-extract', 10:21:41 INFO - 'populate-webroot', 10:21:41 INFO - 'create-virtualenv', 10:21:41 INFO - 'install', 10:21:41 INFO - 'run-tests'), 10:21:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:21:41 INFO - 'download_minidump_stackwalk': True, 10:21:41 INFO - 'download_symbols': 'ondemand', 10:21:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:21:41 INFO - 'tooltool.py': '/tools/tooltool.py', 10:21:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:21:41 INFO - '/tools/misc-python/virtualenv.py')}, 10:21:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:21:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:21:41 INFO - 'installer_path': 'installer.exe', 10:21:41 INFO - 'log_level': 'info', 10:21:41 INFO - 'log_name': 'talos', 10:21:41 INFO - 'log_to_console': True, 10:21:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:21:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:21:41 INFO - 'opt_config_files': (), 10:21:41 INFO - 'pip_index': False, 10:21:41 INFO - 'sps_profile': False, 10:21:41 INFO - 'sps_profile_interval': 0, 10:21:41 INFO - 'suite': 'dromaeojs', 10:21:41 INFO - 'system_bits': '32', 10:21:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:21:41 INFO - 'title': 'talos-linux64-ix-036', 10:21:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:21:41 INFO - 'use_talos_json': True, 10:21:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:21:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:21:41 INFO - 'work_dir': 'build'} 10:21:41 INFO - [mozharness: 2016-09-01 17:21:41.985787Z] Running clobber step. 10:21:41 INFO - Running pre-action listener: _resource_record_pre_action 10:21:41 INFO - Running main action method: clobber 10:21:41 INFO - rmtree: /builds/slave/test/build 10:21:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:21:42 INFO - Running post-action listener: _resource_record_post_action 10:21:42 INFO - [mozharness: 2016-09-01 17:21:42.516304Z] Finished clobber step (success) 10:21:42 INFO - [mozharness: 2016-09-01 17:21:42.516407Z] Running read-buildbot-config step. 10:21:42 INFO - Running pre-action listener: _resource_record_pre_action 10:21:42 INFO - Running main action method: read_buildbot_config 10:21:42 INFO - Using buildbot properties: 10:21:42 INFO - { 10:21:42 INFO - "project": "", 10:21:42 INFO - "product": "firefox", 10:21:42 INFO - "script_repo_revision": "production", 10:21:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:21:42 INFO - "repository": "", 10:21:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 10:21:42 INFO - "buildid": "20160901084920", 10:21:42 INFO - "pgo_build": "False", 10:21:42 INFO - "basedir": "/builds/slave/test", 10:21:42 INFO - "buildnumber": 199, 10:21:42 INFO - "slavename": "talos-linux64-ix-036", 10:21:42 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:21:42 INFO - "platform": "ubuntu64_hw", 10:21:42 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 10:21:42 INFO - "branch": "mozilla-central", 10:21:42 INFO - "repo_path": "mozilla-central", 10:21:42 INFO - "revision": "fc4a79cf9ac9681bfbeafb60f0056153b780e99e", 10:21:42 INFO - "stage_platform": "linux64", 10:21:42 INFO - "builduid": "c979e1a36aad46fcb8f098c78cf1ad62", 10:21:42 INFO - "slavebuilddir": "test" 10:21:42 INFO - } 10:21:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 10:21:42 INFO - Running post-action listener: _resource_record_post_action 10:21:42 INFO - [mozharness: 2016-09-01 17:21:42.518862Z] Finished read-buildbot-config step (success) 10:21:42 INFO - [mozharness: 2016-09-01 17:21:42.518953Z] Running download-and-extract step. 10:21:42 INFO - Running pre-action listener: _resource_record_pre_action 10:21:42 INFO - Running main action method: download_and_extract 10:21:42 INFO - mkdir: /builds/slave/test/build/tests 10:21:42 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')]} 10:21:42 INFO - trying https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:21:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:43 INFO - Downloaded 1361 bytes. 10:21:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:21:43 INFO - Using the following test package requirements: 10:21:43 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:21:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:21:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:21:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'jsshell-linux-x86_64.zip'], 10:21:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:21:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:21:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:21:43 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:21:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:21:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:21:43 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:21:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:21:43 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')]} 10:21:43 INFO - trying https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:21:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:43 INFO - Downloaded 20336293 bytes. 10:21:43 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 10:21:48 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 10:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:48 INFO - trying https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:21:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:49 INFO - Downloaded 20336293 bytes. 10:21:49 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 10:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:53 INFO - trying https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 10:21:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:54 INFO - Downloaded 14413998 bytes. 10:21:54 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 10:21: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')]} 10:21:54 INFO - trying https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:21:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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 10:21:55 INFO - Downloaded 55870585 bytes. 10:21:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:21:55 INFO - mkdir: /builds/slave/test/properties 10:21:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:21:55 INFO - Writing to file /builds/slave/test/properties/build_url 10:21:55 INFO - Contents: 10:21:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:21:56 INFO - Running post-action listener: _resource_record_post_action 10:21:56 INFO - Running post-action listener: set_extra_try_arguments 10:21:56 INFO - [mozharness: 2016-09-01 17:21:56.400101Z] Finished download-and-extract step (success) 10:21:56 INFO - [mozharness: 2016-09-01 17:21:56.400330Z] Running populate-webroot step. 10:21:56 INFO - Running pre-action listener: _resource_record_pre_action 10:21:56 INFO - Running main action method: populate_webroot 10:21:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:21:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:21:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:21:56 INFO - '--tppagecycles', 10:21:56 INFO - '7'], 10:21:56 INFO - 'tests': ['tsvgm']}}, 10:21:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:21:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:21:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:21:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:21:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:21:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['damp', 'tps']}, 10:21:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'tests': ['damp', 'tps']}, 10:21:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['dromaeo_dom']}, 10:21:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:21:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['basic_compositor_video']}, 10:21:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:21:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['a11yr', 10:21:56 INFO - 'ts_paint', 10:21:56 INFO - 'tpaint', 10:21:56 INFO - 'sessionrestore', 10:21:56 INFO - 'sessionrestore_no_auto_restore', 10:21:56 INFO - 'tabpaint']}, 10:21:56 INFO - 'other-e10s': {'tests': ['a11yr', 10:21:56 INFO - 'ts_paint', 10:21:56 INFO - 'tpaint', 10:21:56 INFO - 'sessionrestore', 10:21:56 INFO - 'sessionrestore_no_auto_restore', 10:21:56 INFO - 'tabpaint']}, 10:21:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:21:56 INFO - 'ts_paint', 10:21:56 INFO - 'tpaint', 10:21:56 INFO - 'sessionrestore', 10:21:56 INFO - 'sessionrestore_no_auto_restore', 10:21:56 INFO - 'tabpaint']}, 10:21:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:21:56 INFO - 'ts_paint', 10:21:56 INFO - 'tpaint', 10:21:56 INFO - 'sessionrestore', 10:21:56 INFO - 'sessionrestore_no_auto_restore', 10:21:56 INFO - 'tabpaint']}, 10:21:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['a11yr', 10:21:56 INFO - 'ts_paint', 10:21:56 INFO - 'tpaint', 10:21:56 INFO - 'sessionrestore', 10:21:56 INFO - 'sessionrestore_no_auto_restore', 10:21:56 INFO - 'tabpaint']}, 10:21:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['a11yr', 10:21:56 INFO - 'ts_paint', 10:21:56 INFO - 'tpaint', 10:21:56 INFO - 'sessionrestore', 10:21:56 INFO - 'sessionrestore_no_auto_restore', 10:21:56 INFO - 'tabpaint']}, 10:21:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['tsvgx', 10:21:56 INFO - 'tsvgr_opacity', 10:21:56 INFO - 'tart', 10:21:56 INFO - 'tscrollx', 10:21:56 INFO - 'cart']}, 10:21:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:21:56 INFO - 'tsvgr_opacity', 10:21:56 INFO - 'tart', 10:21:56 INFO - 'tscrollx', 10:21:56 INFO - 'cart']}, 10:21:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'talos_options': ['--disable-e10s'], 10:21:56 INFO - 'tests': ['tp5o']}, 10:21:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'tests': ['tp5o']}, 10:21:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'talos_options': ['--disable-e10s', 10:21:56 INFO - '--xperf_path', 10:21:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:56 INFO - 'tests': ['tp5n']}, 10:21:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:21:56 INFO - 'talos_options': ['--xperf_path', 10:21:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:21:56 INFO - 'tests': ['tp5n']}}, 10:21:56 INFO - 'talos.zip': {'path': '', 10:21:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:21:56 INFO - Running post-action listener: _resource_record_post_action 10:21:56 INFO - [mozharness: 2016-09-01 17:21:56.419303Z] Finished populate-webroot step (success) 10:21:56 INFO - [mozharness: 2016-09-01 17:21:56.419393Z] Running create-virtualenv step. 10:21:56 INFO - Running pre-action listener: _resource_record_pre_action 10:21:56 INFO - Running main action method: create_virtualenv 10:21:56 INFO - Creating virtualenv /builds/slave/test/build/venv 10:21:56 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 10:21:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:21:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:21:56 INFO - Using real prefix '/usr' 10:21:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:21:57 INFO - Installing distribute.............................................................................................................................................................................................done. 10:21:59 INFO - Installing pip.................done. 10:21:59 INFO - Return code: 0 10:21:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:59 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:21:59 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 10:21:59 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 10:21:59 INFO - Using env: {'DISPLAY': ':0', 10:21:59 INFO - 'HOME': '/home/cltbld', 10:21:59 INFO - 'LANG': 'en_US.UTF-8', 10:21:59 INFO - 'LANGUAGE': 'en_US:en', 10:21:59 INFO - 'LOGNAME': 'cltbld', 10:21:59 INFO - 'MAIL': '/var/mail/cltbld', 10:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:59 INFO - 'MOZ_NO_REMOTE': '1', 10:21:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:59 INFO - 'NO_EM_RESTART': '1', 10:21:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:59 INFO - 'PWD': '/builds/slave/test', 10:21:59 INFO - 'SHELL': '/bin/bash', 10:21:59 INFO - 'SHLVL': '1', 10:21:59 INFO - 'TERM': 'linux', 10:21:59 INFO - 'TMOUT': '86400', 10:21:59 INFO - 'USER': 'cltbld', 10:21:59 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:59 INFO - '_': '/tools/buildbot/bin/python'} 10:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:59 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 10:21:59 INFO - Cleaning up... 10:21:59 INFO - Return code: 0 10:21:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:59 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:21:59 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 10:21:59 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 10:21:59 INFO - Using env: {'DISPLAY': ':0', 10:21:59 INFO - 'HOME': '/home/cltbld', 10:21:59 INFO - 'LANG': 'en_US.UTF-8', 10:21:59 INFO - 'LANGUAGE': 'en_US:en', 10:21:59 INFO - 'LOGNAME': 'cltbld', 10:21:59 INFO - 'MAIL': '/var/mail/cltbld', 10:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:59 INFO - 'MOZ_NO_REMOTE': '1', 10:21:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:59 INFO - 'NO_EM_RESTART': '1', 10:21:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:59 INFO - 'PWD': '/builds/slave/test', 10:21:59 INFO - 'SHELL': '/bin/bash', 10:21:59 INFO - 'SHLVL': '1', 10:21:59 INFO - 'TERM': 'linux', 10:21:59 INFO - 'TMOUT': '86400', 10:21:59 INFO - 'USER': 'cltbld', 10:21:59 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:59 INFO - '_': '/tools/buildbot/bin/python'} 10:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:59 INFO - Downloading/unpacking psutil>=3.1.1 10:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:22:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:22:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:22:01 INFO - Installing collected packages: psutil 10:22:01 INFO - Running setup.py install for psutil 10:22:01 INFO - building 'psutil._psutil_linux' extension 10:22:01 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 10:22:01 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 10:22:01 INFO - building 'psutil._psutil_posix' extension 10:22:01 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 10:22:01 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 10:22:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:22:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:22:01 INFO - Successfully installed psutil 10:22:01 INFO - Cleaning up... 10:22:01 INFO - Return code: 0 10:22:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:22:01 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')]} 10:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:01 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')]} 10:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:01 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22:01 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 10:22:01 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 10:22:01 INFO - Using env: {'DISPLAY': ':0', 10:22:01 INFO - 'HOME': '/home/cltbld', 10:22:01 INFO - 'LANG': 'en_US.UTF-8', 10:22:01 INFO - 'LANGUAGE': 'en_US:en', 10:22:01 INFO - 'LOGNAME': 'cltbld', 10:22:01 INFO - 'MAIL': '/var/mail/cltbld', 10:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:02 INFO - 'MOZ_NO_REMOTE': '1', 10:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:02 INFO - 'NO_EM_RESTART': '1', 10:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:02 INFO - 'PWD': '/builds/slave/test', 10:22:02 INFO - 'SHELL': '/bin/bash', 10:22:02 INFO - 'SHLVL': '1', 10:22:02 INFO - 'TERM': 'linux', 10:22:02 INFO - 'TMOUT': '86400', 10:22:02 INFO - 'USER': 'cltbld', 10:22:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:02 INFO - '_': '/tools/buildbot/bin/python'} 10:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:22:02 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 10:22:02 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 10:22:02 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 10:22:02 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 10:22:02 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 10:22:02 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 10:22:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:22:04 INFO - Installing collected packages: mozsystemmonitor 10:22:04 INFO - Running setup.py install for mozsystemmonitor 10:22:04 INFO - Successfully installed mozsystemmonitor 10:22:04 INFO - Cleaning up... 10:22:04 INFO - Return code: 0 10:22:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:04 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22:04 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 10:22:04 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 10:22:04 INFO - Using env: {'DISPLAY': ':0', 10:22:04 INFO - 'HOME': '/home/cltbld', 10:22:04 INFO - 'LANG': 'en_US.UTF-8', 10:22:04 INFO - 'LANGUAGE': 'en_US:en', 10:22:04 INFO - 'LOGNAME': 'cltbld', 10:22:04 INFO - 'MAIL': '/var/mail/cltbld', 10:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:04 INFO - 'MOZ_NO_REMOTE': '1', 10:22:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:04 INFO - 'NO_EM_RESTART': '1', 10:22:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:04 INFO - 'PWD': '/builds/slave/test', 10:22:04 INFO - 'SHELL': '/bin/bash', 10:22:04 INFO - 'SHLVL': '1', 10:22:04 INFO - 'TERM': 'linux', 10:22:04 INFO - 'TMOUT': '86400', 10:22:04 INFO - 'USER': 'cltbld', 10:22:04 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:04 INFO - '_': '/tools/buildbot/bin/python'} 10:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:04 INFO - Downloading/unpacking jsonschema==2.5.1 10:22:04 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 10:22:04 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 10:22:04 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 10:22:04 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 10:22:04 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 10:22:04 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 10:22:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:22:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:22:06 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 10:22:06 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 10:22:06 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 10:22:06 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 10:22:06 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 10:22:06 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 10:22:06 INFO - Downloading functools32-3.2.3-2.tar.gz 10:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:22:06 INFO - warning: no files found matching '*.txt' 10:22:06 INFO - no previously-included directories found matching 'build' 10:22:06 INFO - no previously-included directories found matching 'dist' 10:22:06 INFO - no previously-included directories found matching '.git*' 10:22:06 INFO - Installing collected packages: jsonschema, functools32 10:22:06 INFO - Running setup.py install for functools32 10:22:06 INFO - warning: no files found matching '*.txt' 10:22:06 INFO - no previously-included directories found matching 'build' 10:22:06 INFO - no previously-included directories found matching 'dist' 10:22:06 INFO - no previously-included directories found matching '.git*' 10:22:06 INFO - Successfully installed jsonschema functools32 10:22:06 INFO - Cleaning up... 10:22:06 INFO - Return code: 0 10:22:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:22: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')]} 10:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22: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')]} 10:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:06 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22:06 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 10:22:06 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 10:22:06 INFO - Using env: {'DISPLAY': ':0', 10:22:06 INFO - 'HOME': '/home/cltbld', 10:22:06 INFO - 'LANG': 'en_US.UTF-8', 10:22:06 INFO - 'LANGUAGE': 'en_US:en', 10:22:06 INFO - 'LOGNAME': 'cltbld', 10:22:06 INFO - 'MAIL': '/var/mail/cltbld', 10:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:06 INFO - 'MOZ_NO_REMOTE': '1', 10:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:06 INFO - 'NO_EM_RESTART': '1', 10:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:06 INFO - 'PWD': '/builds/slave/test', 10:22:06 INFO - 'SHELL': '/bin/bash', 10:22:06 INFO - 'SHLVL': '1', 10:22:06 INFO - 'TERM': 'linux', 10:22:06 INFO - 'TMOUT': '86400', 10:22:06 INFO - 'USER': 'cltbld', 10:22:06 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:06 INFO - '_': '/tools/buildbot/bin/python'} 10:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:22:07 INFO - Cleaning up... 10:22:07 INFO - Return code: 0 10:22:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:22:07 INFO - Using env: {'DISPLAY': ':0', 10:22:07 INFO - 'HOME': '/home/cltbld', 10:22:07 INFO - 'LANG': 'en_US.UTF-8', 10:22:07 INFO - 'LANGUAGE': 'en_US:en', 10:22:07 INFO - 'LOGNAME': 'cltbld', 10:22:07 INFO - 'MAIL': '/var/mail/cltbld', 10:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:07 INFO - 'MOZ_NO_REMOTE': '1', 10:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:07 INFO - 'NO_EM_RESTART': '1', 10:22:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:07 INFO - 'PWD': '/builds/slave/test', 10:22:07 INFO - 'SHELL': '/bin/bash', 10:22:07 INFO - 'SHLVL': '1', 10:22:07 INFO - 'TERM': 'linux', 10:22:07 INFO - 'TMOUT': '86400', 10:22:07 INFO - 'USER': 'cltbld', 10:22:07 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:07 INFO - '_': '/tools/buildbot/bin/python'} 10:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:07 INFO - Downloading/unpacking blobuploader==1.2.4 10:22:07 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 10:22:07 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 10:22:07 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 10:22:07 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 10:22:07 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 10:22:07 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 10:22:09 INFO - Downloading blobuploader-1.2.4.tar.gz 10:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:22:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:22:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 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 10:22:09 INFO - Downloading docopt-0.6.1.tar.gz 10:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:22:09 INFO - Installing collected packages: blobuploader, requests, docopt 10:22:09 INFO - Running setup.py install for blobuploader 10:22:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:22:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:22:10 INFO - Running setup.py install for requests 10:22:10 INFO - Running setup.py install for docopt 10:22:10 INFO - Successfully installed blobuploader requests docopt 10:22:10 INFO - Cleaning up... 10:22:10 INFO - Return code: 0 10:22:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22: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')]} 10:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22: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')]} 10:22:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:10 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22:10 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 10:22:10 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 10:22:10 INFO - Using env: {'DISPLAY': ':0', 10:22:10 INFO - 'HOME': '/home/cltbld', 10:22:10 INFO - 'LANG': 'en_US.UTF-8', 10:22:10 INFO - 'LANGUAGE': 'en_US:en', 10:22:10 INFO - 'LOGNAME': 'cltbld', 10:22:10 INFO - 'MAIL': '/var/mail/cltbld', 10:22:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:10 INFO - 'MOZ_NO_REMOTE': '1', 10:22:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:10 INFO - 'NO_EM_RESTART': '1', 10:22:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:10 INFO - 'PWD': '/builds/slave/test', 10:22:10 INFO - 'SHELL': '/bin/bash', 10:22:10 INFO - 'SHLVL': '1', 10:22:10 INFO - 'TERM': 'linux', 10:22:10 INFO - 'TMOUT': '86400', 10:22:10 INFO - 'USER': 'cltbld', 10:22:10 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:10 INFO - '_': '/tools/buildbot/bin/python'} 10:22:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:22:10 INFO - Running setup.py (path:/tmp/pip-LrW9Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:22:10 INFO - Running setup.py (path:/tmp/pip-bALD1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:22:10 INFO - Running setup.py (path:/tmp/pip-FlR2JK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:22:11 INFO - Running setup.py (path:/tmp/pip-7JwJbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:22:11 INFO - Running setup.py (path:/tmp/pip-YBtYfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:22:11 INFO - Running setup.py (path:/tmp/pip-9SzbmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:22:11 INFO - Running setup.py (path:/tmp/pip-OSdybS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:22:11 INFO - Running setup.py (path:/tmp/pip-PQWC3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:22:11 INFO - Running setup.py (path:/tmp/pip-glzXEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:22:11 INFO - Running setup.py (path:/tmp/pip-8tkGLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:22:11 INFO - Running setup.py (path:/tmp/pip-Kr0Zo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:22:11 INFO - Running setup.py (path:/tmp/pip-LzH25a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:22:11 INFO - Running setup.py (path:/tmp/pip-72Ytmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:22:11 INFO - Running setup.py (path:/tmp/pip-t6W3Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:11 INFO - Running setup.py (path:/tmp/pip-grLAtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:22:11 INFO - Running setup.py (path:/tmp/pip-Yhx28O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:22:11 INFO - Running setup.py (path:/tmp/pip-ZpM0sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:22:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:22:12 INFO - Running setup.py install for manifestparser 10:22:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Running setup.py install for mozcrash 10:22:12 INFO - Running setup.py install for mozdebug 10:22:12 INFO - Running setup.py install for mozdevice 10:22:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Running setup.py install for mozfile 10:22:12 INFO - Running setup.py install for mozhttpd 10:22:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Running setup.py install for mozinfo 10:22:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Running setup.py install for mozInstall 10:22:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:22:12 INFO - Running setup.py install for mozleak 10:22:12 INFO - Running setup.py install for mozlog 10:22:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:22:13 INFO - Running setup.py install for moznetwork 10:22:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:22:13 INFO - Running setup.py install for mozprocess 10:22:13 INFO - Running setup.py install for mozprofile 10:22:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:22:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:22:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:22:13 INFO - Running setup.py install for mozrunner 10:22:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:22:13 INFO - Running setup.py install for mozscreenshot 10:22:13 INFO - Running setup.py install for moztest 10:22:13 INFO - Running setup.py install for mozversion 10:22:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:22:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:22:13 INFO - Cleaning up... 10:22:13 INFO - Return code: 0 10:22:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22: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')]} 10:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22: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')]} 10:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:13 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22:13 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 10:22:13 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 10:22:13 INFO - Using env: {'DISPLAY': ':0', 10:22:13 INFO - 'HOME': '/home/cltbld', 10:22:13 INFO - 'LANG': 'en_US.UTF-8', 10:22:13 INFO - 'LANGUAGE': 'en_US:en', 10:22:13 INFO - 'LOGNAME': 'cltbld', 10:22:13 INFO - 'MAIL': '/var/mail/cltbld', 10:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:13 INFO - 'MOZ_NO_REMOTE': '1', 10:22:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:13 INFO - 'NO_EM_RESTART': '1', 10:22:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:13 INFO - 'PWD': '/builds/slave/test', 10:22:13 INFO - 'SHELL': '/bin/bash', 10:22:13 INFO - 'SHLVL': '1', 10:22:13 INFO - 'TERM': 'linux', 10:22:13 INFO - 'TMOUT': '86400', 10:22:13 INFO - 'USER': 'cltbld', 10:22:13 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:13 INFO - '_': '/tools/buildbot/bin/python'} 10:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:22:14 INFO - Running setup.py (path:/tmp/pip-537QlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:22:14 INFO - Running setup.py (path:/tmp/pip-7Oehno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:22:14 INFO - Running setup.py (path:/tmp/pip-vj602u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:22:14 INFO - Running setup.py (path:/tmp/pip-SOYjtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:22:14 INFO - Running setup.py (path:/tmp/pip-NCCGNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:22:14 INFO - Running setup.py (path:/tmp/pip-GgWbxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:22:14 INFO - Running setup.py (path:/tmp/pip-DOLhVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:22:14 INFO - Running setup.py (path:/tmp/pip-th31N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:22:14 INFO - Running setup.py (path:/tmp/pip-uOCmT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:22:14 INFO - Running setup.py (path:/tmp/pip-qDK1d_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:22:14 INFO - Running setup.py (path:/tmp/pip-ifbgdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:22:14 INFO - Running setup.py (path:/tmp/pip-ZQCcGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:22:14 INFO - Running setup.py (path:/tmp/pip-IpgdHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:22:14 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)) 10:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:22:14 INFO - Running setup.py (path:/tmp/pip-sKvw0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:22:15 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)) 10:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:15 INFO - Running setup.py (path:/tmp/pip-qsZPeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:15 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)) 10:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:22:15 INFO - Running setup.py (path:/tmp/pip-hggcPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:22:15 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)) 10:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:22:15 INFO - Running setup.py (path:/tmp/pip-MqaD0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:22:15 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)) 10:22:15 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)) 10:22:15 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)) 10:22:15 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)) 10:22:15 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)) 10:22:15 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)) 10:22:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:17 INFO - Downloading blessings-1.6.tar.gz 10:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:22:17 INFO - Installing collected packages: blessings 10:22:17 INFO - Running setup.py install for blessings 10:22:17 INFO - Successfully installed blessings 10:22:17 INFO - Cleaning up... 10:22:17 INFO - Return code: 0 10:22:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:22:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:17 INFO - Reading from file tmpfile_stdout 10:22:17 INFO - Current package versions: 10:22:17 INFO - argparse == 1.2.1 10:22:17 INFO - blessings == 1.6 10:22:17 INFO - blobuploader == 1.2.4 10:22:17 INFO - docopt == 0.6.1 10:22:17 INFO - functools32 == 3.2.3-2 10:22:17 INFO - jsonschema == 2.5.1 10:22:17 INFO - manifestparser == 1.1 10:22:17 INFO - mozInstall == 1.12 10:22:17 INFO - mozcrash == 0.17 10:22:17 INFO - mozdebug == 0.1 10:22:17 INFO - mozdevice == 0.48 10:22:17 INFO - mozfile == 1.2 10:22:17 INFO - mozhttpd == 0.7 10:22:17 INFO - mozinfo == 0.9 10:22:17 INFO - mozleak == 0.1 10:22:17 INFO - mozlog == 3.3 10:22:17 INFO - moznetwork == 0.27 10:22:17 INFO - mozprocess == 0.23 10:22:17 INFO - mozprofile == 0.28 10:22:17 INFO - mozrunner == 6.12 10:22:17 INFO - mozscreenshot == 0.1 10:22:17 INFO - mozsystemmonitor == 0.3 10:22:17 INFO - moztest == 0.7 10:22:17 INFO - mozversion == 1.4 10:22:17 INFO - psutil == 3.1.1 10:22:17 INFO - requests == 1.2.3 10:22:17 INFO - wsgiref == 0.1.2 10:22:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:17 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 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22:17 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 10:22:17 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 10:22:17 INFO - Using env: {'DISPLAY': ':0', 10:22:17 INFO - 'HOME': '/home/cltbld', 10:22:17 INFO - 'LANG': 'en_US.UTF-8', 10:22:17 INFO - 'LANGUAGE': 'en_US:en', 10:22:17 INFO - 'LOGNAME': 'cltbld', 10:22:17 INFO - 'MAIL': '/var/mail/cltbld', 10:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:17 INFO - 'MOZ_NO_REMOTE': '1', 10:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:17 INFO - 'NO_EM_RESTART': '1', 10:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:17 INFO - 'PWD': '/builds/slave/test', 10:22:17 INFO - 'SHELL': '/bin/bash', 10:22:17 INFO - 'SHLVL': '1', 10:22:17 INFO - 'TERM': 'linux', 10:22:17 INFO - 'TMOUT': '86400', 10:22:17 INFO - 'USER': 'cltbld', 10:22:17 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:17 INFO - '_': '/tools/buildbot/bin/python'} 10:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 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)) 10:22:18 INFO - Cleaning up... 10:22:18 INFO - Return code: 0 10:22:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:22: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')]} 10:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22: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')]} 10:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5889501f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b6b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, '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': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', '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 10:22: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', 'jsonschema'] in /builds/slave/test/build 10:22: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 jsonschema 10:22:18 INFO - Using env: {'DISPLAY': ':0', 10:22:18 INFO - 'HOME': '/home/cltbld', 10:22:18 INFO - 'LANG': 'en_US.UTF-8', 10:22:18 INFO - 'LANGUAGE': 'en_US:en', 10:22:18 INFO - 'LOGNAME': 'cltbld', 10:22:18 INFO - 'MAIL': '/var/mail/cltbld', 10:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:18 INFO - 'MOZ_NO_REMOTE': '1', 10:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:18 INFO - 'NO_EM_RESTART': '1', 10:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:18 INFO - 'PWD': '/builds/slave/test', 10:22:18 INFO - 'SHELL': '/bin/bash', 10:22:18 INFO - 'SHLVL': '1', 10:22:18 INFO - 'TERM': 'linux', 10:22:18 INFO - 'TMOUT': '86400', 10:22:18 INFO - 'USER': 'cltbld', 10:22:18 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:18 INFO - '_': '/tools/buildbot/bin/python'} 10:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:22:18 INFO - Cleaning up... 10:22:18 INFO - Return code: 0 10:22:18 INFO - Running post-action listener: _resource_record_post_action 10:22:18 INFO - Running post-action listener: _start_resource_monitoring 10:22:18 INFO - Starting resource monitoring. 10:22:18 INFO - [mozharness: 2016-09-01 17:22:18.290199Z] Finished create-virtualenv step (success) 10:22:18 INFO - [mozharness: 2016-09-01 17:22:18.290703Z] Running install step. 10:22:18 INFO - Running pre-action listener: _resource_record_pre_action 10:22:18 INFO - Running main action method: install 10:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:18 INFO - Reading from file tmpfile_stdout 10:22:18 INFO - Detecting whether we're running mozinstall >=1.0... 10:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:22:18 INFO - Reading from file tmpfile_stdout 10:22:18 INFO - Output received: 10:22:18 INFO - Usage: mozinstall [options] installer 10:22:18 INFO - Options: 10:22:18 INFO - -h, --help show this help message and exit 10:22:18 INFO - -d DEST, --destination=DEST 10:22:18 INFO - Directory to install application into. [default: 10:22:18 INFO - "/builds/slave/test"] 10:22:18 INFO - --app=APP Application being installed. [default: firefox] 10:22:18 INFO - mkdir: /builds/slave/test/build/application 10:22:18 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'] 10:22:18 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 10:22:34 INFO - Reading from file tmpfile_stdout 10:22:34 INFO - Output received: 10:22:34 INFO - /builds/slave/test/build/application/firefox/firefox 10:22:34 INFO - Running post-action listener: _resource_record_post_action 10:22:34 INFO - [mozharness: 2016-09-01 17:22:34.742174Z] Finished install step (success) 10:22:34 INFO - [mozharness: 2016-09-01 17:22:34.742454Z] Running run-tests step. 10:22:34 INFO - Running pre-action listener: _resource_record_pre_action 10:22:34 INFO - Running main action method: run_tests 10:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:22:34 INFO - Python 2.7.3 10:22:34 INFO - Return code: 0 10:22:34 INFO - grabbing minidump binary from tooltool 10:22:34 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')]} 10:22:34 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 0x2b65090>, '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 0x2b689b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b68e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:22:34 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 10:22:34 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 10:22:34 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 10:22:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:22:34 INFO - Return code: 0 10:22:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:22:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:22:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:22:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:22:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:22:34 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/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 10:22:34 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/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 10:22:34 INFO - Using env: {'DISPLAY': ':0', 10:22:34 INFO - 'HOME': '/home/cltbld', 10:22:34 INFO - 'LANG': 'en_US.UTF-8', 10:22:34 INFO - 'LANGUAGE': 'en_US:en', 10:22:34 INFO - 'LOGNAME': 'cltbld', 10:22:34 INFO - 'MAIL': '/var/mail/cltbld', 10:22:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:22:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:34 INFO - 'MOZ_NO_REMOTE': '1', 10:22:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:22:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:22:34 INFO - 'NO_EM_RESTART': '1', 10:22:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:34 INFO - 'PWD': '/builds/slave/test', 10:22:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:22:34 INFO - 'SHELL': '/bin/bash', 10:22:34 INFO - 'SHLVL': '1', 10:22:34 INFO - 'TERM': 'linux', 10:22:34 INFO - 'TMOUT': '86400', 10:22:34 INFO - 'USER': 'cltbld', 10:22:34 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718', 10:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:34 INFO - '_': '/tools/buildbot/bin/python'} 10:22:34 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/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 10:22:35 INFO - mozversion application_buildid: 20160901084920 10:22:35 INFO - mozversion application_changeset: fc4a79cf9ac9681bfbeafb60f0056153b780e99e 10:22:35 INFO - mozversion application_display_name: Nightly 10:22:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:22:35 INFO - mozversion application_name: Firefox 10:22:35 INFO - mozversion application_remotingname: firefox 10:22:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:22:35 INFO - mozversion application_vendor: Mozilla 10:22:35 INFO - mozversion application_version: 51.0a1 10:22:35 INFO - mozversion platform_buildid: 20160901084920 10:22:35 INFO - mozversion platform_changeset: fc4a79cf9ac9681bfbeafb60f0056153b780e99e 10:22:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:22:35 INFO - mozversion platform_version: 51.0a1 10:22:35 INFO - using testdate: 1472750555 10:22:35 INFO - actual date: 1472750555 10:22:35 INFO - starting webserver on 'localhost:51670' 10:22:35 INFO - SUITE-START | Running 2 tests 10:22:35 INFO - TEST-START | dromaeo_css 10:22:35 INFO - operating with platform_type : linux_ 10:22:35 INFO - Initialising browser for dromaeo_css test... 10:22:35 INFO - TEST-INFO | started process 12313 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF9atGq/profile http://localhost:51670/getInfo.html) 10:22:39 INFO - PROCESS | 12313 | __metrics Screen width/height:1600/1200 10:22:39 INFO - PROCESS | 12313 | colorDepth:24 10:22:39 INFO - PROCESS | 12313 | Browser inner width/height: 1024/697 10:22:39 INFO - PROCESS | 12313 | __metrics 10:22:40 INFO - PROCESS | 12313 | 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" 10:22:40 INFO - PROCESS | 12313 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:22:40 INFO - PROCESS | 12313 | console.error: PushService: 10:22:40 INFO - PROCESS | 12313 | stateChangeProcessEnqueue: Error transitioning state 10:22:40 INFO - PROCESS | 12313 | UnknownError 10:22:41 INFO - TEST-INFO | 12313: exit 0 10:22:41 INFO - Browser initialized. 10:22:41 INFO - Running cycle 1/1 for dromaeo_css test... 10:22:41 INFO - TEST-INFO | started process 12395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF9atGq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:22:53 INFO - PROCESS | 12395 | RSS: Main: 181202944 10:22:53 INFO - PROCESS | 12395 | 10:23:45 INFO - PROCESS | 12395 | 1472750625678 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] 10:25:58 INFO - PROCESS | 12395 | Cycle 1(1): loaded http://localhost:51670/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51670/tests/dromaeo/cssquery-ext.html) 10:25:59 INFO - PROCESS | 12395 | RSS: Main: 279728128 10:25:59 INFO - PROCESS | 12395 | 10:29:02 INFO - PROCESS | 12395 | Cycle 1(1): loaded http://localhost:51670/tests/dromaeo/cssquery-ext.html (next: http://localhost:51670/tests/dromaeo/cssquery-jquery.html) 10:29:02 INFO - PROCESS | 12395 | RSS: Main: 275431424 10:29:02 INFO - PROCESS | 12395 | 10:32:04 INFO - PROCESS | 12395 | Cycle 1(1): loaded http://localhost:51670/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51670/tests/dromaeo/cssquery-mootools.html) 10:32:05 INFO - PROCESS | 12395 | RSS: Main: 259842048 10:32:05 INFO - PROCESS | 12395 | 10:35:07 INFO - PROCESS | 12395 | Cycle 1(1): loaded http://localhost:51670/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51670/tests/dromaeo/cssquery-prototype.html) 10:35:08 INFO - PROCESS | 12395 | RSS: Main: 227500032 10:35:08 INFO - PROCESS | 12395 | 10:38:11 INFO - PROCESS | 12395 | Cycle 1(1): loaded http://localhost:51670/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51670/tests/dromaeo/cssquery-yui.html) 10:38:12 INFO - PROCESS | 12395 | RSS: Main: 219815936 10:38:12 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | Cycle 1(1): loaded http://localhost:51670/tests/dromaeo/cssquery-yui.html (next: http://localhost:51670/tests/dromaeo/cssquery-dojo.html) 10:41:15 INFO - PROCESS | 12395 | RSS: Main: 308850688 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | __start_tp_report 10:41:15 INFO - PROCESS | 12395 | _x_x_mozilla_page_load 10:41:15 INFO - PROCESS | 12395 | _x_x_mozilla_page_load_details 10:41:15 INFO - PROCESS | 12395 | |i|pagename|runs| 10:41:15 INFO - PROCESS | 12395 | |0;dojo.html;2626.7504587064227;2635.9077432289873;2649.403884126073;2687.825291356062;2698.136596289187;4634.930476042862;4683.227267500863;4711.222648263041;4766.094442056011;4835.613150947925;4718.457377401592;4721.9055618887605;4725.0077483728455;4759.167145749489;4779.593734532569;4292.61366477017;4313.1158112586845;4370.6940514164025;4431.0251744616135;4549.135664223803;4444.73331600105;4481.574250446202;4498.752568608726;4521.954780452203;4541.591256786888;4290.549492303295;4333.609975102225;4334.111507141029;4394.36281739146;4423.469183697976;4349.891252718676;4386.0569977455;4407.691461597689;4457.24226881431;4467.955320446787;4271.423357846682;4276.828926842923;4294.312909934395;4346.109047645256;4392.450943632046;4697.53024697531;4752.7861246244;4759.904801903943;4767.237242041291;4822.758862056883;4962.330085438492;5095;5106.770195341219;5118.846434606968;5125.333706618153;5157.226416037624;5157.381114266274;5168.198929165986;5169.56058735008;5174.146265866128;4780.11567859947;4846.127697014501;4846.7818948147415;4876.512348765095;4904.5340692634145;5056.165732654144;5190.532852043334;5233.973830130901;5243.737813109329;5311.176767601028;4991.101601711727;5028.673136246132;5029.27075574047;5029.698218106925;5032.84901452957;5120.846374608767;5121.769520371592;5122.1804511278015;5129.204973229155;5174.223866420067;4932.950670493321;4987.152184128707;5027.396712394536;5033.5721463676;5097.745112744348;5010.473900240495;5017.648764586444;5020.221865610836;5020.623453240948;5074.188129899198;4255.510616279105;4259.701820872571;4261.27558315081;4261.616454519108;4320.675949303814;3677.44838274261;3691.390920498088;3706.7590606610484;3747.7001839852746;3780.697544196458;4390.05613793036;4414.735115893057;4417.315316126005;4454.7549884756;4462.888427789267;5097.286379906816;5109.6678715883345;5111.207762796773;5113.181890897513;5166.664166829144;4296.806643701041;4382.430284063115;4402.031553058322;4412.492563355191;4432.578905004019;4580.335851301543;4584.55284956842;4598.333241679938;4644.071185762861;4660.673752837269;4852.51474852519;4868.196747536758;4869.805207791719;4870.610351171968;4937.259411088366;4998.550130488274;5034.899302014012;5076.416212135652;5081.288619593334;5133.717645529382;4781.402324709411;4801.855944321676;4808.63935204868;4853.757312134449;4874.4150701916;4938.7530623467965;4982.825601103944;4988.451270360185;4990.5508504234795;5056.064628043824;4931.457539670561;5046.142155833591;5075.54320111177;5079.3650793650795;5086.6439349246675;4971.627127965345;4974.626902982219;4974.8756281093265;4978.775955082103;5008.4240312364545;4970.080535101018;4973.602111831117;4976.676516026447;4994.450610432777;5026.145555255688;4797.496262892346;4924.679895806761;4957.553820156203;4962.354893863775;4963.429205641253;5082.009008243357;5088.796448142181;5091.490850914879;5092.185250359925;5121.8463446096675;4976.427710813303;4981.252812078217;4982.128127577587;4983.27742477346;5025.773840177274 10:41:15 INFO - PROCESS | 12395 | |1;ext.html;553.7950958145486;555.908275134603;566.6401834834878;567.3447168520357;579.2556564814215;27712.86143569282;30819;30923;30947;30967.2258193545;29991.700082999163;42682.359764603556;43532.34701479472;43535;43593.56406435934;7656.693732250717;7784.961075194616;7787;7787.026621672291;7790.493617914845;7498.587577683225;7574.469787114904;7586.962065189667;7587.544747315151;7594.5063570868;7246.528975616595;7431.554106753585;7436;7633.541987480741;9761.707148785548;9705.708828735114;10052.64815731446;10063.245256605747;10069.647562335284;10076.09315161639;9843.114119729258;9926.2059035277;9928.404295742206;9941.254405919584;9942.204623630092;37371.62628373709;37657.62342376568;37666;37671.81164094162;37682;2950.129711735043;3136;3255.886043988473;3625.365561026836;3652.196516766307;28396.29009274764;28521.85739071312;28540.571891421645;28548.429031419462;28652;3482.3035392921265;3608.4948107265004;3622.094476380905;3676.191237927652;3738.532683414573;255108;255337;256190;256250;259086;30614;31662;51202.23196652053;51477.7426112867;51670;32101.67898320987;32105.51841722376;32125.196870078435;32211;32228.516572251436;12623.242605443702;12757.107002509922;12768.6807829805;12775.169613975062;12775.169613975248;10431.165506759593;10504.947475262576;10505.422201778976;10508.054275115124;10509.84235236472;7407.444441666897;7438.925610743931;7475.439342049368;7478.85042299151;7509.699612015458;2847.8860845565946;2919.5256395520128;2970.777191710587;3076.615423072116;3078.799878007967;798.3134504326275;840.1766269056359;848.7708318754026;912.1744820937063;953.609020301673;25647.358816029748;26864.462710745676;27416.862915685295;27449.17652470429;29140.562891556645;874.5146443723668;898.8067565473455;901.5402144906145;908.8598890832159;1000.5197505197546;13670.316484175752;14256.42974281017;14357.856421435652;14520.273986300643;14630.707385852224;387455;390064;390891;391001;391131;47432;47715.522844771804;47905.52094479011;47978.76010619925;48051.27923081157;1022.3405903192399;1086.2070688397357;1140.3101123820136;1153.204289040576;1169.5731058163879;26635.46729065369;28017.43965120727;28507.28731781722;28570.714292856806;28806.71193288124;40823.79588102041;41696;41736.37395439132;41769.58230417657;41810.790946045076;306871;307067;311309;633497;644181;36697.63302366942;37133.62866371302;37526.2494750098;37567.06082347854;37998.43002355022;40735.555866648094;41053.79473102615;41674.58325416707;41785.58214417939;41905.37141942812;34660.65339346574;35111.82444087763;35541.466877997365;35556.82221588876;35695.46456803202;34560.308793824486;34643.48034779531;34723.82638086793;34868;35223.82388088043;33913.66086339105;34230.82884585561;34578.135546611535;34660;35149.64850351566;29597.26006849846;31970.52044219385;32054.358912821146;32101.839490802395;32296.192595185308;24314.51370972606;24474.75525244796;24596.50806983886;24720.629190561795;24840.627390589518 10:41:15 INFO - PROCESS | 12395 | |2;jquery.html;342.73359935040037;365.48466662006575;368.7842612071937;373.30006238303184;390.6074395232791;12305.569305074325;15551.688966220683;15858.20708964553;15877.603059923493;16755.078470684108;16051.197440128006;16364.83635163648;16396.344146234165;16991.23539440725;17141.314347426087;4357.237483440401;4952.801887924478;5106.259592359106;5130.820421285256;5161.535461808437;5068.594512439015;5141.4087379951225;5143.459936706629;5146.845594632167;5155.407128180251;4906.828261010865;5046.671966322196;5074.5432911038;5111.13110771168;5137.407403704845;5036.722980236085;5071.1632580624155;5075.796968121271;5080.085584594772;5083.517065878736;4314.288142456491;4422.402975598286;4464.374987501752;4531.56950089741;4532.29749388842;20050.59898802016;20255.89872050645;20330.28843990468;20441.79558204414;20587.48531286715;1799.7300404939233;1927.3543362973421;2289.072925465173;2344.2615576093476;2418.7460316666675;12789.680257993532;13150;13365.264910429922;13712.725745485133;13918.58244252674;1734.6357264974245;1739.7390391441259;1746.266568041413;1750.8686848486414;1752.6144248265362;69520;71980.99999999948;72378.1506319961;72761;73345.26654733438;8549.316054715608;8701.608427620773;9489.572969216402;9714.22286217101;9956.303058785961;13360.599182024554;13378.464861405435;13379.732405351839;13396.459407168106;13399.397027133607;6990.685419156151;7103.325184107502;7192.892106618405;7299.23358047408;7369.8157546061775;6854.760083397057;6896.103506544117;6919.861602767916;6941.68762405693;7112.288771122846;5936.821895343147;6003.459688628044;6022.156898034279;6082.75668973245;6202.503799696014;1701.5575950253024;1737.3137610643726;1737.6264103217868;1747.100243374663;1791.3282519055354;791.3313250303485;808.9110197878229;839.9034111077304;958.2860768727203;968.7674958010168;15987.840121598867;16860.30936529496;17341.479755607354;17937.910310448366;18039.819601803814;872.4285592936636;879.2174964281794;891.5779331965505;926.5182105305204;934.8083642853198;9783.315167938319;9994.400335979864;10129.189664826796;10133.797324053477;10152;374263;375719;377373;398375;402185;73381;87857.56071219603;88046;88461.55769221242;88471;433.5252898076601;449.7728647033293;450.59446498151925;452.298936648194;452.728362982208;9882.25883058759;10190.592376304865;10528.157747380345;10539.841902371623;10662.306950048222;36365.45451818278;37781.622183777814;38147;38579;39011.80494097511;53526.73236633948;55733.721331393084;57069;58359.4164058354;59543.809123818144;7385.781346078052;7752.1472638008645;7825.452218344662;7848.489848159851;7975.72084977023;10837.729056773644;11438;11652.883471165518;12111.394430278628;12542.749145016865;9818.410895346302;9998.500074996076;10083.445410502492;10308.381497110197;10937.289076210021;7636.12184598756;7705.768826935201;8241.79395515117;10389.584416623251;10606.363618182933;7681.231876812274;7778.572178530008;7863.370930325675;7928.524288542706;8035.236652518001;9987.350822196535;10200.234982376203;10727.195460340661;10860.837087443853;11686.70783230426;10848.945755271174;11122.221444498808;11218.439078046229;11258.493367798632;11592.362420066976 10:41:15 INFO - PROCESS | 12395 | |3;mootools.html;1196.0790191552499;1319.736052789442;1353.6412850594593;1396.3855903402502;1642.433360490631;7183;7481.738139165131;7541.547507149575;7738.342240909516;7750.341220996208;7642.121156067054;7735.9226407735905;7741.7677469676;7745.3029227369525;7769.300762931335;2144.828413726898;2169.2949791317806;2170.6092903277404;2173.293679554143;2178.553396553703;2143.710599069121;2191.7260342457193;2210.4805370737863;2214.5460180662926;2214.944626384345;2133.807957283844;2148.677698345245;2168.533765240473;2171.0664414301846;2177.825773938081;2107.1360742095744;2119.9788002119935;2122.4693826543366;2122.957540849174;2125.798049185325;2075.6990236415636;2138.5402138540135;2139.475828422031;2139.5934772393193;2142.3144593730012;7551.773446796605;7578.242175782433;7702.768916932501;7706.460547761659;7706.961465192639;4463.509014008457;4659.161350956826;4662.930056049162;4666.23007203811;4667.813287468532;2747.4779791839687;2873.310405502685;2922.547005214195;2927.0340787540063;2930.033089080598;928.3362395886974;933.3373304953491;951.7810903492166;952.9475878826662;961.8749562556893;10430.634927777568;10516.475111108846;10525.736856578571;10529.262951593315;10529.578816847317;3978.0850404407147;4128.8348466061625;4132.648724858421;4151.626353628188;4357.891052723644;8324.376746534368;8433.40966132377;8468.195521425454;8473.449225800303;8476.957615211884;4622.329762184464;4623.722576645412;4627.907441851212;4628.814847406066;4631.698939568917;3608.1881576645046;4025.7785821780085;4224.809883555247;4298.333758267474;4304.332828411601;3356.2280675444786;3375.4936759486272;3520.2607452435277;3523.9119022024656;3536.328097661436;1025.251566356549;1051.069803224152;1064.6167379743285;1065.3980501016902;1075.1076606416655;807.100083406997;831.3203410595834;837.7486753973783;838.1409055717938;866.2809867809702;5082.669626474268;5352.00987817255;5437.809676661377;5477.7261136943;5616;807.9434439589172;841.2176675691543;870.1820288928513;879.5926517571962;882;3500.7374446916583;3522.4011917974008;3590.015083257582;3647.908802279964;3652.945205821915;9568.186704130088;9720.416774993522;9779.951100244596;9831.36096153762;9832.852507212398;9542.379745316532;9686.903130968742;9704.805903881883;9729.318947673595;9755.365901216399;7234.96382518084;7401.666924988388;7408.296211859864;7412.962935185397;7417.517861338995;5199.428062913001;5247.265382846328;5352.277442545206;5357.437469065848;5470.53500452466;8801.503744363346;8877.511736854534;8974.19232269099;9017.143371379709;9058.139476749699;9077.319201059814;9134.177923986874;9200.907990920005;9202.815943681222;9404.811903762024;5579.46995035471;6271.310155882758;6375.4262116409745;6537.5096867734155;6545.298222462289;7356.301151390609;7654.349380302737;7675.577843218453;7677.078750549969;7681.500702454323;6532.346765323429;6679.966600167162;6725.764598239038;6780.559263647847;7032.5077244592385;6624.9337506624315;6779.220389655253;6789.93210067893;6995.475339349671;7017.754378596725;6296.212973378328;6381.776637817654;6407.135036769976;6424.967875160594;6444.291127976013;4416.227160247008;4420.646348292064;4464.866054018385;4600.88497787558;4610.631149507964;4620.976895115503;4695.976520117378;4700.623950084054;4766.809327626995;4951.554360107608 10:41:15 INFO - PROCESS | 12395 | |4;prototype.html;294.48024237221307;296.6395829067683;300.64523861843014;302.2730333547818;307.8260782657797;4690.865656126462;4770.284457331402;4855.295982082596;4886.657933944621;4905.435874874391;4783.2346824507995;4786.760661966897;4786.952130478694;4807.3750412446425;4820.060088282787;3828.540575130974;3832.2754760357952;3838.6353296436932;3850.249201405727;3853.807309634521;3598.7121030317644;3630.0380399194237;3843.48113004745;3851.9807400962954;3862.6523612874835;3772.264408440355;3780.1683629601443;3784.7161462890253;3788.1588808393703;3802;3761.22894806564;3805.162864169878;3813.4470501777364;3814.4659747635355;3828.1195325075387;3390.677885600864;3393.744314603588;3395.405803984293;3397.524346591479;3399.6430374810534;4532.342810292522;4680.274557443602;4701.7414042227665;4706.152892479352;4729.952700472985;4366.737995720236;4375.496817865923;4375.8249670013165;4380.518143004267;4381.16757816014;4880.560749532559;5068.341115654941;5069.594432445396;5071.656011157046;5086.974565127188;3612.674859262679;3615.331163734691;3619.6742293193474;3620;3621.474886141521;5221.39953905302;5239.13554263546;5265.578753699695;5273.947260527384;5288.10102282609;3650.324689932371;3677.6322367763546;3678.0988657779017;3683.7053035757076;3685.8341374638208;3626.1297288650535;3697.574778900407;3701.1857391373856;3722.6091260417265;3728.217074414349;4229.323308270662;4245.8513952011535;4247.511536173318;4265.786710664516;4267.445232119804;5011.774470148852;5025.547700706954;5045.091883461012;5054.1407960646775;5059.30513278056;1208.4380762945273;1222.896053835434;1272.0840994483956;1286.5882917466322;1304.660788195076;858.8448536718104;866.8136350684633;880.1198801198801;881.2465342132513;883.8011447424407;4506.549345065468;4509.571590698878;4511.187986162522;4514.864554063383;4519.706219095749;5070.822521211814;5169.198774190007;5171.689698618095;5172.637915345889;5173.5602473789395;4442.466903971479;4444.355568442557;4444.3555884323005;4447.777611119431;4457.554244575581;2283.2807665585287;2388.4387169015267;2399.7360290368047;2413.263954493861;2419.588669926117;4316.647427152209;4317.244482215599;4327.199468098412;4344.565543445693;4358.76026818528;4602.815887364535;4610.861674149781;4622.491525932145;4632.189366860786;4658.853620277955;4088.9182216355507;4249.638780703551;4283.657307415399;4308.224519586504;4350.499692535335;4458.955410445854;4480.177488182424;4521.389612402284;4523.570260825217;4540.954590454053;4390.319500477431;4404.361367601744;4427.446569178853;4437.733735975852;4458.420405347284;4090.570490098497;4271.5514870938105;4303.526612072732;4326.783660816884;4354.368616550646;3340.8496617651776;3349.933001340008;3351.765376423627;3354.228527438654;3362.9831850840587;4553.886152846206;4556.2254416748565;4563.361129441947;4581.977090114528;4969.850904472872;4825.455854126786;5018.673786203885;5018.874528136826;5039.798408063783;5046.621503387334;4979.427365852974;4985.900281994413;4990.4011518618;4996.950030499794;5008.9248661269385;4824.49342818999;4920.852374428773;4943.2585112233455;4974.676646017997;4988.9251661225835;5032.6225533084435;5033.697978121324;5039.445660977216;5063.417706963599;5067.822626208213;4933.432655244549;4953.678010929278;4953.801847926043;4971.254311853107;4976.780688731284 10:41:15 INFO - PROCESS | 12395 | |5;yui.html;628.4281304013348;661.771688767375;706.9681864316105;721.7329588052415;749.8050506868212;13050.347482625866;13969.092009019407;14001.579952601425;14042.578722638324;14190.361433735481;12879.484820607187;12993.61019169424;13004.284764337957;13023.544175953844;13028.80456793149;580.4485738548395;709.6770969209024;712.1347562711305;727.9126504819429;744.0699126092385;748.8951546783454;749.2881762325785;751.2675141736818;752.2026651749136;752.2627824731766;785.1128225105623;785.3638552772244;785.83104632504;791.936645068396;794.5431376958243;608.3004544773502;739.5267029101352;763.8815983522563;766.7967988483057;770.7726220764888;554.2323881424217;554.3237250554317;554.5175697143511;554.8252300525362;554.977800887964;12262.693432664166;12307.384630768425;12345.93827030859;12413;12421;11992.400379980965;12343.19769214998;12738.363081845964;12860.228386296852;12982.415791289415;11038.337699738042;11260.380679062648;11303.265287756269;11488.310701357861;11998.040156787436;3746.4942232798494;3791.696664266852;3816.446615240802;3881.3207688654657;3887.436321733333;63873.361266387205;64556;64576.677116614585;64604.353956460305;64819;9863.75340616476;10054.899451005542;10085.445300508396;10111.544980475895;10131.594736210469;19120.426387208405;19146.71279930802;19169.424917252225;19196.80803191978;19205.90397048006;6545.541812073205;6571.145751052428;6676.632785196861;6705.094812200388;6751.189857217068;3487.476878468199;3544.8050357230086;3593.586737525166;3633.1280492682095;3647.7811331320204;2618.279973007438;2676.7323267673078;2678.4776968490955;2682.906098286551;2694.7439993200614;471.9032598317337;476.4140107667519;484.41144010027983;490.44579625023493;491.29008582597805;437.97732295091316;483.74603333250326;487.89998050399583;489.6890474548616;518.0338668383513;7150.249223831423;7633.770986870402;7652.885206721904;7672.808179795438;7744.109427415807;519.0967716173831;538.1953978801716;554.4732504121101;556.4213218253052;568.9630174038757;3454.707808314337;3522.7181825453904;3525.1715846775987;3694.51971243742;3787.6780473659496;59043.70478147582;69484;100102;111932;112709.43645281722;88727.55636221777;90384.5480772592;93953;95198.52400737951;95969;47687;76508.23491765122;77964.6101769499;79735;79868;6885.931140688729;7004.369606735215;7278.490505664553;7312.96343518279;7399.260073992557;43503.56496434995;44757;45839.54160458353;45909.540904590525;46475;53063.73468132634;53360.46639533555;54682.72658636841;62352.06471903016;62722.96432570767;10738.570457181937;11003.878237237004;11134.944325278322;11245.269057511236;11359.772804544029;12383.798969097843;12765.8085128725;12820.423080961193;12933.54732584355;13069.477220911058;11894.821577676168;12043.698907527381;12463.065270104596;12630.87369126297;12696.936515317364;11636.88363116358;12274.018078553872;12472.750544988869;12559.309237991998;12675.676215989492;10967.88570579623;11321.603743868925;11389.772204556028;11495.540178393105;11631.418429078682;9014;9196.264298855942;9246.352755307063;9330.206932410822;9365.922918864419;9803.852942206015;10205.84691229647;10281.228907832092;10306.587736490457;10369.63706270277 10:41:15 INFO - PROCESS | 12395 | __end_tp_report 10:41:15 INFO - PROCESS | 12395 | __start_cc_report 10:41:15 INFO - PROCESS | 12395 | _x_x_mozilla_cycle_collect,1011 10:41:15 INFO - PROCESS | 12395 | __end_cc_report 10:41:15 INFO - PROCESS | 12395 | __startTimestamp1472751675449__endTimestamp 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | ------- Summary: start ------- 10:41:15 INFO - PROCESS | 12395 | Number of tests: 6 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | [#0] dojo.html Cycles:165 Average:4717.73 Median:4874.42 stddev:499.40 (10.2%) stddev-sans-first:473.23 10:41:15 INFO - PROCESS | 12395 | Values: 2626.8 2635.9 2649.4 2687.8 2698.1 4634.9 4683.2 4711.2 4766.1 4835.6 4718.5 4721.9 4725.0 4759.2 4779.6 4292.6 4313.1 4370.7 4431.0 4549.1 4444.7 4481.6 4498.8 4522.0 4541.6 4290.5 4333.6 4334.1 4394.4 4423.5 4349.9 4386.1 4407.7 4457.2 4468.0 4271.4 4276.8 4294.3 4346.1 4392.5 4697.5 4752.8 4759.9 4767.2 4822.8 4962.3 5095.0 5106.8 5118.8 5125.3 5157.2 5157.4 5168.2 5169.6 5174.1 4780.1 4846.1 4846.8 4876.5 4904.5 5056.2 5190.5 5234.0 5243.7 5311.2 4991.1 5028.7 5029.3 5029.7 5032.8 5120.8 5121.8 5122.2 5129.2 5174.2 4933.0 4987.2 5027.4 5033.6 5097.7 5010.5 5017.6 5020.2 5020.6 5074.2 4255.5 4259.7 4261.3 4261.6 4320.7 3677.4 3691.4 3706.8 3747.7 3780.7 4390.1 4414.7 4417.3 4454.8 4462.9 5097.3 5109.7 5111.2 5113.2 5166.7 4296.8 4382.4 4402.0 4412.5 4432.6 4580.3 4584.6 4598.3 4644.1 4660.7 4852.5 4868.2 4869.8 4870.6 4937.3 4998.6 5034.9 5076.4 5081.3 5133.7 4781.4 4801.9 4808.6 4853.8 4874.4 4938.8 4982.8 4988.5 4990.6 5056.1 4931.5 5046.1 5075.5 5079.4 5086.6 4971.6 4974.6 4974.9 4978.8 5008.4 4970.1 4973.6 4976.7 4994.5 5026.1 4797.5 4924.7 4957.6 4962.4 4963.4 5082.0 5088.8 5091.5 5092.2 5121.8 4976.4 4981.3 4982.1 4983.3 5025.8 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | [#1] ext.html Cycles:180 Average:49599.84 Median:35186.74 stddev:101991.45 (289.9%) stddev-sans-first:102211.08 10:41:15 INFO - PROCESS | 12395 | Values: 553.8 555.9 566.6 567.3 579.3 27712.9 30819.0 30923.0 30947.0 30967.2 29991.7 42682.4 43532.3 43535.0 43593.6 7656.7 7785.0 7787.0 7787.0 7790.5 7498.6 7574.5 7587.0 7587.5 7594.5 7246.5 7431.6 7436.0 7633.5 9761.7 9705.7 10052.6 10063.2 10069.6 10076.1 9843.1 9926.2 9928.4 9941.3 9942.2 37371.6 37657.6 37666.0 37671.8 37682.0 2950.1 3136.0 3255.9 3625.4 3652.2 28396.3 28521.9 28540.6 28548.4 28652.0 3482.3 3608.5 3622.1 3676.2 3738.5 255108.0 255337.0 256190.0 256250.0 259086.0 30614.0 31662.0 51202.2 51477.7 51670.0 32101.7 32105.5 32125.2 32211.0 32228.5 12623.2 12757.1 12768.7 12775.2 12775.2 10431.2 10504.9 10505.4 10508.1 10509.8 7407.4 7438.9 7475.4 7478.9 7509.7 2847.9 2919.5 2970.8 3076.6 3078.8 798.3 840.2 848.8 912.2 953.6 25647.4 26864.5 27416.9 27449.2 29140.6 874.5 898.8 901.5 908.9 1000.5 13670.3 14256.4 14357.9 14520.3 14630.7 387455.0 390064.0 390891.0 391001.0 391131.0 47432.0 47715.5 47905.5 47978.8 48051.3 1022.3 1086.2 1140.3 1153.2 1169.6 26635.5 28017.4 28507.3 28570.7 28806.7 40823.8 41696.0 41736.4 41769.6 41810.8 306871.0 307067.0 311309.0 633497.0 644181.0 36697.6 37133.6 37526.2 37567.1 37998.4 40735.6 41053.8 41674.6 41785.6 41905.4 34660.7 35111.8 35541.5 35556.8 35695.5 34560.3 34643.5 34723.8 34868.0 35223.8 33913.7 34230.8 34578.1 34660.0 35149.6 29597.3 31970.5 32054.4 32101.8 32296.2 24314.5 24474.8 24596.5 24720.6 24840.6 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | [#2] jquery.html Cycles:180 Average:24582.54 Median:2457.07 stddev:64093.17 (2608.5%) stddev-sans-first:64247.13 10:41:15 INFO - PROCESS | 12395 | Values: 342.7 365.5 368.8 373.3 390.6 12305.6 15551.7 15858.2 15877.6 16755.1 16051.2 16364.8 16396.3 16991.2 17141.3 4357.2 4952.8 5106.3 5130.8 5161.5 5068.6 5141.4 5143.5 5146.8 5155.4 4906.8 5046.7 5074.5 5111.1 5137.4 5036.7 5071.2 5075.8 5080.1 5083.5 4314.3 4422.4 4464.4 4531.6 4532.3 20050.6 20255.9 20330.3 20441.8 20587.5 1799.7 1927.4 2289.1 2344.3 2418.7 12789.7 13150.0 13365.3 13712.7 13918.6 1734.6 1739.7 1746.3 1750.9 1752.6 69520.0 71981.0 72378.2 72761.0 73345.3 8549.3 8701.6 9489.6 9714.2 9956.3 13360.6 13378.5 13379.7 13396.5 13399.4 6990.7 7103.3 7192.9 7299.2 7369.8 6854.8 6896.1 6919.9 6941.7 7112.3 5936.8 6003.5 6022.2 6082.8 6202.5 1701.6 1737.3 1737.6 1747.1 1791.3 791.3 808.9 839.9 958.3 968.8 15987.8 16860.3 17341.5 17937.9 18039.8 872.4 879.2 891.6 926.5 934.8 9783.3 9994.4 10129.2 10133.8 10152.0 374263.0 375719.0 377373.0 398375.0 402185.0 73381.0 87857.6 88046.0 88461.6 88471.0 433.5 449.8 450.6 452.3 452.7 9882.3 10190.6 10528.2 10539.8 10662.3 36365.5 37781.6 38147.0 38579.0 39011.8 53526.7 55733.7 57069.0 58359.4 59543.8 7385.8 7752.1 7825.5 7848.5 7975.7 10837.7 11438.0 11652.9 12111.4 12542.7 9818.4 9998.5 10083.4 10308.4 10937.3 7636.1 7705.8 8241.8 10389.6 10606.4 7681.2 7778.6 7863.4 7928.5 8035.2 9987.4 10200.2 10727.2 10860.8 11686.7 10848.9 11122.2 11218.4 11258.5 11592.4 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | [#3] mootools.html Cycles:180 Average:5108.25 Median:5397.62 stddev:2868.55 (53.1%) stddev-sans-first:2861.53 10:41:15 INFO - PROCESS | 12395 | Values: 1196.1 1319.7 1353.6 1396.4 1642.4 7183.0 7481.7 7541.5 7738.3 7750.3 7642.1 7735.9 7741.8 7745.3 7769.3 2144.8 2169.3 2170.6 2173.3 2178.6 2143.7 2191.7 2210.5 2214.5 2214.9 2133.8 2148.7 2168.5 2171.1 2177.8 2107.1 2120.0 2122.5 2123.0 2125.8 2075.7 2138.5 2139.5 2139.6 2142.3 7551.8 7578.2 7702.8 7706.5 7707.0 4463.5 4659.2 4662.9 4666.2 4667.8 2747.5 2873.3 2922.5 2927.0 2930.0 928.3 933.3 951.8 952.9 961.9 10430.6 10516.5 10525.7 10529.3 10529.6 3978.1 4128.8 4132.6 4151.6 4357.9 8324.4 8433.4 8468.2 8473.4 8477.0 4622.3 4623.7 4627.9 4628.8 4631.7 3608.2 4025.8 4224.8 4298.3 4304.3 3356.2 3375.5 3520.3 3523.9 3536.3 1025.3 1051.1 1064.6 1065.4 1075.1 807.1 831.3 837.7 838.1 866.3 5082.7 5352.0 5437.8 5477.7 5616.0 807.9 841.2 870.2 879.6 882.0 3500.7 3522.4 3590.0 3647.9 3652.9 9568.2 9720.4 9780.0 9831.4 9832.9 9542.4 9686.9 9704.8 9729.3 9755.4 7235.0 7401.7 7408.3 7413.0 7417.5 5199.4 5247.3 5352.3 5357.4 5470.5 8801.5 8877.5 8974.2 9017.1 9058.1 9077.3 9134.2 9200.9 9202.8 9404.8 5579.5 6271.3 6375.4 6537.5 6545.3 7356.3 7654.3 7675.6 7677.1 7681.5 6532.3 6680.0 6725.8 6780.6 7032.5 6624.9 6779.2 6789.9 6995.5 7017.8 6296.2 6381.8 6407.1 6425.0 6444.3 4416.2 4420.6 4464.9 4600.9 4610.6 4621.0 4696.0 4700.6 4766.8 4951.6 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | [#4] prototype.html Cycles:180 Average:4057.57 Median:4444.36 stddev:1166.22 (26.2%) stddev-sans-first:1134.78 10:41:15 INFO - PROCESS | 12395 | Values: 294.5 296.6 300.6 302.3 307.8 4690.9 4770.3 4855.3 4886.7 4905.4 4783.2 4786.8 4787.0 4807.4 4820.1 3828.5 3832.3 3838.6 3850.2 3853.8 3598.7 3630.0 3843.5 3852.0 3862.7 3772.3 3780.2 3784.7 3788.2 3802.0 3761.2 3805.2 3813.4 3814.5 3828.1 3390.7 3393.7 3395.4 3397.5 3399.6 4532.3 4680.3 4701.7 4706.2 4730.0 4366.7 4375.5 4375.8 4380.5 4381.2 4880.6 5068.3 5069.6 5071.7 5087.0 3612.7 3615.3 3619.7 3620.0 3621.5 5221.4 5239.1 5265.6 5273.9 5288.1 3650.3 3677.6 3678.1 3683.7 3685.8 3626.1 3697.6 3701.2 3722.6 3728.2 4229.3 4245.9 4247.5 4265.8 4267.4 5011.8 5025.5 5045.1 5054.1 5059.3 1208.4 1222.9 1272.1 1286.6 1304.7 858.8 866.8 880.1 881.2 883.8 4506.5 4509.6 4511.2 4514.9 4519.7 5070.8 5169.2 5171.7 5172.6 5173.6 4442.5 4444.4 4444.4 4447.8 4457.6 2283.3 2388.4 2399.7 2413.3 2419.6 4316.6 4317.2 4327.2 4344.6 4358.8 4602.8 4610.9 4622.5 4632.2 4658.9 4088.9 4249.6 4283.7 4308.2 4350.5 4459.0 4480.2 4521.4 4523.6 4541.0 4390.3 4404.4 4427.4 4437.7 4458.4 4090.6 4271.6 4303.5 4326.8 4354.4 3340.8 3349.9 3351.8 3354.2 3363.0 4553.9 4556.2 4563.4 4582.0 4969.9 4825.5 5018.7 5018.9 5039.8 5046.6 4979.4 4985.9 4990.4 4997.0 5008.9 4824.5 4920.9 4943.3 4974.7 4988.9 5032.6 5033.7 5039.4 5063.4 5067.8 4933.4 4953.7 4953.8 4971.3 4976.8 10:41:15 INFO - PROCESS | 12395 | 10:41:15 INFO - PROCESS | 12395 | [#5] yui.html Cycles:180 Average:17650.55 Median:46192.27 stddev:25569.48 (55.4%) stddev-sans-first:25609.27 10:41:15 INFO - PROCESS | 12395 | Values: 628.4 661.8 707.0 721.7 749.8 13050.3 13969.1 14001.6 14042.6 14190.4 12879.5 12993.6 13004.3 13023.5 13028.8 580.4 709.7 712.1 727.9 744.1 748.9 749.3 751.3 752.2 752.3 785.1 785.4 785.8 791.9 794.5 608.3 739.5 763.9 766.8 770.8 554.2 554.3 554.5 554.8 555.0 12262.7 12307.4 12345.9 12413.0 12421.0 11992.4 12343.2 12738.4 12860.2 12982.4 11038.3 11260.4 11303.3 11488.3 11998.0 3746.5 3791.7 3816.4 3881.3 3887.4 63873.4 64556.0 64576.7 64604.4 64819.0 9863.8 10054.9 10085.4 10111.5 10131.6 19120.4 19146.7 19169.4 19196.8 19205.9 6545.5 6571.1 6676.6 6705.1 6751.2 3487.5 3544.8 3593.6 3633.1 3647.8 2618.3 2676.7 2678.5 2682.9 2694.7 471.9 476.4 484.4 490.4 491.3 438.0 483.7 487.9 489.7 518.0 7150.2 7633.8 7652.9 7672.8 7744.1 519.1 538.2 554.5 556.4 569.0 3454.7 3522.7 3525.2 3694.5 3787.7 59043.7 69484.0 100102.0 111932.0 112709.4 88727.6 90384.5 93953.0 95198.5 95969.0 47687.0 76508.2 77964.6 79735.0 79868.0 6885.9 7004.4 7278.5 7313.0 7399.3 43503.6 44757.0 45839.5 45909.5 46475.0 53063.7 53360.5 54682.7 62352.1 62723.0 10738.6 11003.9 11134.9 11245.3 11359.8 12383.8 12765.8 12820.4 12933.5 13069.5 11894.8 12043.7 12463.1 12630.9 12696.9 11636.9 12274.0 12472.8 12559.3 12675.7 10967.9 11321.6 11389.8 11495.5 11631.4 9014.0 9196.3 9246.4 9330.2 9365.9 9803.9 10205.8 10281.2 10306.6 10369.6 10:41:15 INFO - PROCESS | 12395 | -------- Summary: end -------- 10:41:15 INFO - PROCESS | 12395 | 10:41:16 INFO - TEST-INFO | 12395: exit 0 10:41:16 INFO - TEST-OK | dromaeo_css | took 1121470ms 10:41:16 INFO - TEST-START | kraken 10:41:16 INFO - operating with platform_type : linux_ 10:41:16 INFO - Initialising browser for kraken test... 10:41:16 INFO - TEST-INFO | started process 12704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJNGyZu/profile http://localhost:51670/getInfo.html) 10:41:21 INFO - PROCESS | 12704 | __metrics Screen width/height:1600/1200 10:41:21 INFO - PROCESS | 12704 | colorDepth:24 10:41:21 INFO - PROCESS | 12704 | Browser inner width/height: 1024/697 10:41:21 INFO - PROCESS | 12704 | __metrics 10:41:21 INFO - PROCESS | 12704 | 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" 10:41:21 INFO - PROCESS | 12704 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:41:22 INFO - PROCESS | 12704 | console.error: PushService: 10:41:22 INFO - PROCESS | 12704 | stateChangeProcessEnqueue: Error transitioning state 10:41:22 INFO - PROCESS | 12704 | UnknownError 10:41:22 INFO - TEST-INFO | 12704: exit 0 10:41:22 INFO - Browser initialized. 10:41:22 INFO - Running cycle 1/1 for kraken test... 10:41:22 INFO - TEST-INFO | started process 12788 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJNGyZu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:41:35 INFO - PROCESS | 12788 | RSS: Main: 180142080 10:41:35 INFO - PROCESS | 12788 | 10:41:37 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51670/tests/kraken/driver.html?testName=audio-beat-detection) 10:41:38 INFO - PROCESS | 12788 | RSS: Main: 234721280 10:41:38 INFO - PROCESS | 12788 | 10:41:41 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51670/tests/kraken/driver.html?testName=audio-dft) 10:41:42 INFO - PROCESS | 12788 | RSS: Main: 222892032 10:41:42 INFO - PROCESS | 12788 | 10:41:45 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51670/tests/kraken/driver.html?testName=audio-fft) 10:41:45 INFO - PROCESS | 12788 | RSS: Main: 302043136 10:41:45 INFO - PROCESS | 12788 | 10:41:48 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51670/tests/kraken/driver.html?testName=audio-oscillator) 10:41:48 INFO - PROCESS | 12788 | RSS: Main: 211804160 10:41:48 INFO - PROCESS | 12788 | 10:41:50 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51670/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:41:51 INFO - PROCESS | 12788 | RSS: Main: 214261760 10:41:51 INFO - PROCESS | 12788 | 10:41:54 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51670/tests/kraken/driver.html?testName=imaging-darkroom) 10:41:55 INFO - PROCESS | 12788 | RSS: Main: 252649472 10:41:55 INFO - PROCESS | 12788 | 10:41:58 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51670/tests/kraken/driver.html?testName=imaging-desaturate) 10:41:59 INFO - PROCESS | 12788 | RSS: Main: 246788096 10:41:59 INFO - PROCESS | 12788 | 10:42:02 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51670/tests/kraken/driver.html?testName=json-parse-financial) 10:42:03 INFO - PROCESS | 12788 | RSS: Main: 250339328 10:42:03 INFO - PROCESS | 12788 | 10:42:04 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51670/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:42:05 INFO - PROCESS | 12788 | RSS: Main: 214032384 10:42:05 INFO - PROCESS | 12788 | 10:42:07 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:42:07 INFO - PROCESS | 12788 | RSS: Main: 221990912 10:42:07 INFO - PROCESS | 12788 | 10:42:10 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:42:10 INFO - PROCESS | 12788 | RSS: Main: 222765056 10:42:10 INFO - PROCESS | 12788 | 10:42:13 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:42:14 INFO - PROCESS | 12788 | RSS: Main: 223641600 10:42:14 INFO - PROCESS | 12788 | 10:42:17 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:42:17 INFO - PROCESS | 12788 | RSS: Main: 221970432 10:42:17 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | Cycle 1(1): loaded http://localhost:51670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51670/tests/kraken/driver.html?testName=ai-astar) 10:42:19 INFO - PROCESS | 12788 | RSS: Main: 272519168 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | __start_tp_report 10:42:19 INFO - PROCESS | 12788 | _x_x_mozilla_page_load 10:42:19 INFO - PROCESS | 12788 | _x_x_mozilla_page_load_details 10:42:19 INFO - PROCESS | 12788 | |i|pagename|runs| 10:42:19 INFO - PROCESS | 12788 | |0;ai-astar;116;116;122;117;115;116;114;115;115;132 10:42:19 INFO - PROCESS | 12788 | |1;audio-beat-detection;147;123;151;192;123;151;151;128;183;129 10:42:19 INFO - PROCESS | 12788 | |2;audio-dft;164;163;163;162;161;160;161;161;160;159 10:42:19 INFO - PROCESS | 12788 | |3;audio-fft;76;77;79;170;77;77;77;76;170;78 10:42:19 INFO - PROCESS | 12788 | |4;audio-oscillator;85;84;149;83;84;83;142;83;83;138 10:42:19 INFO - PROCESS | 12788 | |5;imaging-gaussian-blur;117;116;117;117;118;117;118;117;118;117 10:42:19 INFO - PROCESS | 12788 | |6;imaging-darkroom;147;146;146;147;147;146;147;146;146;147 10:42:19 INFO - PROCESS | 12788 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;97;95 10:42:19 INFO - PROCESS | 12788 | |8;json-parse-financial;64;64;65;65;66;68;68;64;75;66 10:42:19 INFO - PROCESS | 12788 | |9;json-stringify-tinderbox;65;66;66;65;66;65;66;66;67;65 10:42:19 INFO - PROCESS | 12788 | |10;stanford-crypto-aes;82;82;90;82;84;82;92;82;85;82 10:42:19 INFO - PROCESS | 12788 | |11;stanford-crypto-ccm;135;132;134;142;136;131;132;133;181;136 10:42:19 INFO - PROCESS | 12788 | |12;stanford-crypto-pbkdf2;175;174;173;173;174;174;173;174;180;176 10:42:19 INFO - PROCESS | 12788 | |13;stanford-crypto-sha256-iterative;71;72;69;70;70;69;70;73;68;67 10:42:19 INFO - PROCESS | 12788 | __end_tp_report 10:42:19 INFO - PROCESS | 12788 | __start_cc_report 10:42:19 INFO - PROCESS | 12788 | _x_x_mozilla_cycle_collect,2002 10:42:19 INFO - PROCESS | 12788 | __end_cc_report 10:42:19 INFO - PROCESS | 12788 | __startTimestamp1472751739887__endTimestamp 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | ------- Summary: start ------- 10:42:19 INFO - PROCESS | 12788 | Number of tests: 14 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#0] ai-astar Cycles:10 Average:117.80 Median:116.00 stddev:5.45 (4.7%) stddev-sans-first:5.74 10:42:19 INFO - PROCESS | 12788 | Values: 116.0 116.0 122.0 117.0 115.0 116.0 114.0 115.0 115.0 132.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:151.00 stddev:24.04 (15.9%) stddev-sans-first:25.49 10:42:19 INFO - PROCESS | 12788 | Values: 147.0 123.0 151.0 192.0 123.0 151.0 151.0 128.0 183.0 129.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.58 (1.0%) stddev-sans-first:1.36 10:42:19 INFO - PROCESS | 12788 | Values: 164.0 163.0 163.0 162.0 161.0 160.0 161.0 161.0 160.0 159.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#3] audio-fft Cycles:10 Average:95.70 Median:77.00 stddev:39.17 (50.9%) stddev-sans-first:40.89 10:42:19 INFO - PROCESS | 12788 | Values: 76.0 77.0 79.0 170.0 77.0 77.0 77.0 76.0 170.0 78.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#4] audio-oscillator Cycles:10 Average:101.40 Median:83.00 stddev:28.83 (34.7%) stddev-sans-first:29.97 10:42:19 INFO - PROCESS | 12788 | Values: 85.0 84.0 149.0 83.0 84.0 83.0 142.0 83.0 83.0 138.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 10:42:19 INFO - PROCESS | 12788 | Values: 117.0 116.0 117.0 117.0 118.0 117.0 118.0 117.0 118.0 117.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 10:42:19 INFO - PROCESS | 12788 | Values: 147.0 146.0 146.0 147.0 147.0 146.0 147.0 146.0 146.0 147.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 10:42:19 INFO - PROCESS | 12788 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 97.0 95.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:66.00 stddev:3.34 (5.1%) stddev-sans-first:3.42 10:42:19 INFO - PROCESS | 12788 | Values: 64.0 64.0 65.0 65.0 66.0 68.0 68.0 64.0 75.0 66.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 10:42:19 INFO - PROCESS | 12788 | Values: 65.0 66.0 66.0 65.0 66.0 65.0 66.0 66.0 67.0 65.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:83.00 stddev:3.71 (4.5%) stddev-sans-first:3.84 10:42:19 INFO - PROCESS | 12788 | Values: 82.0 82.0 90.0 82.0 84.0 82.0 92.0 82.0 85.0 82.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:135.50 stddev:15.02 (11.1%) stddev-sans-first:15.85 10:42:19 INFO - PROCESS | 12788 | Values: 135.0 132.0 134.0 142.0 136.0 131.0 132.0 133.0 181.0 136.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:174.00 stddev:2.12 (1.2%) stddev-sans-first:2.24 10:42:19 INFO - PROCESS | 12788 | Values: 175.0 174.0 173.0 173.0 174.0 174.0 173.0 174.0 180.0 176.0 10:42:19 INFO - PROCESS | 12788 | 10:42:19 INFO - PROCESS | 12788 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:1.79 (2.6%) stddev-sans-first:1.86 10:42:19 INFO - PROCESS | 12788 | Values: 71.0 72.0 69.0 70.0 70.0 69.0 70.0 73.0 68.0 67.0 10:42:19 INFO - PROCESS | 12788 | -------- Summary: end -------- 10:42:19 INFO - PROCESS | 12788 | 10:42:21 INFO - TEST-INFO | 12788: exit 0 10:42:21 INFO - TEST-OK | kraken | took 64825ms 10:42:21 INFO - SUITE-END | took 1186s 10:42:21 INFO - Completed test suite (00:19:46) 10:42:21 INFO - javascript benchmark 10:42:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2626.7504587064227, 2635.9077432289873, 2649.403884126073, 2687.825291356062, 2698.136596289187, 4634.930476042862, 4683.227267500863, 4711.222648263041, 4766.094442056011, 4835.613150947925, 4718.457377401592, 4721.9055618887605, 4725.0077483728455, 4759.167145749489, 4779.593734532569, 4292.61366477017, 4313.1158112586845, 4370.6940514164025, 4431.0251744616135, 4549.135664223803, 4444.73331600105, 4481.574250446202, 4498.752568608726, 4521.954780452203, 4541.591256786888, 4290.549492303295, 4333.609975102225, 4334.111507141029, 4394.36281739146, 4423.469183697976, 4349.891252718676, 4386.0569977455, 4407.691461597689, 4457.24226881431, 4467.955320446787, 4271.423357846682, 4276.828926842923, 4294.312909934395, 4346.109047645256, 4392.450943632046, 4697.53024697531, 4752.7861246244, 4759.904801903943, 4767.237242041291, 4822.758862056883, 4962.330085438492, 5095.0, 5106.770195341219, 5118.846434606968, 5125.333706618153, 5157.226416037624, 5157.381114266274, 5168.198929165986, 5169.56058735008, 5174.146265866128, 4780.11567859947, 4846.127697014501, 4846.7818948147415, 4876.512348765095, 4904.5340692634145, 5056.165732654144, 5190.532852043334, 5233.973830130901, 5243.737813109329, 5311.176767601028, 4991.101601711727, 5028.673136246132, 5029.27075574047, 5029.698218106925, 5032.84901452957, 5120.846374608767, 5121.769520371592, 5122.1804511278015, 5129.204973229155, 5174.223866420067, 4932.950670493321, 4987.152184128707, 5027.396712394536, 5033.5721463676, 5097.745112744348, 5010.473900240495, 5017.648764586444, 5020.221865610836, 5020.623453240948, 5074.188129899198, 4255.510616279105, 4259.701820872571, 4261.27558315081, 4261.616454519108, 4320.675949303814, 3677.44838274261, 3691.390920498088, 3706.7590606610484, 3747.7001839852746, 3780.697544196458, 4390.05613793036, 4414.735115893057, 4417.315316126005, 4454.7549884756, 4462.888427789267, 5097.286379906816, 5109.6678715883345, 5111.207762796773, 5113.181890897513, 5166.664166829144, 4296.806643701041, 4382.430284063115, 4402.031553058322, 4412.492563355191, 4432.578905004019, 4580.335851301543, 4584.55284956842, 4598.333241679938, 4644.071185762861, 4660.673752837269, 4852.51474852519, 4868.196747536758, 4869.805207791719, 4870.610351171968, 4937.259411088366, 4998.550130488274, 5034.899302014012, 5076.416212135652, 5081.288619593334, 5133.717645529382, 4781.402324709411, 4801.855944321676, 4808.63935204868, 4853.757312134449, 4874.4150701916, 4938.7530623467965, 4982.825601103944, 4988.451270360185, 4990.5508504234795, 5056.064628043824, 4931.457539670561, 5046.142155833591, 5075.54320111177, 5079.3650793650795, 5086.6439349246675, 4971.627127965345, 4974.626902982219, 4974.8756281093265, 4978.775955082103, 5008.4240312364545, 4970.080535101018, 4973.602111831117, 4976.676516026447, 4994.450610432777, 5026.145555255688, 4797.496262892346, 4924.679895806761, 4957.553820156203, 4962.354893863775, 4963.429205641253, 5082.009008243357, 5088.796448142181, 5091.490850914879, 5092.185250359925, 5121.8463446096675, 4976.427710813303, 4981.252812078217, 4982.128127577587, 4983.27742477346, 5025.773840177274], "value": 4685.407132464048, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.7950958145486, 555.908275134603, 566.6401834834878, 567.3447168520357, 579.2556564814215, 27712.86143569282, 30819.0, 30923.0, 30947.0, 30967.2258193545, 29991.700082999163, 42682.359764603556, 43532.34701479472, 43535.0, 43593.56406435934, 7656.693732250717, 7784.961075194616, 7787.0, 7787.026621672291, 7790.493617914845, 7498.587577683225, 7574.469787114904, 7586.962065189667, 7587.544747315151, 7594.5063570868, 7246.528975616595, 7431.554106753585, 7436.0, 7633.541987480741, 9761.707148785548, 9705.708828735114, 10052.64815731446, 10063.245256605747, 10069.647562335284, 10076.09315161639, 9843.114119729258, 9926.2059035277, 9928.404295742206, 9941.254405919584, 9942.204623630092, 37371.62628373709, 37657.62342376568, 37666.0, 37671.81164094162, 37682.0, 2950.129711735043, 3136.0, 3255.886043988473, 3625.365561026836, 3652.196516766307, 28396.29009274764, 28521.85739071312, 28540.571891421645, 28548.429031419462, 28652.0, 3482.3035392921265, 3608.4948107265004, 3622.094476380905, 3676.191237927652, 3738.532683414573, 255108.0, 255337.0, 256190.0, 256250.0, 259086.0, 30614.0, 31662.0, 51202.23196652053, 51477.7426112867, 51670.0, 32101.67898320987, 32105.51841722376, 32125.196870078435, 32211.0, 32228.516572251436, 12623.242605443702, 12757.107002509922, 12768.6807829805, 12775.169613975062, 12775.169613975248, 10431.165506759593, 10504.947475262576, 10505.422201778976, 10508.054275115124, 10509.84235236472, 7407.444441666897, 7438.925610743931, 7475.439342049368, 7478.85042299151, 7509.699612015458, 2847.8860845565946, 2919.5256395520128, 2970.777191710587, 3076.615423072116, 3078.799878007967, 798.3134504326275, 840.1766269056359, 848.7708318754026, 912.1744820937063, 953.609020301673, 25647.358816029748, 26864.462710745676, 27416.862915685295, 27449.17652470429, 29140.562891556645, 874.5146443723668, 898.8067565473455, 901.5402144906145, 908.8598890832159, 1000.5197505197546, 13670.316484175752, 14256.42974281017, 14357.856421435652, 14520.273986300643, 14630.707385852224, 387455.0, 390064.0, 390891.0, 391001.0, 391131.0, 47432.0, 47715.522844771804, 47905.52094479011, 47978.76010619925, 48051.27923081157, 1022.3405903192399, 1086.2070688397357, 1140.3101123820136, 1153.204289040576, 1169.5731058163879, 26635.46729065369, 28017.43965120727, 28507.28731781722, 28570.714292856806, 28806.71193288124, 40823.79588102041, 41696.0, 41736.37395439132, 41769.58230417657, 41810.790946045076, 306871.0, 307067.0, 311309.0, 633497.0, 644181.0, 36697.63302366942, 37133.62866371302, 37526.2494750098, 37567.06082347854, 37998.43002355022, 40735.555866648094, 41053.79473102615, 41674.58325416707, 41785.58214417939, 41905.37141942812, 34660.65339346574, 35111.82444087763, 35541.466877997365, 35556.82221588876, 35695.46456803202, 34560.308793824486, 34643.48034779531, 34723.82638086793, 34868.0, 35223.82388088043, 33913.66086339105, 34230.82884585561, 34578.135546611535, 34660.0, 35149.64850351566, 29597.26006849846, 31970.52044219385, 32054.358912821146, 32101.839490802395, 32296.192595185308, 24314.51370972606, 24474.75525244796, 24596.50806983886, 24720.629190561795, 24840.627390589518], "value": 16548.25780835459, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.73359935040037, 365.48466662006575, 368.7842612071937, 373.30006238303184, 390.6074395232791, 12305.569305074325, 15551.688966220683, 15858.20708964553, 15877.603059923493, 16755.078470684108, 16051.197440128006, 16364.83635163648, 16396.344146234165, 16991.23539440725, 17141.314347426087, 4357.237483440401, 4952.801887924478, 5106.259592359106, 5130.820421285256, 5161.535461808437, 5068.594512439015, 5141.4087379951225, 5143.459936706629, 5146.845594632167, 5155.407128180251, 4906.828261010865, 5046.671966322196, 5074.5432911038, 5111.13110771168, 5137.407403704845, 5036.722980236085, 5071.1632580624155, 5075.796968121271, 5080.085584594772, 5083.517065878736, 4314.288142456491, 4422.402975598286, 4464.374987501752, 4531.56950089741, 4532.29749388842, 20050.59898802016, 20255.89872050645, 20330.28843990468, 20441.79558204414, 20587.48531286715, 1799.7300404939233, 1927.3543362973421, 2289.072925465173, 2344.2615576093476, 2418.7460316666675, 12789.680257993532, 13150.0, 13365.264910429922, 13712.725745485133, 13918.58244252674, 1734.6357264974245, 1739.7390391441259, 1746.266568041413, 1750.8686848486414, 1752.6144248265362, 69520.0, 71980.99999999948, 72378.1506319961, 72761.0, 73345.26654733438, 8549.316054715608, 8701.608427620773, 9489.572969216402, 9714.22286217101, 9956.303058785961, 13360.599182024554, 13378.464861405435, 13379.732405351839, 13396.459407168106, 13399.397027133607, 6990.685419156151, 7103.325184107502, 7192.892106618405, 7299.23358047408, 7369.8157546061775, 6854.760083397057, 6896.103506544117, 6919.861602767916, 6941.68762405693, 7112.288771122846, 5936.821895343147, 6003.459688628044, 6022.156898034279, 6082.75668973245, 6202.503799696014, 1701.5575950253024, 1737.3137610643726, 1737.6264103217868, 1747.100243374663, 1791.3282519055354, 791.3313250303485, 808.9110197878229, 839.9034111077304, 958.2860768727203, 968.7674958010168, 15987.840121598867, 16860.30936529496, 17341.479755607354, 17937.910310448366, 18039.819601803814, 872.4285592936636, 879.2174964281794, 891.5779331965505, 926.5182105305204, 934.8083642853198, 9783.315167938319, 9994.400335979864, 10129.189664826796, 10133.797324053477, 10152.0, 374263.0, 375719.0, 377373.0, 398375.0, 402185.0, 73381.0, 87857.56071219603, 88046.0, 88461.55769221242, 88471.0, 433.5252898076601, 449.7728647033293, 450.59446498151925, 452.298936648194, 452.728362982208, 9882.25883058759, 10190.592376304865, 10528.157747380345, 10539.841902371623, 10662.306950048222, 36365.45451818278, 37781.622183777814, 38147.0, 38579.0, 39011.80494097511, 53526.73236633948, 55733.721331393084, 57069.0, 58359.4164058354, 59543.809123818144, 7385.781346078052, 7752.1472638008645, 7825.452218344662, 7848.489848159851, 7975.72084977023, 10837.729056773644, 11438.0, 11652.883471165518, 12111.394430278628, 12542.749145016865, 9818.410895346302, 9998.500074996076, 10083.445410502492, 10308.381497110197, 10937.289076210021, 7636.12184598756, 7705.768826935201, 8241.79395515117, 10389.584416623251, 10606.363618182933, 7681.231876812274, 7778.572178530008, 7863.370930325675, 7928.524288542706, 8035.236652518001, 9987.350822196535, 10200.234982376203, 10727.195460340661, 10860.837087443853, 11686.70783230426, 10848.945755271174, 11122.221444498808, 11218.439078046229, 11258.493367798632, 11592.362420066976], "value": 8077.226050361938, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1196.0790191552499, 1319.736052789442, 1353.6412850594593, 1396.3855903402502, 1642.433360490631, 7183.0, 7481.738139165131, 7541.547507149575, 7738.342240909516, 7750.341220996208, 7642.121156067054, 7735.9226407735905, 7741.7677469676, 7745.3029227369525, 7769.300762931335, 2144.828413726898, 2169.2949791317806, 2170.6092903277404, 2173.293679554143, 2178.553396553703, 2143.710599069121, 2191.7260342457193, 2210.4805370737863, 2214.5460180662926, 2214.944626384345, 2133.807957283844, 2148.677698345245, 2168.533765240473, 2171.0664414301846, 2177.825773938081, 2107.1360742095744, 2119.9788002119935, 2122.4693826543366, 2122.957540849174, 2125.798049185325, 2075.6990236415636, 2138.5402138540135, 2139.475828422031, 2139.5934772393193, 2142.3144593730012, 7551.773446796605, 7578.242175782433, 7702.768916932501, 7706.460547761659, 7706.961465192639, 4463.509014008457, 4659.161350956826, 4662.930056049162, 4666.23007203811, 4667.813287468532, 2747.4779791839687, 2873.310405502685, 2922.547005214195, 2927.0340787540063, 2930.033089080598, 928.3362395886974, 933.3373304953491, 951.7810903492166, 952.9475878826662, 961.8749562556893, 10430.634927777568, 10516.475111108846, 10525.736856578571, 10529.262951593315, 10529.578816847317, 3978.0850404407147, 4128.8348466061625, 4132.648724858421, 4151.626353628188, 4357.891052723644, 8324.376746534368, 8433.40966132377, 8468.195521425454, 8473.449225800303, 8476.957615211884, 4622.329762184464, 4623.722576645412, 4627.907441851212, 4628.814847406066, 4631.698939568917, 3608.1881576645046, 4025.7785821780085, 4224.809883555247, 4298.333758267474, 4304.332828411601, 3356.2280675444786, 3375.4936759486272, 3520.2607452435277, 3523.9119022024656, 3536.328097661436, 1025.251566356549, 1051.069803224152, 1064.6167379743285, 1065.3980501016902, 1075.1076606416655, 807.100083406997, 831.3203410595834, 837.7486753973783, 838.1409055717938, 866.2809867809702, 5082.669626474268, 5352.00987817255, 5437.809676661377, 5477.7261136943, 5616.0, 807.9434439589172, 841.2176675691543, 870.1820288928513, 879.5926517571962, 882.0, 3500.7374446916583, 3522.4011917974008, 3590.015083257582, 3647.908802279964, 3652.945205821915, 9568.186704130088, 9720.416774993522, 9779.951100244596, 9831.36096153762, 9832.852507212398, 9542.379745316532, 9686.903130968742, 9704.805903881883, 9729.318947673595, 9755.365901216399, 7234.96382518084, 7401.666924988388, 7408.296211859864, 7412.962935185397, 7417.517861338995, 5199.428062913001, 5247.265382846328, 5352.277442545206, 5357.437469065848, 5470.53500452466, 8801.503744363346, 8877.511736854534, 8974.19232269099, 9017.143371379709, 9058.139476749699, 9077.319201059814, 9134.177923986874, 9200.907990920005, 9202.815943681222, 9404.811903762024, 5579.46995035471, 6271.310155882758, 6375.4262116409745, 6537.5096867734155, 6545.298222462289, 7356.301151390609, 7654.349380302737, 7675.577843218453, 7677.078750549969, 7681.500702454323, 6532.346765323429, 6679.966600167162, 6725.764598239038, 6780.559263647847, 7032.5077244592385, 6624.9337506624315, 6779.220389655253, 6789.93210067893, 6995.475339349671, 7017.754378596725, 6296.212973378328, 6381.776637817654, 6407.135036769976, 6424.967875160594, 6444.291127976013, 4416.227160247008, 4420.646348292064, 4464.866054018385, 4600.88497787558, 4610.631149507964, 4620.976895115503, 4695.976520117378, 4700.623950084054, 4766.809327626995, 4951.554360107608], "value": 4101.574699973152, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.48024237221307, 296.6395829067683, 300.64523861843014, 302.2730333547818, 307.8260782657797, 4690.865656126462, 4770.284457331402, 4855.295982082596, 4886.657933944621, 4905.435874874391, 4783.2346824507995, 4786.760661966897, 4786.952130478694, 4807.3750412446425, 4820.060088282787, 3828.540575130974, 3832.2754760357952, 3838.6353296436932, 3850.249201405727, 3853.807309634521, 3598.7121030317644, 3630.0380399194237, 3843.48113004745, 3851.9807400962954, 3862.6523612874835, 3772.264408440355, 3780.1683629601443, 3784.7161462890253, 3788.1588808393703, 3802.0, 3761.22894806564, 3805.162864169878, 3813.4470501777364, 3814.4659747635355, 3828.1195325075387, 3390.677885600864, 3393.744314603588, 3395.405803984293, 3397.524346591479, 3399.6430374810534, 4532.342810292522, 4680.274557443602, 4701.7414042227665, 4706.152892479352, 4729.952700472985, 4366.737995720236, 4375.496817865923, 4375.8249670013165, 4380.518143004267, 4381.16757816014, 4880.560749532559, 5068.341115654941, 5069.594432445396, 5071.656011157046, 5086.974565127188, 3612.674859262679, 3615.331163734691, 3619.6742293193474, 3620.0, 3621.474886141521, 5221.39953905302, 5239.13554263546, 5265.578753699695, 5273.947260527384, 5288.10102282609, 3650.324689932371, 3677.6322367763546, 3678.0988657779017, 3683.7053035757076, 3685.8341374638208, 3626.1297288650535, 3697.574778900407, 3701.1857391373856, 3722.6091260417265, 3728.217074414349, 4229.323308270662, 4245.8513952011535, 4247.511536173318, 4265.786710664516, 4267.445232119804, 5011.774470148852, 5025.547700706954, 5045.091883461012, 5054.1407960646775, 5059.30513278056, 1208.4380762945273, 1222.896053835434, 1272.0840994483956, 1286.5882917466322, 1304.660788195076, 858.8448536718104, 866.8136350684633, 880.1198801198801, 881.2465342132513, 883.8011447424407, 4506.549345065468, 4509.571590698878, 4511.187986162522, 4514.864554063383, 4519.706219095749, 5070.822521211814, 5169.198774190007, 5171.689698618095, 5172.637915345889, 5173.5602473789395, 4442.466903971479, 4444.355568442557, 4444.3555884323005, 4447.777611119431, 4457.554244575581, 2283.2807665585287, 2388.4387169015267, 2399.7360290368047, 2413.263954493861, 2419.588669926117, 4316.647427152209, 4317.244482215599, 4327.199468098412, 4344.565543445693, 4358.76026818528, 4602.815887364535, 4610.861674149781, 4622.491525932145, 4632.189366860786, 4658.853620277955, 4088.9182216355507, 4249.638780703551, 4283.657307415399, 4308.224519586504, 4350.499692535335, 4458.955410445854, 4480.177488182424, 4521.389612402284, 4523.570260825217, 4540.954590454053, 4390.319500477431, 4404.361367601744, 4427.446569178853, 4437.733735975852, 4458.420405347284, 4090.570490098497, 4271.5514870938105, 4303.526612072732, 4326.783660816884, 4354.368616550646, 3340.8496617651776, 3349.933001340008, 3351.765376423627, 3354.228527438654, 3362.9831850840587, 4553.886152846206, 4556.2254416748565, 4563.361129441947, 4581.977090114528, 4969.850904472872, 4825.455854126786, 5018.673786203885, 5018.874528136826, 5039.798408063783, 5046.621503387334, 4979.427365852974, 4985.900281994413, 4990.4011518618, 4996.950030499794, 5008.9248661269385, 4824.49342818999, 4920.852374428773, 4943.2585112233455, 4974.676646017997, 4988.9251661225835, 5032.6225533084435, 5033.697978121324, 5039.445660977216, 5063.417706963599, 5067.822626208213, 4933.432655244549, 4953.678010929278, 4953.801847926043, 4971.254311853107, 4976.780688731284], "value": 3691.849973055271, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.4281304013348, 661.771688767375, 706.9681864316105, 721.7329588052415, 749.8050506868212, 13050.347482625866, 13969.092009019407, 14001.579952601425, 14042.578722638324, 14190.361433735481, 12879.484820607187, 12993.61019169424, 13004.284764337957, 13023.544175953844, 13028.80456793149, 580.4485738548395, 709.6770969209024, 712.1347562711305, 727.9126504819429, 744.0699126092385, 748.8951546783454, 749.2881762325785, 751.2675141736818, 752.2026651749136, 752.2627824731766, 785.1128225105623, 785.3638552772244, 785.83104632504, 791.936645068396, 794.5431376958243, 608.3004544773502, 739.5267029101352, 763.8815983522563, 766.7967988483057, 770.7726220764888, 554.2323881424217, 554.3237250554317, 554.5175697143511, 554.8252300525362, 554.977800887964, 12262.693432664166, 12307.384630768425, 12345.93827030859, 12413.0, 12421.0, 11992.400379980965, 12343.19769214998, 12738.363081845964, 12860.228386296852, 12982.415791289415, 11038.337699738042, 11260.380679062648, 11303.265287756269, 11488.310701357861, 11998.040156787436, 3746.4942232798494, 3791.696664266852, 3816.446615240802, 3881.3207688654657, 3887.436321733333, 63873.361266387205, 64556.0, 64576.677116614585, 64604.353956460305, 64819.0, 9863.75340616476, 10054.899451005542, 10085.445300508396, 10111.544980475895, 10131.594736210469, 19120.426387208405, 19146.71279930802, 19169.424917252225, 19196.80803191978, 19205.90397048006, 6545.541812073205, 6571.145751052428, 6676.632785196861, 6705.094812200388, 6751.189857217068, 3487.476878468199, 3544.8050357230086, 3593.586737525166, 3633.1280492682095, 3647.7811331320204, 2618.279973007438, 2676.7323267673078, 2678.4776968490955, 2682.906098286551, 2694.7439993200614, 471.9032598317337, 476.4140107667519, 484.41144010027983, 490.44579625023493, 491.29008582597805, 437.97732295091316, 483.74603333250326, 487.89998050399583, 489.6890474548616, 518.0338668383513, 7150.249223831423, 7633.770986870402, 7652.885206721904, 7672.808179795438, 7744.109427415807, 519.0967716173831, 538.1953978801716, 554.4732504121101, 556.4213218253052, 568.9630174038757, 3454.707808314337, 3522.7181825453904, 3525.1715846775987, 3694.51971243742, 3787.6780473659496, 59043.70478147582, 69484.0, 100102.0, 111932.0, 112709.43645281722, 88727.55636221777, 90384.5480772592, 93953.0, 95198.52400737951, 95969.0, 47687.0, 76508.23491765122, 77964.6101769499, 79735.0, 79868.0, 6885.931140688729, 7004.369606735215, 7278.490505664553, 7312.96343518279, 7399.260073992557, 43503.56496434995, 44757.0, 45839.54160458353, 45909.540904590525, 46475.0, 53063.73468132634, 53360.46639533555, 54682.72658636841, 62352.06471903016, 62722.96432570767, 10738.570457181937, 11003.878237237004, 11134.944325278322, 11245.269057511236, 11359.772804544029, 12383.798969097843, 12765.8085128725, 12820.423080961193, 12933.54732584355, 13069.477220911058, 11894.821577676168, 12043.698907527381, 12463.065270104596, 12630.87369126297, 12696.936515317364, 11636.88363116358, 12274.018078553872, 12472.750544988869, 12559.309237991998, 12675.676215989492, 10967.88570579623, 11321.603743868925, 11389.772204556028, 11495.540178393105, 11631.418429078682, 9014.0, 9196.264298855942, 9246.352755307063, 9330.206932410822, 9365.922918864419, 9803.852942206015, 10205.84691229647, 10281.228907832092, 10306.587736490457, 10369.63706270277], "value": 6395.415706565848, "unit": "score"}], "name": "dromaeo_css", "value": 6268.240014421467, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 122.0, 117.0, 115.0, 116.0, 114.0, 115.0, 115.0, 132.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 123.0, 151.0, 192.0, 123.0, 151.0, 151.0, 128.0, 183.0, 129.0], "value": 147.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 163.0, 162.0, 161.0, 160.0, 161.0, 161.0, 160.0, 159.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 79.0, 170.0, 77.0, 77.0, 77.0, 76.0, 170.0, 78.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 149.0, 83.0, 84.0, 83.0, 142.0, 83.0, 83.0, 138.0], "value": 101.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 97.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 65.0, 66.0, 68.0, 68.0, 64.0, 75.0, 66.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 66.0, 67.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 90.0, 82.0, 84.0, 82.0, 92.0, 82.0, 85.0, 82.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 132.0, 134.0, 142.0, 136.0, 131.0, 132.0, 133.0, 181.0, 136.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 173.0, 173.0, 174.0, 174.0, 173.0, 174.0, 180.0, 176.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 69.0, 70.0, 70.0, 69.0, 70.0, 73.0, 68.0, 67.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1583.1000000000001, "alertThreshold": 2.0}]} 10:42:21 INFO - Return code: 0 10:42:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:42:21 INFO - # TBPL SUCCESS # 10:42:21 INFO - Running post-action listener: _resource_record_post_action 10:42:21 INFO - [mozharness: 2016-09-01 17:42:21.689013Z] Finished run-tests step (success) 10:42:21 INFO - Running post-run listener: _resource_record_post_run 10:42:21 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 417808384; Read time: 24; Write time: 359252 10:42:21 INFO - TinderboxPrint: CPU usage
13.1% 10:42:21 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 10:42:21 INFO - TinderboxPrint: I/O write bytes / time
417,808,384 / 359,252 10:42:21 INFO - TinderboxPrint: CPU idle
8,339.0 (86.9%) 10:42:21 INFO - TinderboxPrint: CPU user
1,209.1 (12.6%) 10:42:21 INFO - TinderboxPrint: Swap in / out
0 / 0 10:42:21 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 125906944; Read time: 0; Write time: 128120 10:42:21 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 291901440; Read time: 0; Write time: 231132 10:42:22 INFO - Running post-run listener: _upload_blobber_files 10:42:22 INFO - Blob upload gear active. 10:42:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:42:22 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 10:42:22 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'] 10:42:22 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 10:42:22 INFO - (blobuploader) - INFO - Open directory for files ... 10:42:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:42:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:42:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:23 INFO - (blobuploader) - INFO - Done attempting. 10:42:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:42:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:42:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:24 INFO - (blobuploader) - INFO - Done attempting. 10:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:29 INFO - (blobuploader) - INFO - Done attempting. 10:42:29 INFO - (blobuploader) - INFO - Iteration through files over. 10:42:29 INFO - Return code: 0 10:42:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:42:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:42:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e2d1582556fc0d8677784a905e6f986a439d13a0c33707c614af915a908faf246eaa80b7f1a94a9b1d7da956645c477015d7325fa72c42dc6a25554dac23d5b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec0699ddaec3767dd73db63e50eb49afc60741650d655b5c2353292fd278783f25490d73c675c737ccb3924ddf439e3a42491ff654c06338e7235b9d494af00b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a495781244679d19b785f6ff6e8479b66d0d82c078912b08c258fc56f7248315b8a6e6320b28f8bb0b767075cc36266ad12dad37fb8f5ae4afc3fd90ba4dfa6"} 10:42:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:42:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:42:29 INFO - Contents: 10:42:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e2d1582556fc0d8677784a905e6f986a439d13a0c33707c614af915a908faf246eaa80b7f1a94a9b1d7da956645c477015d7325fa72c42dc6a25554dac23d5b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec0699ddaec3767dd73db63e50eb49afc60741650d655b5c2353292fd278783f25490d73c675c737ccb3924ddf439e3a42491ff654c06338e7235b9d494af00b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a495781244679d19b785f6ff6e8479b66d0d82c078912b08c258fc56f7248315b8a6e6320b28f8bb0b767075cc36266ad12dad37fb8f5ae4afc3fd90ba4dfa6"} 10:42:29 INFO - Running post-run listener: copy_logs_to_upload_dir 10:42:29 INFO - Copying logs to upload dir... 10:42:29 INFO - mkdir: /builds/slave/test/build/upload/logs 10:42:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.866974 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-01 10:42:29.677964) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 10:42:29.681529) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718 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/e2d1582556fc0d8677784a905e6f986a439d13a0c33707c614af915a908faf246eaa80b7f1a94a9b1d7da956645c477015d7325fa72c42dc6a25554dac23d5b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec0699ddaec3767dd73db63e50eb49afc60741650d655b5c2353292fd278783f25490d73c675c737ccb3924ddf439e3a42491ff654c06338e7235b9d494af00b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a495781244679d19b785f6ff6e8479b66d0d82c078912b08c258fc56f7248315b8a6e6320b28f8bb0b767075cc36266ad12dad37fb8f5ae4afc3fd90ba4dfa6"} build_url:https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/Qu05Qm1WS-6w9SWuVOpqjA/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/e2d1582556fc0d8677784a905e6f986a439d13a0c33707c614af915a908faf246eaa80b7f1a94a9b1d7da956645c477015d7325fa72c42dc6a25554dac23d5b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec0699ddaec3767dd73db63e50eb49afc60741650d655b5c2353292fd278783f25490d73c675c737ccb3924ddf439e3a42491ff654c06338e7235b9d494af00b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a495781244679d19b785f6ff6e8479b66d0d82c078912b08c258fc56f7248315b8a6e6320b28f8bb0b767075cc36266ad12dad37fb8f5ae4afc3fd90ba4dfa6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 10:42:29.707112) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:42:29.707442) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472750500.324247-1301567718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 10:42:29.790218) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 10:42:29.790573) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 10:42:29.790880) ========= ========= Total master_lag: 0.17 =========