builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-067 starttime: 1470755436.74 results: success (0) buildid: 20160809064620 builduid: df3f0aad8c01402a91b882437a2037c2 revision: 6cf0089510fad8deb866136f5b92bbced9498447 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.740007) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.740485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.740763) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.755487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.755763) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-09 08:10:37-- 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% 38.4M=0s 2016-08-09 08:10:37 (38.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140934 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.910344) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.910674) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019943 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.960013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:36.960373) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6cf0089510fad8deb866136f5b92bbced9498447 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6cf0089510fad8deb866136f5b92bbced9498447 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-09 08:10:37,264 truncating revision to first 12 chars 2016-08-09 08:10:37,264 Setting DEBUG logging. 2016-08-09 08:10:37,264 attempt 1/10 2016-08-09 08:10:37,264 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6cf0089510fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 08:10:37,555 unpacking tar archive at: mozilla-central-6cf0089510fa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619399 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:37.593719) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:37.594047) ========= script_repo_revision: 6cf0089510fad8deb866136f5b92bbced9498447 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:37.594468) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:37.594742) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:10:37.603337) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-09 08:10:37.603623) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:10:37 INFO - MultiFileLogger online at 20160809 08:10:37 in /builds/slave/test 08:10:37 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 08:10:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:10:37 INFO - {'append_to_log': False, 08:10:37 INFO - 'base_work_dir': '/builds/slave/test', 08:10:37 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:10:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:10:37 INFO - 'branch': 'Firefox-Non-PGO', 08:10:37 INFO - 'buildbot_json_path': 'buildprops.json', 08:10:37 INFO - 'config_files': ('talos/linux_config.py',), 08:10:37 INFO - 'default_actions': ('clobber', 08:10:37 INFO - 'read-buildbot-config', 08:10:37 INFO - 'download-and-extract', 08:10:37 INFO - 'populate-webroot', 08:10:37 INFO - 'create-virtualenv', 08:10:37 INFO - 'install', 08:10:37 INFO - 'run-tests'), 08:10:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:10:37 INFO - 'download_minidump_stackwalk': True, 08:10:37 INFO - 'download_symbols': 'ondemand', 08:10:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:10:37 INFO - 'tooltool.py': '/tools/tooltool.py', 08:10:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:10:37 INFO - '/tools/misc-python/virtualenv.py')}, 08:10:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:10:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:10:37 INFO - 'installer_path': 'installer.exe', 08:10:37 INFO - 'log_level': 'info', 08:10:37 INFO - 'log_name': 'talos', 08:10:37 INFO - 'log_to_console': True, 08:10:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:10:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:10:37 INFO - 'opt_config_files': (), 08:10:37 INFO - 'pip_index': False, 08:10:37 INFO - 'sps_profile': False, 08:10:37 INFO - 'sps_profile_interval': 0, 08:10:37 INFO - 'suite': 'dromaeojs', 08:10:37 INFO - 'system_bits': '32', 08:10:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:10:37 INFO - 'title': 'talos-linux64-ix-067', 08:10:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:10:37 INFO - 'use_talos_json': True, 08:10:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:10:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:10:37 INFO - 'work_dir': 'build'} 08:10:37 INFO - [mozharness: 2016-08-09 15:10:37.998881Z] Running clobber step. 08:10:37 INFO - Running pre-action listener: _resource_record_pre_action 08:10:37 INFO - Running main action method: clobber 08:10:37 INFO - rmtree: /builds/slave/test/build 08:10:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:10:38 INFO - Running post-action listener: _resource_record_post_action 08:10:38 INFO - [mozharness: 2016-08-09 15:10:38.479217Z] Finished clobber step (success) 08:10:38 INFO - [mozharness: 2016-08-09 15:10:38.479318Z] Running read-buildbot-config step. 08:10:38 INFO - Running pre-action listener: _resource_record_pre_action 08:10:38 INFO - Running main action method: read_buildbot_config 08:10:38 INFO - Using buildbot properties: 08:10:38 INFO - { 08:10:38 INFO - "project": "", 08:10:38 INFO - "product": "firefox", 08:10:38 INFO - "script_repo_revision": "production", 08:10:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:10:38 INFO - "repository": "", 08:10:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:10:38 INFO - "buildid": "20160809064620", 08:10:38 INFO - "pgo_build": "False", 08:10:38 INFO - "basedir": "/builds/slave/test", 08:10:38 INFO - "buildnumber": 590, 08:10:38 INFO - "slavename": "talos-linux64-ix-067", 08:10:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:10:38 INFO - "platform": "ubuntu64_hw", 08:10:38 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:10:38 INFO - "branch": "mozilla-central", 08:10:38 INFO - "repo_path": "mozilla-central", 08:10:38 INFO - "revision": "6cf0089510fad8deb866136f5b92bbced9498447", 08:10:38 INFO - "stage_platform": "linux64", 08:10:38 INFO - "builduid": "df3f0aad8c01402a91b882437a2037c2", 08:10:38 INFO - "slavebuilddir": "test" 08:10:38 INFO - } 08:10:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:10:38 INFO - Running post-action listener: _resource_record_post_action 08:10:38 INFO - [mozharness: 2016-08-09 15:10:38.481757Z] Finished read-buildbot-config step (success) 08:10:38 INFO - [mozharness: 2016-08-09 15:10:38.481846Z] Running download-and-extract step. 08:10:38 INFO - Running pre-action listener: _resource_record_pre_action 08:10:38 INFO - Running main action method: download_and_extract 08:10:38 INFO - mkdir: /builds/slave/test/build/tests 08:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:38 INFO - trying https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:10:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:39 INFO - Downloaded 1361 bytes. 08:10:39 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:10:39 INFO - Using the following test package requirements: 08:10:39 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:39 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:10:39 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:10:39 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'jsshell-linux-x86_64.zip'], 08:10:39 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:10:39 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:39 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:10:39 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:10:39 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:10:39 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:39 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:10:39 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:10:39 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')]} 08:10:39 INFO - trying https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:10:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:39 INFO - Downloaded 20259633 bytes. 08:10:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:10:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:10:41 INFO - Return code: 0 08:10:41 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 08:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:41 INFO - trying https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:10:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:41 INFO - Downloaded 20259633 bytes. 08:10:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:10:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:10:43 INFO - Return code: 0 08:10: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')]} 08:10:43 INFO - trying https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:43 INFO - Downloaded 14413723 bytes. 08:10:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:10:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:10:44 INFO - Return code: 0 08:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:44 INFO - trying https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:10:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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 08:10:45 INFO - Downloaded 55612872 bytes. 08:10:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:10:45 INFO - mkdir: /builds/slave/test/properties 08:10:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:10:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:10:45 INFO - Contents: 08:10:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:10:45 INFO - Running post-action listener: _resource_record_post_action 08:10:45 INFO - Running post-action listener: set_extra_try_arguments 08:10:45 INFO - [mozharness: 2016-08-09 15:10:45.921943Z] Finished download-and-extract step (success) 08:10:45 INFO - [mozharness: 2016-08-09 15:10:45.922174Z] Running populate-webroot step. 08:10:45 INFO - Running pre-action listener: _resource_record_pre_action 08:10:45 INFO - Running main action method: populate_webroot 08:10:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:10:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:10:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:10:45 INFO - '--tppagecycles', 08:10:45 INFO - '7'], 08:10:45 INFO - 'tests': ['tsvgm']}}, 08:10:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:10:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:10:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:10:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:10:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['damp', 'tps']}, 08:10:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'tests': ['damp', 'tps']}, 08:10:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['dromaeo_dom']}, 08:10:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:10:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['basic_compositor_video']}, 08:10:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:10:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other-e10s': {'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['a11yr', 08:10:45 INFO - 'ts_paint', 08:10:45 INFO - 'tpaint', 08:10:45 INFO - 'sessionrestore', 08:10:45 INFO - 'sessionrestore_no_auto_restore', 08:10:45 INFO - 'tabpaint']}, 08:10:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tsvgx', 08:10:45 INFO - 'tsvgr_opacity', 08:10:45 INFO - 'tart', 08:10:45 INFO - 'tscrollx', 08:10:45 INFO - 'cart']}, 08:10:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:10:45 INFO - 'tsvgr_opacity', 08:10:45 INFO - 'tart', 08:10:45 INFO - 'tscrollx', 08:10:45 INFO - 'cart']}, 08:10:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s'], 08:10:45 INFO - 'tests': ['tp5o']}, 08:10:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'tests': ['tp5o']}, 08:10:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--disable-e10s', 08:10:45 INFO - '--xperf_path', 08:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:45 INFO - 'tests': ['tp5n']}, 08:10:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:45 INFO - 'talos_options': ['--xperf_path', 08:10:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:45 INFO - 'tests': ['tp5n']}}, 08:10:45 INFO - 'talos.zip': {'path': '', 08:10:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:10:45 INFO - Running post-action listener: _resource_record_post_action 08:10:45 INFO - [mozharness: 2016-08-09 15:10:45.940041Z] Finished populate-webroot step (success) 08:10:45 INFO - [mozharness: 2016-08-09 15:10:45.940129Z] Running create-virtualenv step. 08:10:45 INFO - Running pre-action listener: _resource_record_pre_action 08:10:45 INFO - Running main action method: create_virtualenv 08:10:45 INFO - Creating virtualenv /builds/slave/test/build/venv 08:10:45 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 08:10:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:10:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:10:46 INFO - Using real prefix '/usr' 08:10:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:10:46 INFO - Installing distribute.............................................................................................................................................................................................done. 08:10:48 INFO - Installing pip.................done. 08:10:48 INFO - Return code: 0 08:10:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:10: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')]} 08:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10: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')]} 08:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:48 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 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:10:48 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 08:10:48 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 08:10:48 INFO - Using env: {'DISPLAY': ':0', 08:10:48 INFO - 'HOME': '/home/cltbld', 08:10:48 INFO - 'LANG': 'en_US.UTF-8', 08:10:48 INFO - 'LANGUAGE': 'en_US:en', 08:10:48 INFO - 'LOGNAME': 'cltbld', 08:10:48 INFO - 'MAIL': '/var/mail/cltbld', 08:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:48 INFO - 'MOZ_NO_REMOTE': '1', 08:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:48 INFO - 'NO_EM_RESTART': '1', 08:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:48 INFO - 'PWD': '/builds/slave/test', 08:10:48 INFO - 'SHELL': '/bin/bash', 08:10:48 INFO - 'SHLVL': '1', 08:10:48 INFO - 'TERM': 'linux', 08:10:48 INFO - 'TMOUT': '86400', 08:10:48 INFO - 'USER': 'cltbld', 08:10:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:48 INFO - '_': '/tools/buildbot/bin/python'} 08:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:48 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 08:10:48 INFO - Cleaning up... 08:10:48 INFO - Return code: 0 08:10:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:10: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')]} 08:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10: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')]} 08:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:48 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 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:10:48 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 08:10:48 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 08:10:48 INFO - Using env: {'DISPLAY': ':0', 08:10:48 INFO - 'HOME': '/home/cltbld', 08:10:48 INFO - 'LANG': 'en_US.UTF-8', 08:10:48 INFO - 'LANGUAGE': 'en_US:en', 08:10:48 INFO - 'LOGNAME': 'cltbld', 08:10:48 INFO - 'MAIL': '/var/mail/cltbld', 08:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:48 INFO - 'MOZ_NO_REMOTE': '1', 08:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:48 INFO - 'NO_EM_RESTART': '1', 08:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:48 INFO - 'PWD': '/builds/slave/test', 08:10:48 INFO - 'SHELL': '/bin/bash', 08:10:48 INFO - 'SHLVL': '1', 08:10:48 INFO - 'TERM': 'linux', 08:10:48 INFO - 'TMOUT': '86400', 08:10:48 INFO - 'USER': 'cltbld', 08:10:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:48 INFO - '_': '/tools/buildbot/bin/python'} 08:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:48 INFO - Downloading/unpacking psutil>=3.1.1 08:10:48 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 08:10:48 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 08:10:48 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 08:10:48 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 08:10:48 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 08:10:48 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 08:10:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:10:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:50 INFO - Installing collected packages: psutil 08:10:50 INFO - Running setup.py install for psutil 08:10:51 INFO - building 'psutil._psutil_linux' extension 08:10:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:10:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:10:51 INFO - building 'psutil._psutil_posix' extension 08:10:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:10:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:10:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:51 INFO - Successfully installed psutil 08:10:51 INFO - Cleaning up... 08:10:51 INFO - Return code: 0 08:10:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:10:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:10:51 INFO - Using env: {'DISPLAY': ':0', 08:10:51 INFO - 'HOME': '/home/cltbld', 08:10:51 INFO - 'LANG': 'en_US.UTF-8', 08:10:51 INFO - 'LANGUAGE': 'en_US:en', 08:10:51 INFO - 'LOGNAME': 'cltbld', 08:10:51 INFO - 'MAIL': '/var/mail/cltbld', 08:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:51 INFO - 'MOZ_NO_REMOTE': '1', 08:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:51 INFO - 'NO_EM_RESTART': '1', 08:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:51 INFO - 'PWD': '/builds/slave/test', 08:10:51 INFO - 'SHELL': '/bin/bash', 08:10:51 INFO - 'SHLVL': '1', 08:10:51 INFO - 'TERM': 'linux', 08:10:51 INFO - 'TMOUT': '86400', 08:10:51 INFO - 'USER': 'cltbld', 08:10:51 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:51 INFO - '_': '/tools/buildbot/bin/python'} 08:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:10:51 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 08:10:51 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 08:10:51 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 08:10:51 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 08:10:51 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 08:10:51 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 08:10:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:10:53 INFO - Installing collected packages: mozsystemmonitor 08:10:53 INFO - Running setup.py install for mozsystemmonitor 08:10:53 INFO - Successfully installed mozsystemmonitor 08:10:53 INFO - Cleaning up... 08:10:53 INFO - Return code: 0 08:10:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:10: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')]} 08:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10: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')]} 08:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:53 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 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:10:53 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 08:10:53 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 08:10:53 INFO - Using env: {'DISPLAY': ':0', 08:10:53 INFO - 'HOME': '/home/cltbld', 08:10:53 INFO - 'LANG': 'en_US.UTF-8', 08:10:53 INFO - 'LANGUAGE': 'en_US:en', 08:10:53 INFO - 'LOGNAME': 'cltbld', 08:10:53 INFO - 'MAIL': '/var/mail/cltbld', 08:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:53 INFO - 'MOZ_NO_REMOTE': '1', 08:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:53 INFO - 'NO_EM_RESTART': '1', 08:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:53 INFO - 'PWD': '/builds/slave/test', 08:10:53 INFO - 'SHELL': '/bin/bash', 08:10:53 INFO - 'SHLVL': '1', 08:10:53 INFO - 'TERM': 'linux', 08:10:53 INFO - 'TMOUT': '86400', 08:10:53 INFO - 'USER': 'cltbld', 08:10:53 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:53 INFO - '_': '/tools/buildbot/bin/python'} 08:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:54 INFO - Downloading/unpacking jsonschema==2.5.1 08:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:10:55 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 08:10:55 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 08:10:55 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 08:10:55 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 08:10:55 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 08:10:55 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 08:10:56 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:56 INFO - warning: no files found matching '*.txt' 08:10:56 INFO - no previously-included directories found matching 'build' 08:10:56 INFO - no previously-included directories found matching 'dist' 08:10:56 INFO - no previously-included directories found matching '.git*' 08:10:56 INFO - Installing collected packages: jsonschema, functools32 08:10:56 INFO - Running setup.py install for functools32 08:10:56 INFO - warning: no files found matching '*.txt' 08:10:56 INFO - no previously-included directories found matching 'build' 08:10:56 INFO - no previously-included directories found matching 'dist' 08:10:56 INFO - no previously-included directories found matching '.git*' 08:10:56 INFO - Successfully installed jsonschema functools32 08:10:56 INFO - Cleaning up... 08:10:56 INFO - Return code: 0 08:10:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:10:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:10:56 INFO - Using env: {'DISPLAY': ':0', 08:10:56 INFO - 'HOME': '/home/cltbld', 08:10:56 INFO - 'LANG': 'en_US.UTF-8', 08:10:56 INFO - 'LANGUAGE': 'en_US:en', 08:10:56 INFO - 'LOGNAME': 'cltbld', 08:10:56 INFO - 'MAIL': '/var/mail/cltbld', 08:10:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:56 INFO - 'MOZ_NO_REMOTE': '1', 08:10:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:56 INFO - 'NO_EM_RESTART': '1', 08:10:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:56 INFO - 'PWD': '/builds/slave/test', 08:10:56 INFO - 'SHELL': '/bin/bash', 08:10:56 INFO - 'SHLVL': '1', 08:10:56 INFO - 'TERM': 'linux', 08:10:56 INFO - 'TMOUT': '86400', 08:10:56 INFO - 'USER': 'cltbld', 08:10:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:10:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:56 INFO - '_': '/tools/buildbot/bin/python'} 08:10:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:10:56 INFO - Cleaning up... 08:10:56 INFO - Return code: 0 08:10:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:10:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:10:56 INFO - Using env: {'DISPLAY': ':0', 08:10:56 INFO - 'HOME': '/home/cltbld', 08:10:56 INFO - 'LANG': 'en_US.UTF-8', 08:10:56 INFO - 'LANGUAGE': 'en_US:en', 08:10:56 INFO - 'LOGNAME': 'cltbld', 08:10:56 INFO - 'MAIL': '/var/mail/cltbld', 08:10:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:56 INFO - 'MOZ_NO_REMOTE': '1', 08:10:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:56 INFO - 'NO_EM_RESTART': '1', 08:10:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:56 INFO - 'PWD': '/builds/slave/test', 08:10:56 INFO - 'SHELL': '/bin/bash', 08:10:56 INFO - 'SHLVL': '1', 08:10:56 INFO - 'TERM': 'linux', 08:10:56 INFO - 'TMOUT': '86400', 08:10:56 INFO - 'USER': 'cltbld', 08:10:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:10:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:56 INFO - '_': '/tools/buildbot/bin/python'} 08:10:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:56 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:58 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10:58 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 08:10:58 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 08:10:58 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 08:10:58 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 08:10:58 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 08:10:58 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 08:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:59 INFO - Downloading docopt-0.6.1.tar.gz 08:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:59 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:59 INFO - Running setup.py install for blobuploader 08:10:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:59 INFO - Running setup.py install for requests 08:10:59 INFO - Running setup.py install for docopt 08:11:00 INFO - Successfully installed blobuploader requests docopt 08:11:00 INFO - Cleaning up... 08:11:00 INFO - Return code: 0 08:11:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:11:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:00 INFO - Using env: {'DISPLAY': ':0', 08:11:00 INFO - 'HOME': '/home/cltbld', 08:11:00 INFO - 'LANG': 'en_US.UTF-8', 08:11:00 INFO - 'LANGUAGE': 'en_US:en', 08:11:00 INFO - 'LOGNAME': 'cltbld', 08:11:00 INFO - 'MAIL': '/var/mail/cltbld', 08:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:00 INFO - 'MOZ_NO_REMOTE': '1', 08:11:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:00 INFO - 'NO_EM_RESTART': '1', 08:11:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:00 INFO - 'PWD': '/builds/slave/test', 08:11:00 INFO - 'SHELL': '/bin/bash', 08:11:00 INFO - 'SHLVL': '1', 08:11:00 INFO - 'TERM': 'linux', 08:11:00 INFO - 'TMOUT': '86400', 08:11:00 INFO - 'USER': 'cltbld', 08:11:00 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:00 INFO - '_': '/tools/buildbot/bin/python'} 08:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:00 INFO - Running setup.py (path:/tmp/pip-15pDv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:00 INFO - Running setup.py (path:/tmp/pip-wxQG8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:00 INFO - Running setup.py (path:/tmp/pip-SttDDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:00 INFO - Running setup.py (path:/tmp/pip-oJ8_aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:00 INFO - Running setup.py (path:/tmp/pip-7diq0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:00 INFO - Running setup.py (path:/tmp/pip-D3QUPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:00 INFO - Running setup.py (path:/tmp/pip-HFzqos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:00 INFO - Running setup.py (path:/tmp/pip-QgY5iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:00 INFO - Running setup.py (path:/tmp/pip-NMdpYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:00 INFO - Running setup.py (path:/tmp/pip-HwgEHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:01 INFO - Running setup.py (path:/tmp/pip-QPocQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:01 INFO - Running setup.py (path:/tmp/pip-MZGNNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:01 INFO - Running setup.py (path:/tmp/pip-VVugSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:01 INFO - Running setup.py (path:/tmp/pip-xRz8Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:01 INFO - Running setup.py (path:/tmp/pip-QUxzPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:01 INFO - Running setup.py (path:/tmp/pip-2xJ7Kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:01 INFO - Running setup.py (path:/tmp/pip-HO2cCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:11:01 INFO - Running setup.py install for manifestparser 08:11:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Running setup.py install for mozcrash 08:11:01 INFO - Running setup.py install for mozdebug 08:11:01 INFO - Running setup.py install for mozdevice 08:11:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:11:01 INFO - Running setup.py install for mozfile 08:11:02 INFO - Running setup.py install for mozhttpd 08:11:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Running setup.py install for mozinfo 08:11:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Running setup.py install for mozInstall 08:11:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Running setup.py install for mozleak 08:11:02 INFO - Running setup.py install for mozlog 08:11:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Running setup.py install for moznetwork 08:11:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Running setup.py install for mozprocess 08:11:02 INFO - Running setup.py install for mozprofile 08:11:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Running setup.py install for mozrunner 08:11:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:11:02 INFO - Running setup.py install for mozscreenshot 08:11:03 INFO - Running setup.py install for moztest 08:11:03 INFO - Running setup.py install for mozversion 08:11:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:11:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:11:03 INFO - Cleaning up... 08:11:03 INFO - Return code: 0 08:11:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:11:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:03 INFO - Using env: {'DISPLAY': ':0', 08:11:03 INFO - 'HOME': '/home/cltbld', 08:11:03 INFO - 'LANG': 'en_US.UTF-8', 08:11:03 INFO - 'LANGUAGE': 'en_US:en', 08:11:03 INFO - 'LOGNAME': 'cltbld', 08:11:03 INFO - 'MAIL': '/var/mail/cltbld', 08:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:03 INFO - 'MOZ_NO_REMOTE': '1', 08:11:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:03 INFO - 'NO_EM_RESTART': '1', 08:11:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:03 INFO - 'PWD': '/builds/slave/test', 08:11:03 INFO - 'SHELL': '/bin/bash', 08:11:03 INFO - 'SHLVL': '1', 08:11:03 INFO - 'TERM': 'linux', 08:11:03 INFO - 'TMOUT': '86400', 08:11:03 INFO - 'USER': 'cltbld', 08:11:03 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:03 INFO - '_': '/tools/buildbot/bin/python'} 08:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:03 INFO - Running setup.py (path:/tmp/pip-W703xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:03 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)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:03 INFO - Running setup.py (path:/tmp/pip-LIZ_lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:03 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)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:03 INFO - Running setup.py (path:/tmp/pip-TVz_iH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:03 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)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:03 INFO - Running setup.py (path:/tmp/pip-u6pKh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:03 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)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:03 INFO - Running setup.py (path:/tmp/pip-ItHz6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:03 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)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:03 INFO - Running setup.py (path:/tmp/pip-AZgYgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:03 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)) 08:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:03 INFO - Running setup.py (path:/tmp/pip-19iFBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:04 INFO - Running setup.py (path:/tmp/pip-f07VIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:04 INFO - Running setup.py (path:/tmp/pip-UJj2EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:04 INFO - Running setup.py (path:/tmp/pip-_nYE_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:04 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)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:04 INFO - Running setup.py (path:/tmp/pip-XMoZEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:04 INFO - Running setup.py (path:/tmp/pip-r_xEUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:04 INFO - Running setup.py (path:/tmp/pip-w9stis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:04 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)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:04 INFO - Running setup.py (path:/tmp/pip-hWhVn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:04 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)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:04 INFO - Running setup.py (path:/tmp/pip-VIeNLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:04 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)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:04 INFO - Running setup.py (path:/tmp/pip-OW_3GT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:04 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)) 08:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:04 INFO - Running setup.py (path:/tmp/pip-ZTQyOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:04 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)) 08:11:04 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)) 08:11:04 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)) 08:11:04 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)) 08:11:04 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)) 08:11:04 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)) 08:11:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11:06 INFO - Downloading blessings-1.6.tar.gz 08:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:06 INFO - Installing collected packages: blessings 08:11:06 INFO - Running setup.py install for blessings 08:11:06 INFO - Successfully installed blessings 08:11:06 INFO - Cleaning up... 08:11:06 INFO - Return code: 0 08:11:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:11:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:07 INFO - Reading from file tmpfile_stdout 08:11:07 INFO - Current package versions: 08:11:07 INFO - argparse == 1.2.1 08:11:07 INFO - blessings == 1.6 08:11:07 INFO - blobuploader == 1.2.4 08:11:07 INFO - docopt == 0.6.1 08:11:07 INFO - functools32 == 3.2.3-2 08:11:07 INFO - jsonschema == 2.5.1 08:11:07 INFO - manifestparser == 1.1 08:11:07 INFO - mozInstall == 1.12 08:11:07 INFO - mozcrash == 0.17 08:11:07 INFO - mozdebug == 0.1 08:11:07 INFO - mozdevice == 0.48 08:11:07 INFO - mozfile == 1.2 08:11:07 INFO - mozhttpd == 0.7 08:11:07 INFO - mozinfo == 0.9 08:11:07 INFO - mozleak == 0.1 08:11:07 INFO - mozlog == 3.3 08:11:07 INFO - moznetwork == 0.27 08:11:07 INFO - mozprocess == 0.23 08:11:07 INFO - mozprofile == 0.28 08:11:07 INFO - mozrunner == 6.12 08:11:07 INFO - mozscreenshot == 0.1 08:11:07 INFO - mozsystemmonitor == 0.3 08:11:07 INFO - moztest == 0.7 08:11:07 INFO - mozversion == 1.4 08:11:07 INFO - psutil == 3.1.1 08:11:07 INFO - requests == 1.2.3 08:11:07 INFO - wsgiref == 0.1.2 08:11:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:11:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:07 INFO - Using env: {'DISPLAY': ':0', 08:11:07 INFO - 'HOME': '/home/cltbld', 08:11:07 INFO - 'LANG': 'en_US.UTF-8', 08:11:07 INFO - 'LANGUAGE': 'en_US:en', 08:11:07 INFO - 'LOGNAME': 'cltbld', 08:11:07 INFO - 'MAIL': '/var/mail/cltbld', 08:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:07 INFO - 'MOZ_NO_REMOTE': '1', 08:11:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:07 INFO - 'NO_EM_RESTART': '1', 08:11:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:07 INFO - 'PWD': '/builds/slave/test', 08:11:07 INFO - 'SHELL': '/bin/bash', 08:11:07 INFO - 'SHLVL': '1', 08:11:07 INFO - 'TERM': 'linux', 08:11:07 INFO - 'TMOUT': '86400', 08:11:07 INFO - 'USER': 'cltbld', 08:11:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:07 INFO - '_': '/tools/buildbot/bin/python'} 08:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:11:07 INFO - Cleaning up... 08:11:07 INFO - Return code: 0 08:11:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x294b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', '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 08:11:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:11:07 INFO - Using env: {'DISPLAY': ':0', 08:11:07 INFO - 'HOME': '/home/cltbld', 08:11:07 INFO - 'LANG': 'en_US.UTF-8', 08:11:07 INFO - 'LANGUAGE': 'en_US:en', 08:11:07 INFO - 'LOGNAME': 'cltbld', 08:11:07 INFO - 'MAIL': '/var/mail/cltbld', 08:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:07 INFO - 'MOZ_NO_REMOTE': '1', 08:11:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:07 INFO - 'NO_EM_RESTART': '1', 08:11:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:07 INFO - 'PWD': '/builds/slave/test', 08:11:07 INFO - 'SHELL': '/bin/bash', 08:11:07 INFO - 'SHLVL': '1', 08:11:07 INFO - 'TERM': 'linux', 08:11:07 INFO - 'TMOUT': '86400', 08:11:07 INFO - 'USER': 'cltbld', 08:11:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:07 INFO - '_': '/tools/buildbot/bin/python'} 08:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:11:07 INFO - Cleaning up... 08:11:07 INFO - Return code: 0 08:11:07 INFO - Running post-action listener: _resource_record_post_action 08:11:07 INFO - Running post-action listener: _start_resource_monitoring 08:11:07 INFO - Starting resource monitoring. 08:11:07 INFO - [mozharness: 2016-08-09 15:11:07.582819Z] Finished create-virtualenv step (success) 08:11:07 INFO - [mozharness: 2016-08-09 15:11:07.583254Z] Running install step. 08:11:07 INFO - Running pre-action listener: _resource_record_pre_action 08:11:07 INFO - Running main action method: install 08:11:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:07 INFO - Reading from file tmpfile_stdout 08:11:07 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:11:07 INFO - Reading from file tmpfile_stdout 08:11:07 INFO - Output received: 08:11:07 INFO - Usage: mozinstall [options] installer 08:11:07 INFO - Options: 08:11:07 INFO - -h, --help show this help message and exit 08:11:07 INFO - -d DEST, --destination=DEST 08:11:07 INFO - Directory to install application into. [default: 08:11:07 INFO - "/builds/slave/test"] 08:11:07 INFO - --app=APP Application being installed. [default: firefox] 08:11:07 INFO - mkdir: /builds/slave/test/build/application 08:11:07 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'] 08:11:07 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 08:11:24 INFO - Reading from file tmpfile_stdout 08:11:24 INFO - Output received: 08:11:24 INFO - /builds/slave/test/build/application/firefox/firefox 08:11:24 INFO - Running post-action listener: _resource_record_post_action 08:11:24 INFO - [mozharness: 2016-08-09 15:11:24.005851Z] Finished install step (success) 08:11:24 INFO - [mozharness: 2016-08-09 15:11:24.006098Z] Running run-tests step. 08:11:24 INFO - Running pre-action listener: _resource_record_pre_action 08:11:24 INFO - Running main action method: run_tests 08:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:24 INFO - Python 2.7.3 08:11:24 INFO - Return code: 0 08:11:24 INFO - grabbing minidump binary from tooltool 08:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29493b0>, '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 0x294a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x294a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:11:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:11:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:11:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:24 INFO - Return code: 0 08:11:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:11:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:11:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:24 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/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 08:11:24 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/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 08:11:24 INFO - Using env: {'DISPLAY': ':0', 08:11:24 INFO - 'HOME': '/home/cltbld', 08:11:24 INFO - 'LANG': 'en_US.UTF-8', 08:11:24 INFO - 'LANGUAGE': 'en_US:en', 08:11:24 INFO - 'LOGNAME': 'cltbld', 08:11:24 INFO - 'MAIL': '/var/mail/cltbld', 08:11:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:24 INFO - 'MOZ_NO_REMOTE': '1', 08:11:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:24 INFO - 'NO_EM_RESTART': '1', 08:11:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:24 INFO - 'PWD': '/builds/slave/test', 08:11:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:24 INFO - 'SHELL': '/bin/bash', 08:11:24 INFO - 'SHLVL': '1', 08:11:24 INFO - 'TERM': 'linux', 08:11:24 INFO - 'TMOUT': '86400', 08:11:24 INFO - 'USER': 'cltbld', 08:11:24 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806', 08:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:24 INFO - '_': '/tools/buildbot/bin/python'} 08:11:24 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/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 08:11:24 INFO - mozversion application_buildid: 20160809064620 08:11:24 INFO - mozversion application_changeset: 6cf0089510fad8deb866136f5b92bbced9498447 08:11:24 INFO - mozversion application_display_name: Nightly 08:11:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:24 INFO - mozversion application_name: Firefox 08:11:24 INFO - mozversion application_remotingname: firefox 08:11:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:11:24 INFO - mozversion application_vendor: Mozilla 08:11:24 INFO - mozversion application_version: 51.0a1 08:11:24 INFO - mozversion platform_buildid: 20160809064620 08:11:24 INFO - mozversion platform_changeset: 6cf0089510fad8deb866136f5b92bbced9498447 08:11:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:11:24 INFO - mozversion platform_version: 51.0a1 08:11:24 INFO - using testdate: 1470755484 08:11:24 INFO - actual date: 1470755484 08:11:24 INFO - starting webserver on 'localhost:45773' 08:11:24 INFO - SUITE-START | Running 2 tests 08:11:24 INFO - TEST-START | dromaeo_css 08:11:24 INFO - operating with platform_type : linux_ 08:11:24 INFO - Initialising browser for dromaeo_css test... 08:11:24 INFO - TEST-INFO | started process 19966 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZcBO43/profile http://localhost:45773/getInfo.html) 08:11:28 INFO - PROCESS | 19966 | __metrics Screen width/height:1600/1200 08:11:28 INFO - PROCESS | 19966 | colorDepth:24 08:11:28 INFO - PROCESS | 19966 | Browser inner width/height: 1024/697 08:11:28 INFO - PROCESS | 19966 | __metrics 08:11:28 INFO - PROCESS | 19966 | 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" 08:11:28 INFO - PROCESS | 19966 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:29 INFO - TEST-INFO | 19966: exit 0 08:11:29 INFO - Browser initialized. 08:11:29 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:29 INFO - TEST-INFO | started process 20050 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZcBO43/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:41 INFO - PROCESS | 20050 | RSS: Main: 157057024 08:11:41 INFO - PROCESS | 20050 | 08:12:33 INFO - PROCESS | 20050 | 1470755553065 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] 08:14:45 INFO - PROCESS | 20050 | Cycle 1(1): loaded http://localhost:45773/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45773/tests/dromaeo/cssquery-ext.html) 08:14:46 INFO - PROCESS | 20050 | RSS: Main: 277569536 08:14:46 INFO - PROCESS | 20050 | 08:17:49 INFO - PROCESS | 20050 | Cycle 1(1): loaded http://localhost:45773/tests/dromaeo/cssquery-ext.html (next: http://localhost:45773/tests/dromaeo/cssquery-jquery.html) 08:17:49 INFO - PROCESS | 20050 | RSS: Main: 246284288 08:17:49 INFO - PROCESS | 20050 | 08:20:52 INFO - PROCESS | 20050 | Cycle 1(1): loaded http://localhost:45773/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45773/tests/dromaeo/cssquery-mootools.html) 08:20:52 INFO - PROCESS | 20050 | RSS: Main: 190390272 08:20:52 INFO - PROCESS | 20050 | 08:23:54 INFO - PROCESS | 20050 | Cycle 1(1): loaded http://localhost:45773/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45773/tests/dromaeo/cssquery-prototype.html) 08:23:55 INFO - PROCESS | 20050 | RSS: Main: 225402880 08:23:55 INFO - PROCESS | 20050 | 08:26:58 INFO - PROCESS | 20050 | Cycle 1(1): loaded http://localhost:45773/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45773/tests/dromaeo/cssquery-yui.html) 08:26:59 INFO - PROCESS | 20050 | RSS: Main: 189194240 08:26:59 INFO - PROCESS | 20050 | 08:30:01 INFO - PROCESS | 20050 | Cycle 1(1): loaded http://localhost:45773/tests/dromaeo/cssquery-yui.html (next: http://localhost:45773/tests/dromaeo/cssquery-dojo.html) 08:30:02 INFO - PROCESS | 20050 | RSS: Main: 273997824 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | __start_tp_report 08:30:02 INFO - PROCESS | 20050 | _x_x_mozilla_page_load 08:30:02 INFO - PROCESS | 20050 | _x_x_mozilla_page_load_details 08:30:02 INFO - PROCESS | 20050 | |i|pagename|runs| 08:30:02 INFO - PROCESS | 20050 | |0;dojo.html;2583.8062145339104;2635.235781623329;2669.9466010679785;2673.077788163083;2677.6385187999613;4549.135664223795;4576.199165146103;4746.169420351438;4803.039392121576;4830.082284365968;4673.1121086993535;4685.765711714418;4704.764761761907;4766;4785.210440277358;4248.638865696412;4271.359296105594;4322.0491491871735;4378.10248898975;4485.349624304474;4421.977890110545;4462.8884277892985;4486.865394038184;4513.729176249435;4519.028408892087;4203.264428724991;4233.682473814476;4233.957660423387;4364.8472303469225;4371.759553224571;4288.9142217155795;4303.956960430418;4306.784660766981;4312.6765492587865;4344.152890186415;4295.548967358413;4335.761533115678;4340.804663790105;4346.826126954918;4432.268675668511;4777.4267087949665;4829.734364609945;4865.3675022247235;4872.9512704873205;4878.07316609843;4816.397950256218;4940.308356830046;5217.478252174752;5218.921716174261;5228.111221092423;5061.038402703512;5083.491650834887;5088.898222035538;5109.182530795055;5149.510796474329;4782.402199725035;4785.832495862628;4792.281157826354;4807.543283388072;4808.134535783593;5003.324551185616;5098.362704661918;5144.819931302386;5226.712530810766;5230.712310822942;5002.874928126826;5017.096922553976;5019.648624596316;5019.82430614934;5025.045241404195;5030.89938201234;5032.874178145503;5036.219385995177;5078.060558796635;5079.1365467870555;4873.293372460973;4947.752612369367;4957.652964292537;4972.552470277692;5038.34501514808;4977.776000080005;5017.89964200714;5021.472745361758;5024.773885175103;5025.974870125626;4287.056847493546;4288.078063216423;4305.418768466279;4311.2239796836875;4335.197988372098;3682.723795715332;3787.962120378761;3799.9240015199543;3801.5248093988253;3809.3904975203836;4163.354680024601;4218.8523401680795;4244.702870799076;4345.608895199447;4346.869593912188;5139.691618502902;5142.6914385137015;5143.048536020848;5146.511081447256;5195.376554813447;4345.065810850682;4370.868873933787;4377.321515165155;4412.4705035395955;4422.3366495026;4356.825726970949;4530.886727831765;4558.772061396917;4584.816607335736;4630.259158534618;4778.307145463958;4781.13939490895;4783.210770222874;4812.8796780080775;4823.27650852375;5068.9239661405845;5099.082165210298;5103.311053007945;5112.284280200701;5118.360204974379;4568.725876447424;4678.134545109166;4714.504976977505;4715.952840471688;4822.807087716452;4980.676256043345;4998.300237966615;5003.849884503471;5005.399352077774;5043.268726034631;5072.67027643202;5074.467180946095;5077.847664570068;5080.034793389244;5114.488551144856;4742.051589682008;4920.40955085392;4921.729304888266;4950;4980.103581355391;4990.301357809837;4994.425641051181;4996.075725990703;4996.450390457119;5012.724300163526;4935.876603084952;4939.110960027229;4975.950240497694;4987.177115775857;4987.650864439454;4798.688085274446;4998.4501704813165;5003.974980125076;5067.721275329893;5072.949270507248;4891.26631005352;4893.168161412497;4893.779779909842;4912.828051018198;4946.406431228275 08:30:02 INFO - PROCESS | 20050 | |1;ext.html;567.9772809087636;575.1057106199859;577.9566532510057;588.0561698473948;593.285091464785;29847.552286715716;32332.35335293293;32362.83818580907;32375.51436728448;39147.80426097873;37549.93075934748;41481.792591037076;41497.58502414975;41592.92035398233;41617.583824161746;7719.536827790323;7889.644965976545;7890.723824666138;7891.329237014861;7893.565853878034;7416;7618.390528757714;7632.923670763277;7705.306522412981;7708.653110610036;7599.506032107895;7652.273034061783;9971.60113595459;10178.44018578978;10228.33515821473;10128.848067278997;10161.441120738356;10182.694519164437;10202.540885660126;10204.489775511269;10001.049900259537;10062;10072.848907266396;10085.546150423248;10088.899111008943;38484.230315393535;38739.80630096832;38929.22141557181;38971.80514097412;39005;3042.421939831447;3271.4765637497885;3369.3261347730554;3405.540252065964;3561.8397172127316;29111.56332655012;29322.560161597594;29626.407471850656;29771.851140744373;29826.10521684353;3292.42382583047;3313.96686033139;3408.522806807049;3413.7098346640564;3458.9827050864837;253114;255219;255971;255978;256310;52514.47485525172;52794.47205527972;52847;52891.73554132205;52925.2061219082;55674.443255567734;55795.72102139463;55856.441435585126;55869.441305587236;56005.1599226012;14255.928720356333;14423.062500937405;14433.278336083154;14433.566992990227;14441.49454769078;11170.162237832195;11179.944100279447;11183.72040698989;11185.720356990978;11195.384253866116;13408.530701425403;14741.484048058266;15027.248637568297;15206.695866082837;15422.228888555528;4972.57733092684;5982.46157845796;6147.692615369303;6232.158658581124;6336.89104406727;809.6478032056075;891.9018907920124;970.7912798748303;977.9119879210905;980.5440470181258;27420.862895685794;27857.86071069672;28254.43491130207;28401;28698.282542936595;951.7477868364889;954.1460392948272;972.7713987212999;995.7908839143861;1067.124957534814;14695.706085878222;15080.472183473745;15236.084472000497;15282.847171528143;15507.224638768243;392335;394277;396537;397559;397998;43417.56582434198;44925.775371123586;46312.073758525315;50730.49269507331;50955.23567146496;1195.438144072302;1196.126827415991;1216.0697066744124;1223.5962132496254;1226.45422786859;27176.45647087008;28285.151445456668;28579.571306430837;28868.27829304284;28963;40627.59372406238;41074.58925410708;41079;41084.1783164329;41105;287654.1790201559;550145;568390;568898;569997;24297;24488;24579.508409832062;24589;24731.62902556427;40161.39757903575;40554.18891622092;40924.38613420861;41151.38272925848;42033.36949945692;35312.82343588266;35512.822435887654;35778.28443431065;35873;36400.45399318959;35155;35451.113722157155;35751;35864.462033069;36058;34344.82827585846;34705.479417808245;34750.304993900485;34992.65007349894;35309;30599.694003060577;32620.347593047532;32749.34501309913;32880;32904.177395565304;24087.397815054763;24733.752662473144;24887.37781555403;25378.365540861625;25465.490690186463 08:30:02 INFO - PROCESS | 20050 | |2;jquery.html;334.15958863458394;341.1828670334549;359.123738079087;363.81445462813963;379.0901835594573;12436.253824770509;16095.919520402396;16355.836441635594;17991.64016719665;18032.909835450806;17314.653706925856;17430;17455.603551715863;17728.556786080353;17876.82123178768;5104.821331253397;5212.895742085156;5466.644668096561;5484.780608775664;5576.219329293902;3540.6813386795184;5617.157426386034;5664.065429204176;5666.5183459406;5808.1287806829105;6077.6353418795015;6125.448709616134;6130.540209484284;6140.478059364959;6156.630602163862;5915.112733090028;6031.125486804389;6072.51419886408;6080.209572755558;6148.692565371736;5405.945940540583;5411.107167317388;5412.323459567554;5415.810446634349;5418.458154184589;12236.143469957107;16624.418145364853;19725.11236994339;21002.684959725615;21160.470988225265;1745.589786400195;1923.6248931458372;1961.5880665060354;2010.6883433067899;2021.3632705697657;9317.534123293875;9620.903790962071;9804.166645835112;10020.64927727526;10108;1725.1288099509668;1729.152715169558;1770.7958588160045;1820.4811628685761;1858.27527264367;55950;59225.703871480364;66529.66735166294;67551;68449.3155068448;9946.502674866228;10254.718160229972;10259.435731034715;10460.581576736846;10616.681499555025;13816.171029738249;13816.963727720462;13847.723045539033;13865.445382184802;13988.300584970913;7084.504084714021;7201.819954501075;7249.420046396381;7299.5620262784405;7364.742234021891;6690.264070952191;6790.42281406086;6918.481113916477;6931.202911665122;6937.549059311129;4359.498657654411;4416;4436.800343984529;4547.363369128325;4548.385967894292;1705.8976461412356;1812.6918423867974;1882.2471011595198;1910.551020510179;1953.3553927203989;778.7040924448786;797.095296838093;837.7235512280932;842.5744998775579;894.1505569708803;16060.357585696675;17277.308907643805;17556.209970551357;17786.91106544459;18069.638607228044;822.8930239068883;860.8708693695995;874.7856775090144;882.6557642519423;894.9776255593662;9722.951385243028;9851.310408271496;10131;10195.490225488697;10247.538860751136;379921;387336;396725;399158;401552;45293.77353113279;45927.77036114798;46051.5394846054;51210.48789512132;51314.74342628263;883.3728053082383;887.4187407248263;893.2184338703504;917.1745429113718;919.0946917286467;11585.478653460446;14855.034422762486;15469.613259668147;16465.83534164643;16541.58646033859;35578.822105890344;35811.462828057076;35847.64152358547;36295.27409451743;39586.40620390754;51008.48991510038;51481.742591288305;52159;52213.7389313051;52253.47746522638;7891.565963872042;8026.959865200637;8065.274125328749;8333.583320833814;8544.102869198894;9174.54127293646;11800.17398782077;12489.625311240678;12777.552785652457;12869.871301286867;4940.901181976268;5335.573154147736;8094.271515563628;9338.252939764667;10114.393136411838;9778.41329520231;10679.145668346358;10901.618443354444;11185.496652650487;11195.944020279847;9592.280578956746;9950.303478756417;10039.598416063276;10223.693289201039;10263.846042309222;8095.797605059921;8108.83782324362;8232.094469608459;8325.25072743456;8434.915650843659;9807.362521436084;10090.69727908164;10111.241656875616;10371.792564148524;10501.684949451528 08:30:02 INFO - PROCESS | 20050 | |3;mootools.html;1344.5630170194684;1386.403846346071;1426.7217892510957;1598.3606557377054;1611.500434865192;6679.632620205887;7121.750738724146;7129.857402851946;7131.144262688472;7135.357817796397;6086.7565297388055;6891.1041564596535;7023.578585284874;7121.572705637665;7138.321859423347;2136.092160831648;2167.3281282802304;2167.3822960456273;2168.01355383301;2168.27362833451;2095.528506086126;2129.893505324735;2178.8039076483115;2182.650775875852;2185.1259496201487;1736.2620886123411;2097.4861159016064;2156.137544982012;2169.0998235732154;2185.8579192352445;2105.631514484974;2110.9894450527727;2131.0836340373635;2132;2133.5412886229537;2108.8734675919495;2109.0298462707174;2111.5037966077957;2113.7674855765854;2114.2917122763893;7270.709171633128;7298.9635051824935;7342.082239720035;7361.1166660001145;7374.815629609303;4247.91504169918;4350.2387082260475;4351.6518678505645;4363.149185908749;4366.541513141138;2690.1391554702504;2759.986200069007;2800.3979144484033;2812.535931571288;2815.6480439945008;859.1365677494141;903.5933829776561;924.9630014799467;926.1294383279695;927.3045216087934;9697.806043879084;9701.70894873155;9741.366811157252;9741.415515069119;9755.902440975571;3766.1902690921584;3933.272344616255;4002.699797515198;4095.610916962884;4102.118044620362;8350.457220280783;8363.456375335705;8363.95818020906;8364.289035431935;8368.58157092143;4395.934060989088;4397.934030989538;4398.846040388571;4405.823767049346;4409.559044095629;3968.5634580196156;4011.277969965376;4021.4369988201674;4021.5375231848125;4030.1536677298095;3482.181687303482;3566.0728210665416;3593.658602432765;3615.6926661233565;3616.475611036385;1157.9305241685527;1191.0352614382377;1198.2510930668332;1213.4478812140455;1213.4600102954278;587.8789684312911;750.557171268954;756.4175584799783;803.3211935914143;840.7982084299724;4797.376341075638;4874.8537543873745;4937.925931111036;4993.850184494471;5031.320771695848;837.7464620171719;850.3877208409934;856.4347530629755;857.5926434943352;881.1805021330224;3155.4477966355794;3401.40475416806;3410.5225268462436;3411.880584179542;3461.5326930864517;8393.328533717287;8580.227779499875;8609.354298427643;8686.522241276663;8697.608607612672;8618.482891026559;8620.698275560451;8620.913790862136;8621.353398495137;8622.439541429787;6640.20317561886;6767.695453704595;6771.289014653489;6773.72905083801;6809.38715515606;4811.9518804811505;4848;4863.513648635108;4922.680025798311;4943.629227807857;7995.760127196193;8045.839083218186;8050.83898322042;8096.352291816523;8099.635516401659;7950.443468957349;8013.2387423194705;8210.794730131794;8256.917430825615;8278.213569710868;6187.969060154671;6314.968425157845;6317.431431171217;6318.936810631835;6320.209973753281;6844.178698556205;6886.966954956596;6895.724171033103;6906.654667266516;6916.135483064617;5565.248691426606;5576.358718747234;5721.685307308138;5731.083026715705;5776.075827867521;6119.357467465852;6144.692765361625;6215.751369945332;6221.377862213923;6247.375262473716;5491.0939694951485;5629.043062679272;5631.155326701028;5633.4084921082695;5633.802816901389;4257.829686812493;4298.57768376934;4304.913901721885;4326.9134617306845;4368.519462859146;4326.048269380732;4334.068175342317;4419.535948725337;4466.0398014427055;4544.272916333371 08:30:02 INFO - PROCESS | 20050 | |4;prototype.html;288.6608235323494;297.510595071108;305.4883070856315;306.52794696167905;308.4186308807898;4835.806567737291;4843.80624775009;4850.666066831623;4868.880157563758;4937.284093806405;4565.794539245733;4694.4366675998845;4701.2478003519445;4709.811607535703;4876.14706411564;3692.7415080944343;3744.7004239660896;3758.4442579046236;3766.9623303766953;3838.3131807734558;3777.0746167189077;3793.184465339951;3799.25914446683;3822.9617703823023;3840;3663.7618554793985;3744.307303148926;3769.8690392882163;3789.450529673196;3794.6964242860645;3782.5224477966976;3788.90527736806;3805.1058001369656;3813.4089216171537;3821.445890345912;3387.271736576648;3390.677885600864;3394.4399174136483;3395.9490607640905;3397.252604427022;4713.457952335491;4727.881802954919;4730.361401210827;4732.905341893177;4733.881652958704;4169.437125987984;4331.350297455376;4343.370211319372;4346.348047792856;4369.584889435499;5020.2720605511995;5029.4216165141115;5044;5045.5459008689395;5046.318746969186;3710.2208536207427;3717.6282371762877;3718.1262403335195;3718.4050551911837;3723.2925744108534;5078.822241221539;5229.686218826844;5260.526552610283;5264.657797243167;5274.340707411574;3769.3026790043928;3793.867214647474;3795.5635101963076;3798.537563038195;3811;3846.7113516972126;3854.5374555053572;3875.8643447479203;3893.124047089382;3903.6681882040343;4336.783160841946;4347.478382566077;4361.563843615677;4397.2304846651705;4397.779121543664;4820.8794780130775;5056.140456122394;5057.266696329012;5067.1639179535705;5068.315777370008;1320.9867901321056;1338.444545513601;1338.9087893366875;1342.9597112086458;1346.4412268908488;850.6937502499095;897.7845317123974;917.2524392619994;929.6374413978554;943.0899182288987;4449.599536041772;4461.397711308997;4463.464384273908;4464.330350447459;4482.372467854503;5173.793048278102;5174.275601415805;5184.144616138295;5258.816766227568;5261.474492243745;4278.507971583245;4430.778461076933;4437.378766972626;4463.419755431773;4464.330350447459;2760.917172484829;2760.930976725598;2766.861656917146;2767.225176950457;2777.763890069367;4834.806607735722;4862.708237505761;4872.6832755870755;4876.634252431082;4877.68295060827;5064.328771505395;5170.302009228706;5198.792048318101;5264.131418316011;5271.762770675329;4844.927326090112;4858.732769697559;4859.927101093486;4862.80548778052;4865.7323847188745;5154.355705536808;5165.173572228426;5168.984096202559;5184.274201611853;5187.507186817246;4887.535684110004;5086.694798312114;5147.279380886604;5152.355955505562;5156.40701319338;4931.3096166536;4933.580645645016;4938.30863679078;4940.481249468754;4940.851774446773;3724.571674257495;3727.347714150067;3733.309337772521;3740.1023754299317;3743.3262012836863;5168.922466162935;5243.423223445342;5287.2597836302175;5300.867478313073;5320.388155362028;5097.541221290102;5098.388193416814;5106.744662766772;5110.795568177231;5118.517519003206;5071.13790655582;5096.541311282002;5102.515261050195;5130.358285348652;5136.640435169502;4930.359053323045;5025.271335656236;5041.117804384291;5051.4695956924;5052.595792336678;4897.289892965571;4979.775910083982;5116.616253781056;5149.742512874266;5169.4830516948;5045.183733855883;5045.596352291881;5065.8733531662;5067.721275329893;5072.442031376472 08:30:02 INFO - PROCESS | 20050 | |5;yui.html;663.3731124087737;683.3678847066462;698.8357735932052;729.686234918985;771.4869611708212;13477.730445391098;14898.478553250638;14913.254337283146;14915.701685966274;14958.775618365733;13659.385327660255;13731.862681373183;13750.587482375544;13766.793498097511;13792.586222413318;630.5334052800918;647.3817504283398;760.9771706848803;762.9427792915526;781.7967328494575;772.6793380747011;793.218679600593;793.9523628582275;794.6146119132217;801.5911884938694;847.6694089305176;849.6346570974479;849.9830003399928;850.0182289455657;850.7575341027808;655.1057806094694;788.9171636978092;835.7116794705817;835.8579041562949;836.640244694781;614.4439282449346;614.5728718540616;614.5943677173044;615.157234588612;615.2555407956378;13192.73614527704;13219.140755850838;13243.337833108306;13266.270355130464;13274.933625331909;12421.751564968741;12446.626601201979;12732.681682957907;13027.348632568333;13398.665033374145;11716.70708232301;11768.764624707543;11848.40757962107;11889;12132.211406258653;4092.1611069730634;4101.282275601788;4106.640008314229;4181.790910454495;4181.937270940938;65994;68804.6559767203;69487.65256173686;69706.65146674284;69748.6512567444;9802.215822734164;10162.644307449204;10353.482325883826;10390.272680912414;10461.633842815618;18296;18485;18660.81339186618;18683.626327473376;18704.25182992692;6545.574537655038;6795.048693182958;6878.312168783082;7049.506534542636;7137.643117844087;3808.5239345081864;3811.27585758705;3861.6524512793985;3893.5133108361456;3937.5274967004143;2753.655793025872;2848.9430211395656;2859.3566447549133;2866.4123854609757;2868.670102938147;481.94698583155827;487.98048078076476;501.60623910230294;503.5719638307409;504.699703676312;509.248857934551;517.2835622662565;562.1511517608359;571.402883986227;592.3306663470252;7458.216887226763;7840.33357164636;7922.286994170552;8027.357811375076;8204.548749818818;516.6590050566608;534.28939510451;552.3371953655629;554.6200852416108;566.6543408520799;3551.5560554930635;3604.747667663291;3693.353663108945;3721.8697345592773;3724.832382542792;104656;109120.4543977275;109387.45306273577;109784;110155;52373.73813130986;87514.68727969086;91154.63268050985;91854;94083;81158.59420702858;81905;82005.58997204975;82156.9999999988;83802.58098709467;7259.854802903807;7330.96334518324;7420.666070026754;7427.7771666850085;7701.1913749057785;47881.5211847891;48070.75964620154;49417;49683;49687.75156124196;52692.736536317076;52919.47080529299;53244;59438;63721;10574.788504230026;10690.73273168177;10704.30422022566;10712;11241.71895702614;12580.308083055153;12627.305498197687;12673.239605623692;13145.277009764555;13283.933580332037;10961.287516311415;11085.113190944867;11087.55649774;11238.719032024264;11814.645560633195;12482.313472759086;12544.811827822408;12991.480340786262;13073.477060917457;13572.79640805369;11607.883921160681;12061.75876482483;12220.327881966221;12725.490980360682;12738.490460381481;10331.690049298533;10593.894061059598;10638.679226495033;10642.946785266024;10697.786044279226;9013.278937684838;9234;9578.760530986503;9901.50492475359;9925.751856203653 08:30:02 INFO - PROCESS | 20050 | __end_tp_report 08:30:02 INFO - PROCESS | 20050 | __start_cc_report 08:30:02 INFO - PROCESS | 20050 | _x_x_mozilla_cycle_collect,1155 08:30:02 INFO - PROCESS | 20050 | __end_cc_report 08:30:02 INFO - PROCESS | 20050 | __startTimestamp1470756602123__endTimestamp 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | ------- Summary: start ------- 08:30:02 INFO - PROCESS | 20050 | Number of tests: 6 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | [#0] dojo.html Cycles:165 Average:4688.04 Median:4823.28 stddev:498.17 (10.3%) stddev-sans-first:471.55 08:30:02 INFO - PROCESS | 20050 | Values: 2583.8 2635.2 2669.9 2673.1 2677.6 4549.1 4576.2 4746.2 4803.0 4830.1 4673.1 4685.8 4704.8 4766.0 4785.2 4248.6 4271.4 4322.0 4378.1 4485.3 4422.0 4462.9 4486.9 4513.7 4519.0 4203.3 4233.7 4234.0 4364.8 4371.8 4288.9 4304.0 4306.8 4312.7 4344.2 4295.5 4335.8 4340.8 4346.8 4432.3 4777.4 4829.7 4865.4 4873.0 4878.1 4816.4 4940.3 5217.5 5218.9 5228.1 5061.0 5083.5 5088.9 5109.2 5149.5 4782.4 4785.8 4792.3 4807.5 4808.1 5003.3 5098.4 5144.8 5226.7 5230.7 5002.9 5017.1 5019.6 5019.8 5025.0 5030.9 5032.9 5036.2 5078.1 5079.1 4873.3 4947.8 4957.7 4972.6 5038.3 4977.8 5017.9 5021.5 5024.8 5026.0 4287.1 4288.1 4305.4 4311.2 4335.2 3682.7 3788.0 3799.9 3801.5 3809.4 4163.4 4218.9 4244.7 4345.6 4346.9 5139.7 5142.7 5143.0 5146.5 5195.4 4345.1 4370.9 4377.3 4412.5 4422.3 4356.8 4530.9 4558.8 4584.8 4630.3 4778.3 4781.1 4783.2 4812.9 4823.3 5068.9 5099.1 5103.3 5112.3 5118.4 4568.7 4678.1 4714.5 4716.0 4822.8 4980.7 4998.3 5003.8 5005.4 5043.3 5072.7 5074.5 5077.8 5080.0 5114.5 4742.1 4920.4 4921.7 4950.0 4980.1 4990.3 4994.4 4996.1 4996.5 5012.7 4935.9 4939.1 4976.0 4987.2 4987.7 4798.7 4998.5 5004.0 5067.7 5072.9 4891.3 4893.2 4893.8 4912.8 4946.4 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | [#1] ext.html Cycles:180 Average:52819.34 Median:35073.83 stddev:108096.49 (308.2%) stddev-sans-first:108328.54 08:30:02 INFO - PROCESS | 20050 | Values: 568.0 575.1 578.0 588.1 593.3 29847.6 32332.4 32362.8 32375.5 39147.8 37549.9 41481.8 41497.6 41592.9 41617.6 7719.5 7889.6 7890.7 7891.3 7893.6 7416.0 7618.4 7632.9 7705.3 7708.7 7599.5 7652.3 9971.6 10178.4 10228.3 10128.8 10161.4 10182.7 10202.5 10204.5 10001.0 10062.0 10072.8 10085.5 10088.9 38484.2 38739.8 38929.2 38971.8 39005.0 3042.4 3271.5 3369.3 3405.5 3561.8 29111.6 29322.6 29626.4 29771.9 29826.1 3292.4 3314.0 3408.5 3413.7 3459.0 253114.0 255219.0 255971.0 255978.0 256310.0 52514.5 52794.5 52847.0 52891.7 52925.2 55674.4 55795.7 55856.4 55869.4 56005.2 14255.9 14423.1 14433.3 14433.6 14441.5 11170.2 11179.9 11183.7 11185.7 11195.4 13408.5 14741.5 15027.2 15206.7 15422.2 4972.6 5982.5 6147.7 6232.2 6336.9 809.6 891.9 970.8 977.9 980.5 27420.9 27857.9 28254.4 28401.0 28698.3 951.7 954.1 972.8 995.8 1067.1 14695.7 15080.5 15236.1 15282.8 15507.2 392335.0 394277.0 396537.0 397559.0 397998.0 43417.6 44925.8 46312.1 50730.5 50955.2 1195.4 1196.1 1216.1 1223.6 1226.5 27176.5 28285.2 28579.6 28868.3 28963.0 40627.6 41074.6 41079.0 41084.2 41105.0 287654.2 550145.0 568390.0 568898.0 569997.0 24297.0 24488.0 24579.5 24589.0 24731.6 40161.4 40554.2 40924.4 41151.4 42033.4 35312.8 35512.8 35778.3 35873.0 36400.5 35155.0 35451.1 35751.0 35864.5 36058.0 34344.8 34705.5 34750.3 34992.7 35309.0 30599.7 32620.3 32749.3 32880.0 32904.2 24087.4 24733.8 24887.4 25378.4 25465.5 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | [#2] jquery.html Cycles:180 Average:23420.01 Median:4547.87 stddev:64298.90 (1413.8%) stddev-sans-first:64455.92 08:30:02 INFO - PROCESS | 20050 | Values: 334.2 341.2 359.1 363.8 379.1 12436.3 16095.9 16355.8 17991.6 18032.9 17314.7 17430.0 17455.6 17728.6 17876.8 5104.8 5212.9 5466.6 5484.8 5576.2 3540.7 5617.2 5664.1 5666.5 5808.1 6077.6 6125.4 6130.5 6140.5 6156.6 5915.1 6031.1 6072.5 6080.2 6148.7 5405.9 5411.1 5412.3 5415.8 5418.5 12236.1 16624.4 19725.1 21002.7 21160.5 1745.6 1923.6 1961.6 2010.7 2021.4 9317.5 9620.9 9804.2 10020.6 10108.0 1725.1 1729.2 1770.8 1820.5 1858.3 55950.0 59225.7 66529.7 67551.0 68449.3 9946.5 10254.7 10259.4 10460.6 10616.7 13816.2 13817.0 13847.7 13865.4 13988.3 7084.5 7201.8 7249.4 7299.6 7364.7 6690.3 6790.4 6918.5 6931.2 6937.5 4359.5 4416.0 4436.8 4547.4 4548.4 1705.9 1812.7 1882.2 1910.6 1953.4 778.7 797.1 837.7 842.6 894.2 16060.4 17277.3 17556.2 17786.9 18069.6 822.9 860.9 874.8 882.7 895.0 9723.0 9851.3 10131.0 10195.5 10247.5 379921.0 387336.0 396725.0 399158.0 401552.0 45293.8 45927.8 46051.5 51210.5 51314.7 883.4 887.4 893.2 917.2 919.1 11585.5 14855.0 15469.6 16465.8 16541.6 35578.8 35811.5 35847.6 36295.3 39586.4 51008.5 51481.7 52159.0 52213.7 52253.5 7891.6 8027.0 8065.3 8333.6 8544.1 9174.5 11800.2 12489.6 12777.6 12869.9 4940.9 5335.6 8094.3 9338.3 10114.4 9778.4 10679.1 10901.6 11185.5 11195.9 9592.3 9950.3 10039.6 10223.7 10263.8 8095.8 8108.8 8232.1 8325.3 8434.9 9807.4 10090.7 10111.2 10371.8 10501.7 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | [#3] mootools.html Cycles:180 Average:4747.44 Median:5528.17 stddev:2557.04 (46.3%) stddev-sans-first:2551.42 08:30:02 INFO - PROCESS | 20050 | Values: 1344.6 1386.4 1426.7 1598.4 1611.5 6679.6 7121.8 7129.9 7131.1 7135.4 6086.8 6891.1 7023.6 7121.6 7138.3 2136.1 2167.3 2167.4 2168.0 2168.3 2095.5 2129.9 2178.8 2182.7 2185.1 1736.3 2097.5 2156.1 2169.1 2185.9 2105.6 2111.0 2131.1 2132.0 2133.5 2108.9 2109.0 2111.5 2113.8 2114.3 7270.7 7299.0 7342.1 7361.1 7374.8 4247.9 4350.2 4351.7 4363.1 4366.5 2690.1 2760.0 2800.4 2812.5 2815.6 859.1 903.6 925.0 926.1 927.3 9697.8 9701.7 9741.4 9741.4 9755.9 3766.2 3933.3 4002.7 4095.6 4102.1 8350.5 8363.5 8364.0 8364.3 8368.6 4395.9 4397.9 4398.8 4405.8 4409.6 3968.6 4011.3 4021.4 4021.5 4030.2 3482.2 3566.1 3593.7 3615.7 3616.5 1157.9 1191.0 1198.3 1213.4 1213.5 587.9 750.6 756.4 803.3 840.8 4797.4 4874.9 4937.9 4993.9 5031.3 837.7 850.4 856.4 857.6 881.2 3155.4 3401.4 3410.5 3411.9 3461.5 8393.3 8580.2 8609.4 8686.5 8697.6 8618.5 8620.7 8620.9 8621.4 8622.4 6640.2 6767.7 6771.3 6773.7 6809.4 4812.0 4848.0 4863.5 4922.7 4943.6 7995.8 8045.8 8050.8 8096.4 8099.6 7950.4 8013.2 8210.8 8256.9 8278.2 6188.0 6315.0 6317.4 6318.9 6320.2 6844.2 6887.0 6895.7 6906.7 6916.1 5565.2 5576.4 5721.7 5731.1 5776.1 6119.4 6144.7 6215.8 6221.4 6247.4 5491.1 5629.0 5631.2 5633.4 5633.8 4257.8 4298.6 4304.9 4326.9 4368.5 4326.0 4334.1 4419.5 4466.0 4544.3 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | [#4] prototype.html Cycles:180 Average:4222.97 Median:4835.31 stddev:1205.94 (24.9%) stddev-sans-first:1172.61 08:30:02 INFO - PROCESS | 20050 | Values: 288.7 297.5 305.5 306.5 308.4 4835.8 4843.8 4850.7 4868.9 4937.3 4565.8 4694.4 4701.2 4709.8 4876.1 3692.7 3744.7 3758.4 3767.0 3838.3 3777.1 3793.2 3799.3 3823.0 3840.0 3663.8 3744.3 3769.9 3789.5 3794.7 3782.5 3788.9 3805.1 3813.4 3821.4 3387.3 3390.7 3394.4 3395.9 3397.3 4713.5 4727.9 4730.4 4732.9 4733.9 4169.4 4331.4 4343.4 4346.3 4369.6 5020.3 5029.4 5044.0 5045.5 5046.3 3710.2 3717.6 3718.1 3718.4 3723.3 5078.8 5229.7 5260.5 5264.7 5274.3 3769.3 3793.9 3795.6 3798.5 3811.0 3846.7 3854.5 3875.9 3893.1 3903.7 4336.8 4347.5 4361.6 4397.2 4397.8 4820.9 5056.1 5057.3 5067.2 5068.3 1321.0 1338.4 1338.9 1343.0 1346.4 850.7 897.8 917.3 929.6 943.1 4449.6 4461.4 4463.5 4464.3 4482.4 5173.8 5174.3 5184.1 5258.8 5261.5 4278.5 4430.8 4437.4 4463.4 4464.3 2760.9 2760.9 2766.9 2767.2 2777.8 4834.8 4862.7 4872.7 4876.6 4877.7 5064.3 5170.3 5198.8 5264.1 5271.8 4844.9 4858.7 4859.9 4862.8 4865.7 5154.4 5165.2 5169.0 5184.3 5187.5 4887.5 5086.7 5147.3 5152.4 5156.4 4931.3 4933.6 4938.3 4940.5 4940.9 3724.6 3727.3 3733.3 3740.1 3743.3 5168.9 5243.4 5287.3 5300.9 5320.4 5097.5 5098.4 5106.7 5110.8 5118.5 5071.1 5096.5 5102.5 5130.4 5136.6 4930.4 5025.3 5041.1 5051.5 5052.6 4897.3 4979.8 5116.6 5149.7 5169.5 5045.2 5045.6 5065.9 5067.7 5072.4 08:30:02 INFO - PROCESS | 20050 | 08:30:02 INFO - PROCESS | 20050 | [#5] yui.html Cycles:180 Average:18510.20 Median:24276.35 stddev:27071.89 (111.5%) stddev-sans-first:27114.67 08:30:02 INFO - PROCESS | 20050 | Values: 663.4 683.4 698.8 729.7 771.5 13477.7 14898.5 14913.3 14915.7 14958.8 13659.4 13731.9 13750.6 13766.8 13792.6 630.5 647.4 761.0 762.9 781.8 772.7 793.2 794.0 794.6 801.6 847.7 849.6 850.0 850.0 850.8 655.1 788.9 835.7 835.9 836.6 614.4 614.6 614.6 615.2 615.3 13192.7 13219.1 13243.3 13266.3 13274.9 12421.8 12446.6 12732.7 13027.3 13398.7 11716.7 11768.8 11848.4 11889.0 12132.2 4092.2 4101.3 4106.6 4181.8 4181.9 65994.0 68804.7 69487.7 69706.7 69748.7 9802.2 10162.6 10353.5 10390.3 10461.6 18296.0 18485.0 18660.8 18683.6 18704.3 6545.6 6795.0 6878.3 7049.5 7137.6 3808.5 3811.3 3861.7 3893.5 3937.5 2753.7 2848.9 2859.4 2866.4 2868.7 481.9 488.0 501.6 503.6 504.7 509.2 517.3 562.2 571.4 592.3 7458.2 7840.3 7922.3 8027.4 8204.5 516.7 534.3 552.3 554.6 566.7 3551.6 3604.7 3693.4 3721.9 3724.8 104656.0 109120.5 109387.5 109784.0 110155.0 52373.7 87514.7 91154.6 91854.0 94083.0 81158.6 81905.0 82005.6 82157.0 83802.6 7259.9 7331.0 7420.7 7427.8 7701.2 47881.5 48070.8 49417.0 49683.0 49687.8 52692.7 52919.5 53244.0 59438.0 63721.0 10574.8 10690.7 10704.3 10712.0 11241.7 12580.3 12627.3 12673.2 13145.3 13283.9 10961.3 11085.1 11087.6 11238.7 11814.6 12482.3 12544.8 12991.5 13073.5 13572.8 11607.9 12061.8 12220.3 12725.5 12738.5 10331.7 10593.9 10638.7 10642.9 10697.8 9013.3 9234.0 9578.8 9901.5 9925.8 08:30:02 INFO - PROCESS | 20050 | -------- Summary: end -------- 08:30:02 INFO - PROCESS | 20050 | 08:30:03 INFO - TEST-INFO | 20050: exit 0 08:30:03 INFO - TEST-OK | dromaeo_css | took 1118764ms 08:30:03 INFO - TEST-START | kraken 08:30:03 INFO - operating with platform_type : linux_ 08:30:03 INFO - Initialising browser for kraken test... 08:30:03 INFO - TEST-INFO | started process 20241 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHTMVBY/profile http://localhost:45773/getInfo.html) 08:30:06 INFO - PROCESS | 20241 | __metrics Screen width/height:1600/1200 08:30:06 INFO - PROCESS | 20241 | colorDepth:24 08:30:06 INFO - PROCESS | 20241 | Browser inner width/height: 1024/697 08:30:06 INFO - PROCESS | 20241 | __metrics 08:30:07 INFO - PROCESS | 20241 | 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" 08:30:07 INFO - PROCESS | 20241 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:08 INFO - TEST-INFO | 20241: exit 0 08:30:08 INFO - Browser initialized. 08:30:08 INFO - Running cycle 1/1 for kraken test... 08:30:08 INFO - TEST-INFO | started process 20319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHTMVBY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:20 INFO - PROCESS | 20319 | RSS: Main: 157483008 08:30:20 INFO - PROCESS | 20319 | 08:30:23 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45773/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:24 INFO - PROCESS | 20319 | RSS: Main: 213417984 08:30:24 INFO - PROCESS | 20319 | 08:30:26 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45773/tests/kraken/driver.html?testName=audio-dft) 08:30:27 INFO - PROCESS | 20319 | RSS: Main: 191639552 08:30:27 INFO - PROCESS | 20319 | 08:30:30 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45773/tests/kraken/driver.html?testName=audio-fft) 08:30:31 INFO - PROCESS | 20319 | RSS: Main: 270147584 08:30:31 INFO - PROCESS | 20319 | 08:30:33 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45773/tests/kraken/driver.html?testName=audio-oscillator) 08:30:34 INFO - PROCESS | 20319 | RSS: Main: 179806208 08:30:34 INFO - PROCESS | 20319 | 08:30:36 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45773/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:30:36 INFO - PROCESS | 20319 | RSS: Main: 184115200 08:30:36 INFO - PROCESS | 20319 | 08:30:40 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45773/tests/kraken/driver.html?testName=imaging-darkroom) 08:30:40 INFO - PROCESS | 20319 | RSS: Main: 220262400 08:30:40 INFO - PROCESS | 20319 | 08:30:44 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45773/tests/kraken/driver.html?testName=imaging-desaturate) 08:30:44 INFO - PROCESS | 20319 | RSS: Main: 220196864 08:30:44 INFO - PROCESS | 20319 | 08:30:47 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45773/tests/kraken/driver.html?testName=json-parse-financial) 08:30:48 INFO - PROCESS | 20319 | RSS: Main: 219783168 08:30:48 INFO - PROCESS | 20319 | 08:30:50 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45773/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:50 INFO - PROCESS | 20319 | RSS: Main: 184274944 08:30:50 INFO - PROCESS | 20319 | 08:30:52 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:53 INFO - PROCESS | 20319 | RSS: Main: 189583360 08:30:53 INFO - PROCESS | 20319 | 08:30:55 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:56 INFO - PROCESS | 20319 | RSS: Main: 193826816 08:30:56 INFO - PROCESS | 20319 | 08:30:58 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:59 INFO - PROCESS | 20319 | RSS: Main: 189276160 08:30:59 INFO - PROCESS | 20319 | 08:31:02 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:31:03 INFO - PROCESS | 20319 | RSS: Main: 192090112 08:31:03 INFO - PROCESS | 20319 | 08:31:04 INFO - PROCESS | 20319 | Cycle 1(1): loaded http://localhost:45773/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45773/tests/kraken/driver.html?testName=ai-astar) 08:31:05 INFO - PROCESS | 20319 | RSS: Main: 243212288 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | __start_tp_report 08:31:05 INFO - PROCESS | 20319 | _x_x_mozilla_page_load 08:31:05 INFO - PROCESS | 20319 | _x_x_mozilla_page_load_details 08:31:05 INFO - PROCESS | 20319 | |i|pagename|runs| 08:31:05 INFO - PROCESS | 20319 | |0;ai-astar;104;117;116;116;115;118;118;117;116;115 08:31:05 INFO - PROCESS | 20319 | |1;audio-beat-detection;149;122;130;198;122;129;144;122;163;121 08:31:05 INFO - PROCESS | 20319 | |2;audio-dft;162;161;161;161;162;163;162;159;160;160 08:31:05 INFO - PROCESS | 20319 | |3;audio-fft;78;76;74;110;75;75;75;76;106;75 08:31:05 INFO - PROCESS | 20319 | |4;audio-oscillator;86;84;134;85;86;84;114;86;85;134 08:31:05 INFO - PROCESS | 20319 | |5;imaging-gaussian-blur;117;117;116;117;117;117;118;118;117;117 08:31:05 INFO - PROCESS | 20319 | |6;imaging-darkroom;147;147;146;146;146;146;146;147;146;146 08:31:05 INFO - PROCESS | 20319 | |7;imaging-desaturate;95;95;94;95;95;95;94;96;95;95 08:31:05 INFO - PROCESS | 20319 | |8;json-parse-financial;64;62;63;62;63;64;62;63;63;63 08:31:05 INFO - PROCESS | 20319 | |9;json-stringify-tinderbox;65;66;65;66;65;65;66;65;66;65 08:31:05 INFO - PROCESS | 20319 | |10;stanford-crypto-aes;83;80;78;78;79;80;79;80;88;83 08:31:05 INFO - PROCESS | 20319 | |11;stanford-crypto-ccm;133;150;129;137;126;130;133;130;144;147 08:31:05 INFO - PROCESS | 20319 | |12;stanford-crypto-pbkdf2;168;169;171;167;166;170;175;170;166;168 08:31:05 INFO - PROCESS | 20319 | |13;stanford-crypto-sha256-iterative;68;66;71;71;69;66;68;68;66;67 08:31:05 INFO - PROCESS | 20319 | __end_tp_report 08:31:05 INFO - PROCESS | 20319 | __start_cc_report 08:31:05 INFO - PROCESS | 20319 | _x_x_mozilla_cycle_collect,1969 08:31:05 INFO - PROCESS | 20319 | __end_cc_report 08:31:05 INFO - PROCESS | 20319 | __startTimestamp1470756665061__endTimestamp 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | ------- Summary: start ------- 08:31:05 INFO - PROCESS | 20319 | Number of tests: 14 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.50 stddev:4.08 (3.5%) stddev-sans-first:1.13 08:31:05 INFO - PROCESS | 20319 | Values: 104.0 117.0 116.0 116.0 115.0 118.0 118.0 117.0 116.0 115.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:137.00 stddev:24.82 (18.1%) stddev-sans-first:26.11 08:31:05 INFO - PROCESS | 20319 | Values: 149.0 122.0 130.0 198.0 122.0 129.0 144.0 122.0 163.0 121.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.20 (0.7%) stddev-sans-first:1.22 08:31:05 INFO - PROCESS | 20319 | Values: 162.0 161.0 161.0 161.0 162.0 163.0 162.0 159.0 160.0 160.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:13.78 (18.4%) stddev-sans-first:14.54 08:31:05 INFO - PROCESS | 20319 | Values: 78.0 76.0 74.0 110.0 75.0 75.0 75.0 76.0 106.0 75.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:85.00 stddev:21.11 (24.8%) stddev-sans-first:21.95 08:31:05 INFO - PROCESS | 20319 | Values: 86.0 84.0 134.0 85.0 86.0 84.0 114.0 86.0 85.0 134.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 08:31:05 INFO - PROCESS | 20319 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 08:31:05 INFO - PROCESS | 20319 | Values: 147.0 147.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:31:05 INFO - PROCESS | 20319 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 94.0 96.0 95.0 95.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 08:31:05 INFO - PROCESS | 20319 | Values: 64.0 62.0 63.0 62.0 63.0 64.0 62.0 63.0 63.0 63.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 08:31:05 INFO - PROCESS | 20319 | Values: 65.0 66.0 65.0 66.0 65.0 65.0 66.0 65.0 66.0 65.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:80.00 stddev:3.08 (3.9%) stddev-sans-first:3.17 08:31:05 INFO - PROCESS | 20319 | Values: 83.0 80.0 78.0 78.0 79.0 80.0 79.0 80.0 88.0 83.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#11] stanford-crypto-ccm Cycles:10 Average:135.90 Median:135.00 stddev:8.31 (6.2%) stddev-sans-first:8.74 08:31:05 INFO - PROCESS | 20319 | Values: 133.0 150.0 129.0 137.0 126.0 130.0 133.0 130.0 144.0 147.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.50 stddev:2.71 (1.6%) stddev-sans-first:2.85 08:31:05 INFO - PROCESS | 20319 | Values: 168.0 169.0 171.0 167.0 166.0 170.0 175.0 170.0 166.0 168.0 08:31:05 INFO - PROCESS | 20319 | 08:31:05 INFO - PROCESS | 20319 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.89 (2.8%) stddev-sans-first:2.00 08:31:05 INFO - PROCESS | 20319 | Values: 68.0 66.0 71.0 71.0 69.0 66.0 68.0 68.0 66.0 67.0 08:31:05 INFO - PROCESS | 20319 | -------- Summary: end -------- 08:31:05 INFO - PROCESS | 20319 | 08:31:06 INFO - TEST-INFO | 20319: exit 0 08:31:06 INFO - TEST-OK | kraken | took 63177ms 08:31:06 INFO - SUITE-END | took 1181s 08:31:06 INFO - Completed test suite (00:19:41) 08:31:06 INFO - javascript benchmark 08:31:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2583.8062145339104, 2635.235781623329, 2669.9466010679785, 2673.077788163083, 2677.6385187999613, 4549.135664223795, 4576.199165146103, 4746.169420351438, 4803.039392121576, 4830.082284365968, 4673.1121086993535, 4685.765711714418, 4704.764761761907, 4766.0, 4785.210440277358, 4248.638865696412, 4271.359296105594, 4322.0491491871735, 4378.10248898975, 4485.349624304474, 4421.977890110545, 4462.8884277892985, 4486.865394038184, 4513.729176249435, 4519.028408892087, 4203.264428724991, 4233.682473814476, 4233.957660423387, 4364.8472303469225, 4371.759553224571, 4288.9142217155795, 4303.956960430418, 4306.784660766981, 4312.6765492587865, 4344.152890186415, 4295.548967358413, 4335.761533115678, 4340.804663790105, 4346.826126954918, 4432.268675668511, 4777.4267087949665, 4829.734364609945, 4865.3675022247235, 4872.9512704873205, 4878.07316609843, 4816.397950256218, 4940.308356830046, 5217.478252174752, 5218.921716174261, 5228.111221092423, 5061.038402703512, 5083.491650834887, 5088.898222035538, 5109.182530795055, 5149.510796474329, 4782.402199725035, 4785.832495862628, 4792.281157826354, 4807.543283388072, 4808.134535783593, 5003.324551185616, 5098.362704661918, 5144.819931302386, 5226.712530810766, 5230.712310822942, 5002.874928126826, 5017.096922553976, 5019.648624596316, 5019.82430614934, 5025.045241404195, 5030.89938201234, 5032.874178145503, 5036.219385995177, 5078.060558796635, 5079.1365467870555, 4873.293372460973, 4947.752612369367, 4957.652964292537, 4972.552470277692, 5038.34501514808, 4977.776000080005, 5017.89964200714, 5021.472745361758, 5024.773885175103, 5025.974870125626, 4287.056847493546, 4288.078063216423, 4305.418768466279, 4311.2239796836875, 4335.197988372098, 3682.723795715332, 3787.962120378761, 3799.9240015199543, 3801.5248093988253, 3809.3904975203836, 4163.354680024601, 4218.8523401680795, 4244.702870799076, 4345.608895199447, 4346.869593912188, 5139.691618502902, 5142.6914385137015, 5143.048536020848, 5146.511081447256, 5195.376554813447, 4345.065810850682, 4370.868873933787, 4377.321515165155, 4412.4705035395955, 4422.3366495026, 4356.825726970949, 4530.886727831765, 4558.772061396917, 4584.816607335736, 4630.259158534618, 4778.307145463958, 4781.13939490895, 4783.210770222874, 4812.8796780080775, 4823.27650852375, 5068.9239661405845, 5099.082165210298, 5103.311053007945, 5112.284280200701, 5118.360204974379, 4568.725876447424, 4678.134545109166, 4714.504976977505, 4715.952840471688, 4822.807087716452, 4980.676256043345, 4998.300237966615, 5003.849884503471, 5005.399352077774, 5043.268726034631, 5072.67027643202, 5074.467180946095, 5077.847664570068, 5080.034793389244, 5114.488551144856, 4742.051589682008, 4920.40955085392, 4921.729304888266, 4950.0, 4980.103581355391, 4990.301357809837, 4994.425641051181, 4996.075725990703, 4996.450390457119, 5012.724300163526, 4935.876603084952, 4939.110960027229, 4975.950240497694, 4987.177115775857, 4987.650864439454, 4798.688085274446, 4998.4501704813165, 5003.974980125076, 5067.721275329893, 5072.949270507248, 4891.26631005352, 4893.168161412497, 4893.779779909842, 4912.828051018198, 4946.406431228275], "value": 4656.084473036698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.9772809087636, 575.1057106199859, 577.9566532510057, 588.0561698473948, 593.285091464785, 29847.552286715716, 32332.35335293293, 32362.83818580907, 32375.51436728448, 39147.80426097873, 37549.93075934748, 41481.792591037076, 41497.58502414975, 41592.92035398233, 41617.583824161746, 7719.536827790323, 7889.644965976545, 7890.723824666138, 7891.329237014861, 7893.565853878034, 7416.0, 7618.390528757714, 7632.923670763277, 7705.306522412981, 7708.653110610036, 7599.506032107895, 7652.273034061783, 9971.60113595459, 10178.44018578978, 10228.33515821473, 10128.848067278997, 10161.441120738356, 10182.694519164437, 10202.540885660126, 10204.489775511269, 10001.049900259537, 10062.0, 10072.848907266396, 10085.546150423248, 10088.899111008943, 38484.230315393535, 38739.80630096832, 38929.22141557181, 38971.80514097412, 39005.0, 3042.421939831447, 3271.4765637497885, 3369.3261347730554, 3405.540252065964, 3561.8397172127316, 29111.56332655012, 29322.560161597594, 29626.407471850656, 29771.851140744373, 29826.10521684353, 3292.42382583047, 3313.96686033139, 3408.522806807049, 3413.7098346640564, 3458.9827050864837, 253114.0, 255219.0, 255971.0, 255978.0, 256310.0, 52514.47485525172, 52794.47205527972, 52847.0, 52891.73554132205, 52925.2061219082, 55674.443255567734, 55795.72102139463, 55856.441435585126, 55869.441305587236, 56005.1599226012, 14255.928720356333, 14423.062500937405, 14433.278336083154, 14433.566992990227, 14441.49454769078, 11170.162237832195, 11179.944100279447, 11183.72040698989, 11185.720356990978, 11195.384253866116, 13408.530701425403, 14741.484048058266, 15027.248637568297, 15206.695866082837, 15422.228888555528, 4972.57733092684, 5982.46157845796, 6147.692615369303, 6232.158658581124, 6336.89104406727, 809.6478032056075, 891.9018907920124, 970.7912798748303, 977.9119879210905, 980.5440470181258, 27420.862895685794, 27857.86071069672, 28254.43491130207, 28401.0, 28698.282542936595, 951.7477868364889, 954.1460392948272, 972.7713987212999, 995.7908839143861, 1067.124957534814, 14695.706085878222, 15080.472183473745, 15236.084472000497, 15282.847171528143, 15507.224638768243, 392335.0, 394277.0, 396537.0, 397559.0, 397998.0, 43417.56582434198, 44925.775371123586, 46312.073758525315, 50730.49269507331, 50955.23567146496, 1195.438144072302, 1196.126827415991, 1216.0697066744124, 1223.5962132496254, 1226.45422786859, 27176.45647087008, 28285.151445456668, 28579.571306430837, 28868.27829304284, 28963.0, 40627.59372406238, 41074.58925410708, 41079.0, 41084.1783164329, 41105.0, 287654.1790201559, 550145.0, 568390.0, 568898.0, 569997.0, 24297.0, 24488.0, 24579.508409832062, 24589.0, 24731.62902556427, 40161.39757903575, 40554.18891622092, 40924.38613420861, 41151.38272925848, 42033.36949945692, 35312.82343588266, 35512.822435887654, 35778.28443431065, 35873.0, 36400.45399318959, 35155.0, 35451.113722157155, 35751.0, 35864.462033069, 36058.0, 34344.82827585846, 34705.479417808245, 34750.304993900485, 34992.65007349894, 35309.0, 30599.694003060577, 32620.347593047532, 32749.34501309913, 32880.0, 32904.177395565304, 24087.397815054763, 24733.752662473144, 24887.37781555403, 25378.365540861625, 25465.490690186463], "value": 17847.048184713032, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.15958863458394, 341.1828670334549, 359.123738079087, 363.81445462813963, 379.0901835594573, 12436.253824770509, 16095.919520402396, 16355.836441635594, 17991.64016719665, 18032.909835450806, 17314.653706925856, 17430.0, 17455.603551715863, 17728.556786080353, 17876.82123178768, 5104.821331253397, 5212.895742085156, 5466.644668096561, 5484.780608775664, 5576.219329293902, 3540.6813386795184, 5617.157426386034, 5664.065429204176, 5666.5183459406, 5808.1287806829105, 6077.6353418795015, 6125.448709616134, 6130.540209484284, 6140.478059364959, 6156.630602163862, 5915.112733090028, 6031.125486804389, 6072.51419886408, 6080.209572755558, 6148.692565371736, 5405.945940540583, 5411.107167317388, 5412.323459567554, 5415.810446634349, 5418.458154184589, 12236.143469957107, 16624.418145364853, 19725.11236994339, 21002.684959725615, 21160.470988225265, 1745.589786400195, 1923.6248931458372, 1961.5880665060354, 2010.6883433067899, 2021.3632705697657, 9317.534123293875, 9620.903790962071, 9804.166645835112, 10020.64927727526, 10108.0, 1725.1288099509668, 1729.152715169558, 1770.7958588160045, 1820.4811628685761, 1858.27527264367, 55950.0, 59225.703871480364, 66529.66735166294, 67551.0, 68449.3155068448, 9946.502674866228, 10254.718160229972, 10259.435731034715, 10460.581576736846, 10616.681499555025, 13816.171029738249, 13816.963727720462, 13847.723045539033, 13865.445382184802, 13988.300584970913, 7084.504084714021, 7201.819954501075, 7249.420046396381, 7299.5620262784405, 7364.742234021891, 6690.264070952191, 6790.42281406086, 6918.481113916477, 6931.202911665122, 6937.549059311129, 4359.498657654411, 4416.0, 4436.800343984529, 4547.363369128325, 4548.385967894292, 1705.8976461412356, 1812.6918423867974, 1882.2471011595198, 1910.551020510179, 1953.3553927203989, 778.7040924448786, 797.095296838093, 837.7235512280932, 842.5744998775579, 894.1505569708803, 16060.357585696675, 17277.308907643805, 17556.209970551357, 17786.91106544459, 18069.638607228044, 822.8930239068883, 860.8708693695995, 874.7856775090144, 882.6557642519423, 894.9776255593662, 9722.951385243028, 9851.310408271496, 10131.0, 10195.490225488697, 10247.538860751136, 379921.0, 387336.0, 396725.0, 399158.0, 401552.0, 45293.77353113279, 45927.77036114798, 46051.5394846054, 51210.48789512132, 51314.74342628263, 883.3728053082383, 887.4187407248263, 893.2184338703504, 917.1745429113718, 919.0946917286467, 11585.478653460446, 14855.034422762486, 15469.613259668147, 16465.83534164643, 16541.58646033859, 35578.822105890344, 35811.462828057076, 35847.64152358547, 36295.27409451743, 39586.40620390754, 51008.48991510038, 51481.742591288305, 52159.0, 52213.7389313051, 52253.47746522638, 7891.565963872042, 8026.959865200637, 8065.274125328749, 8333.583320833814, 8544.102869198894, 9174.54127293646, 11800.17398782077, 12489.625311240678, 12777.552785652457, 12869.871301286867, 4940.901181976268, 5335.573154147736, 8094.271515563628, 9338.252939764667, 10114.393136411838, 9778.41329520231, 10679.145668346358, 10901.618443354444, 11185.496652650487, 11195.944020279847, 9592.280578956746, 9950.303478756417, 10039.598416063276, 10223.693289201039, 10263.846042309222, 8095.797605059921, 8108.83782324362, 8232.094469608459, 8325.25072743456, 8434.915650843659, 9807.362521436084, 10090.69727908164, 10111.241656875616, 10371.792564148524, 10501.684949451528], "value": 8106.341498372362, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.5630170194684, 1386.403846346071, 1426.7217892510957, 1598.3606557377054, 1611.500434865192, 6679.632620205887, 7121.750738724146, 7129.857402851946, 7131.144262688472, 7135.357817796397, 6086.7565297388055, 6891.1041564596535, 7023.578585284874, 7121.572705637665, 7138.321859423347, 2136.092160831648, 2167.3281282802304, 2167.3822960456273, 2168.01355383301, 2168.27362833451, 2095.528506086126, 2129.893505324735, 2178.8039076483115, 2182.650775875852, 2185.1259496201487, 1736.2620886123411, 2097.4861159016064, 2156.137544982012, 2169.0998235732154, 2185.8579192352445, 2105.631514484974, 2110.9894450527727, 2131.0836340373635, 2132.0, 2133.5412886229537, 2108.8734675919495, 2109.0298462707174, 2111.5037966077957, 2113.7674855765854, 2114.2917122763893, 7270.709171633128, 7298.9635051824935, 7342.082239720035, 7361.1166660001145, 7374.815629609303, 4247.91504169918, 4350.2387082260475, 4351.6518678505645, 4363.149185908749, 4366.541513141138, 2690.1391554702504, 2759.986200069007, 2800.3979144484033, 2812.535931571288, 2815.6480439945008, 859.1365677494141, 903.5933829776561, 924.9630014799467, 926.1294383279695, 927.3045216087934, 9697.806043879084, 9701.70894873155, 9741.366811157252, 9741.415515069119, 9755.902440975571, 3766.1902690921584, 3933.272344616255, 4002.699797515198, 4095.610916962884, 4102.118044620362, 8350.457220280783, 8363.456375335705, 8363.95818020906, 8364.289035431935, 8368.58157092143, 4395.934060989088, 4397.934030989538, 4398.846040388571, 4405.823767049346, 4409.559044095629, 3968.5634580196156, 4011.277969965376, 4021.4369988201674, 4021.5375231848125, 4030.1536677298095, 3482.181687303482, 3566.0728210665416, 3593.658602432765, 3615.6926661233565, 3616.475611036385, 1157.9305241685527, 1191.0352614382377, 1198.2510930668332, 1213.4478812140455, 1213.4600102954278, 587.8789684312911, 750.557171268954, 756.4175584799783, 803.3211935914143, 840.7982084299724, 4797.376341075638, 4874.8537543873745, 4937.925931111036, 4993.850184494471, 5031.320771695848, 837.7464620171719, 850.3877208409934, 856.4347530629755, 857.5926434943352, 881.1805021330224, 3155.4477966355794, 3401.40475416806, 3410.5225268462436, 3411.880584179542, 3461.5326930864517, 8393.328533717287, 8580.227779499875, 8609.354298427643, 8686.522241276663, 8697.608607612672, 8618.482891026559, 8620.698275560451, 8620.913790862136, 8621.353398495137, 8622.439541429787, 6640.20317561886, 6767.695453704595, 6771.289014653489, 6773.72905083801, 6809.38715515606, 4811.9518804811505, 4848.0, 4863.513648635108, 4922.680025798311, 4943.629227807857, 7995.760127196193, 8045.839083218186, 8050.83898322042, 8096.352291816523, 8099.635516401659, 7950.443468957349, 8013.2387423194705, 8210.794730131794, 8256.917430825615, 8278.213569710868, 6187.969060154671, 6314.968425157845, 6317.431431171217, 6318.936810631835, 6320.209973753281, 6844.178698556205, 6886.966954956596, 6895.724171033103, 6906.654667266516, 6916.135483064617, 5565.248691426606, 5576.358718747234, 5721.685307308138, 5731.083026715705, 5776.075827867521, 6119.357467465852, 6144.692765361625, 6215.751369945332, 6221.377862213923, 6247.375262473716, 5491.0939694951485, 5629.043062679272, 5631.155326701028, 5633.4084921082695, 5633.802816901389, 4257.829686812493, 4298.57768376934, 4304.913901721885, 4326.9134617306845, 4368.519462859146, 4326.048269380732, 4334.068175342317, 4419.535948725337, 4466.0398014427055, 4544.272916333371], "value": 3885.842182637187, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.6608235323494, 297.510595071108, 305.4883070856315, 306.52794696167905, 308.4186308807898, 4835.806567737291, 4843.80624775009, 4850.666066831623, 4868.880157563758, 4937.284093806405, 4565.794539245733, 4694.4366675998845, 4701.2478003519445, 4709.811607535703, 4876.14706411564, 3692.7415080944343, 3744.7004239660896, 3758.4442579046236, 3766.9623303766953, 3838.3131807734558, 3777.0746167189077, 3793.184465339951, 3799.25914446683, 3822.9617703823023, 3840.0, 3663.7618554793985, 3744.307303148926, 3769.8690392882163, 3789.450529673196, 3794.6964242860645, 3782.5224477966976, 3788.90527736806, 3805.1058001369656, 3813.4089216171537, 3821.445890345912, 3387.271736576648, 3390.677885600864, 3394.4399174136483, 3395.9490607640905, 3397.252604427022, 4713.457952335491, 4727.881802954919, 4730.361401210827, 4732.905341893177, 4733.881652958704, 4169.437125987984, 4331.350297455376, 4343.370211319372, 4346.348047792856, 4369.584889435499, 5020.2720605511995, 5029.4216165141115, 5044.0, 5045.5459008689395, 5046.318746969186, 3710.2208536207427, 3717.6282371762877, 3718.1262403335195, 3718.4050551911837, 3723.2925744108534, 5078.822241221539, 5229.686218826844, 5260.526552610283, 5264.657797243167, 5274.340707411574, 3769.3026790043928, 3793.867214647474, 3795.5635101963076, 3798.537563038195, 3811.0, 3846.7113516972126, 3854.5374555053572, 3875.8643447479203, 3893.124047089382, 3903.6681882040343, 4336.783160841946, 4347.478382566077, 4361.563843615677, 4397.2304846651705, 4397.779121543664, 4820.8794780130775, 5056.140456122394, 5057.266696329012, 5067.1639179535705, 5068.315777370008, 1320.9867901321056, 1338.444545513601, 1338.9087893366875, 1342.9597112086458, 1346.4412268908488, 850.6937502499095, 897.7845317123974, 917.2524392619994, 929.6374413978554, 943.0899182288987, 4449.599536041772, 4461.397711308997, 4463.464384273908, 4464.330350447459, 4482.372467854503, 5173.793048278102, 5174.275601415805, 5184.144616138295, 5258.816766227568, 5261.474492243745, 4278.507971583245, 4430.778461076933, 4437.378766972626, 4463.419755431773, 4464.330350447459, 2760.917172484829, 2760.930976725598, 2766.861656917146, 2767.225176950457, 2777.763890069367, 4834.806607735722, 4862.708237505761, 4872.6832755870755, 4876.634252431082, 4877.68295060827, 5064.328771505395, 5170.302009228706, 5198.792048318101, 5264.131418316011, 5271.762770675329, 4844.927326090112, 4858.732769697559, 4859.927101093486, 4862.80548778052, 4865.7323847188745, 5154.355705536808, 5165.173572228426, 5168.984096202559, 5184.274201611853, 5187.507186817246, 4887.535684110004, 5086.694798312114, 5147.279380886604, 5152.355955505562, 5156.40701319338, 4931.3096166536, 4933.580645645016, 4938.30863679078, 4940.481249468754, 4940.851774446773, 3724.571674257495, 3727.347714150067, 3733.309337772521, 3740.1023754299317, 3743.3262012836863, 5168.922466162935, 5243.423223445342, 5287.2597836302175, 5300.867478313073, 5320.388155362028, 5097.541221290102, 5098.388193416814, 5106.744662766772, 5110.795568177231, 5118.517519003206, 5071.13790655582, 5096.541311282002, 5102.515261050195, 5130.358285348652, 5136.640435169502, 4930.359053323045, 5025.271335656236, 5041.117804384291, 5051.4695956924, 5052.595792336678, 4897.289892965571, 4979.775910083982, 5116.616253781056, 5149.742512874266, 5169.4830516948, 5045.183733855883, 5045.596352291881, 5065.8733531662, 5067.721275329893, 5072.442031376472], "value": 3842.980439199685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.3731124087737, 683.3678847066462, 698.8357735932052, 729.686234918985, 771.4869611708212, 13477.730445391098, 14898.478553250638, 14913.254337283146, 14915.701685966274, 14958.775618365733, 13659.385327660255, 13731.862681373183, 13750.587482375544, 13766.793498097511, 13792.586222413318, 630.5334052800918, 647.3817504283398, 760.9771706848803, 762.9427792915526, 781.7967328494575, 772.6793380747011, 793.218679600593, 793.9523628582275, 794.6146119132217, 801.5911884938694, 847.6694089305176, 849.6346570974479, 849.9830003399928, 850.0182289455657, 850.7575341027808, 655.1057806094694, 788.9171636978092, 835.7116794705817, 835.8579041562949, 836.640244694781, 614.4439282449346, 614.5728718540616, 614.5943677173044, 615.157234588612, 615.2555407956378, 13192.73614527704, 13219.140755850838, 13243.337833108306, 13266.270355130464, 13274.933625331909, 12421.751564968741, 12446.626601201979, 12732.681682957907, 13027.348632568333, 13398.665033374145, 11716.70708232301, 11768.764624707543, 11848.40757962107, 11889.0, 12132.211406258653, 4092.1611069730634, 4101.282275601788, 4106.640008314229, 4181.790910454495, 4181.937270940938, 65994.0, 68804.6559767203, 69487.65256173686, 69706.65146674284, 69748.6512567444, 9802.215822734164, 10162.644307449204, 10353.482325883826, 10390.272680912414, 10461.633842815618, 18296.0, 18485.0, 18660.81339186618, 18683.626327473376, 18704.25182992692, 6545.574537655038, 6795.048693182958, 6878.312168783082, 7049.506534542636, 7137.643117844087, 3808.5239345081864, 3811.27585758705, 3861.6524512793985, 3893.5133108361456, 3937.5274967004143, 2753.655793025872, 2848.9430211395656, 2859.3566447549133, 2866.4123854609757, 2868.670102938147, 481.94698583155827, 487.98048078076476, 501.60623910230294, 503.5719638307409, 504.699703676312, 509.248857934551, 517.2835622662565, 562.1511517608359, 571.402883986227, 592.3306663470252, 7458.216887226763, 7840.33357164636, 7922.286994170552, 8027.357811375076, 8204.548749818818, 516.6590050566608, 534.28939510451, 552.3371953655629, 554.6200852416108, 566.6543408520799, 3551.5560554930635, 3604.747667663291, 3693.353663108945, 3721.8697345592773, 3724.832382542792, 104656.0, 109120.4543977275, 109387.45306273577, 109784.0, 110155.0, 52373.73813130986, 87514.68727969086, 91154.63268050985, 91854.0, 94083.0, 81158.59420702858, 81905.0, 82005.58997204975, 82156.9999999988, 83802.58098709467, 7259.854802903807, 7330.96334518324, 7420.666070026754, 7427.7771666850085, 7701.1913749057785, 47881.5211847891, 48070.75964620154, 49417.0, 49683.0, 49687.75156124196, 52692.736536317076, 52919.47080529299, 53244.0, 59438.0, 63721.0, 10574.788504230026, 10690.73273168177, 10704.30422022566, 10712.0, 11241.71895702614, 12580.308083055153, 12627.305498197687, 12673.239605623692, 13145.277009764555, 13283.933580332037, 10961.287516311415, 11085.113190944867, 11087.55649774, 11238.719032024264, 11814.645560633195, 12482.313472759086, 12544.811827822408, 12991.480340786262, 13073.477060917457, 13572.79640805369, 11607.883921160681, 12061.75876482483, 12220.327881966221, 12725.490980360682, 12738.490460381481, 10331.690049298533, 10593.894061059598, 10638.679226495033, 10642.946785266024, 10697.786044279226, 9013.278937684838, 9234.0, 9578.760530986503, 9901.50492475359, 9925.751856203653], "value": 6664.733639525805, "unit": "score"}], "name": "dromaeo_css", "value": 6373.8243664341235, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 116.0, 116.0, 115.0, 118.0, 118.0, 117.0, 116.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 130.0, 198.0, 122.0, 129.0, 144.0, 122.0, 163.0, 121.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 162.0, 163.0, 162.0, 159.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 74.0, 110.0, 75.0, 75.0, 75.0, 76.0, 106.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 134.0, 85.0, 86.0, 84.0, 114.0, 86.0, 85.0, 134.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 62.0, 63.0, 64.0, 62.0, 63.0, 63.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 78.0, 78.0, 79.0, 80.0, 79.0, 80.0, 88.0, 83.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 150.0, 129.0, 137.0, 126.0, 130.0, 133.0, 130.0, 144.0, 147.0], "value": 135.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 171.0, 167.0, 166.0, 170.0, 175.0, 170.0, 166.0, 168.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 71.0, 71.0, 69.0, 66.0, 68.0, 68.0, 66.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1536.4, "alertThreshold": 2.0}]} 08:31:06 INFO - Return code: 0 08:31:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:31:06 INFO - # TBPL SUCCESS # 08:31:06 INFO - Running post-action listener: _resource_record_post_action 08:31:06 INFO - [mozharness: 2016-08-09 15:31:06.354909Z] Finished run-tests step (success) 08:31:06 INFO - Running post-run listener: _resource_record_post_run 08:31:06 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 0; Write bytes: 526344192; Read time: 0; Write time: 390144 08:31:06 INFO - TinderboxPrint: CPU usage
13.0% 08:31:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:31:06 INFO - TinderboxPrint: I/O write bytes / time
526,344,192 / 390,144 08:31:06 INFO - TinderboxPrint: CPU idle
8,321.7 (87.0%) 08:31:06 INFO - TinderboxPrint: CPU user
1,197.9 (12.5%) 08:31:06 INFO - TinderboxPrint: Swap in / out
0 / 0 08:31:06 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 240848896; Read time: 0; Write time: 183352 08:31:06 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 285212672; Read time: 0; Write time: 206768 08:31:06 INFO - Running post-run listener: _upload_blobber_files 08:31:06 INFO - Blob upload gear active. 08:31:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:31:06 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 08:31:06 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'] 08:31:06 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 08:31:07 INFO - (blobuploader) - INFO - Open directory for files ... 08:31:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:31:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:31:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:10 INFO - (blobuploader) - INFO - Done attempting. 08:31:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:31:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:31:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:11 INFO - (blobuploader) - INFO - Done attempting. 08:31:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:31:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:31:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:12 INFO - (blobuploader) - INFO - Done attempting. 08:31:12 INFO - (blobuploader) - INFO - Iteration through files over. 08:31:12 INFO - Return code: 0 08:31:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:31:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:31:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f3f10f37423eca1670069406b4aaed61d3451025fc180adc6b9112460406ce5e7ee0f4ae5061ecbab2f18ee26e9581f9d49cbc5332cac68ce73b138c4ef4773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3167f5173b0486bd62f418580b2e6bf00382594bc2a3c0bf426e67c0bdeb5a9f373127685d58db535987252239bce90b50b4c41474631373ddc25515054bb197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06e2989eb7c4294748448e632da33cba5928c50ccd6953cd0656a90aa5e60d3c876c91f428265db6d49c090a0876da44d1a4c07741de124e960134212d4b132f"} 08:31:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:31:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:31:12 INFO - Contents: 08:31:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f3f10f37423eca1670069406b4aaed61d3451025fc180adc6b9112460406ce5e7ee0f4ae5061ecbab2f18ee26e9581f9d49cbc5332cac68ce73b138c4ef4773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3167f5173b0486bd62f418580b2e6bf00382594bc2a3c0bf426e67c0bdeb5a9f373127685d58db535987252239bce90b50b4c41474631373ddc25515054bb197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06e2989eb7c4294748448e632da33cba5928c50ccd6953cd0656a90aa5e60d3c876c91f428265db6d49c090a0876da44d1a4c07741de124e960134212d4b132f"} 08:31:12 INFO - Running post-run listener: copy_logs_to_upload_dir 08:31:12 INFO - Copying logs to upload dir... 08:31:12 INFO - mkdir: /builds/slave/test/build/upload/logs 08:31:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.475459 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-09 08:31:12.075851) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 08:31:12.079542) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806 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/8f3f10f37423eca1670069406b4aaed61d3451025fc180adc6b9112460406ce5e7ee0f4ae5061ecbab2f18ee26e9581f9d49cbc5332cac68ce73b138c4ef4773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3167f5173b0486bd62f418580b2e6bf00382594bc2a3c0bf426e67c0bdeb5a9f373127685d58db535987252239bce90b50b4c41474631373ddc25515054bb197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06e2989eb7c4294748448e632da33cba5928c50ccd6953cd0656a90aa5e60d3c876c91f428265db6d49c090a0876da44d1a4c07741de124e960134212d4b132f"} build_url:https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/Mpvf0MenQZOK9vDUSiPbZw/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/8f3f10f37423eca1670069406b4aaed61d3451025fc180adc6b9112460406ce5e7ee0f4ae5061ecbab2f18ee26e9581f9d49cbc5332cac68ce73b138c4ef4773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3167f5173b0486bd62f418580b2e6bf00382594bc2a3c0bf426e67c0bdeb5a9f373127685d58db535987252239bce90b50b4c41474631373ddc25515054bb197", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/06e2989eb7c4294748448e632da33cba5928c50ccd6953cd0656a90aa5e60d3c876c91f428265db6d49c090a0876da44d1a4c07741de124e960134212d4b132f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 08:31:12.104173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:31:12.104499) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470755436.218001-1481835806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:31:12.189970) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 08:31:12.190293) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 08:31:12.190574) ========= ========= Total master_lag: 0.15 =========