builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1471918687.86 results: success (0) buildid: 20160822191704 builduid: 069cff7812bf4c79ac140547ddcd18fc revision: 7fab74549bbabc0ab0e64a19fd83f4c5660716b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:07.859438) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:07.860140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:07.860511) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:07.876283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:07.876592) ========= 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=1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 19:18:08-- 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% 46.8M=0s 2016-08-22 19:18:08 (46.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086857 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:07.979345) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:07.980115) ========= 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=1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017300 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:08.032438) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:08.032858) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 7fab74549bbabc0ab0e64a19fd83f4c5660716b4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 7fab74549bbabc0ab0e64a19fd83f4c5660716b4 --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=1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 19:18:08,230 truncating revision to first 12 chars 2016-08-22 19:18:08,230 Setting DEBUG logging. 2016-08-22 19:18:08,230 attempt 1/10 2016-08-22 19:18:08,230 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/7fab74549bba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 19:18:08,572 unpacking tar archive at: mozilla-inbound-7fab74549bba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627344 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:08.676712) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:08.677402) ========= script_repo_revision: 7fab74549bbabc0ab0e64a19fd83f4c5660716b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:08.678328) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:08.678661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 19:18:08.691129) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-22 19:18:08.691613) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:18:08 INFO - MultiFileLogger online at 20160822 19:18:08 in /builds/slave/test 19:18:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 19:18:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:18:08 INFO - {'append_to_log': False, 19:18:08 INFO - 'base_work_dir': '/builds/slave/test', 19:18:08 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:18:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:18:08 INFO - 'branch': 'Mozilla-Inbound', 19:18:08 INFO - 'buildbot_json_path': 'buildprops.json', 19:18:08 INFO - 'config_files': ('talos/linux_config.py',), 19:18:08 INFO - 'default_actions': ('clobber', 19:18:08 INFO - 'read-buildbot-config', 19:18:08 INFO - 'download-and-extract', 19:18:08 INFO - 'populate-webroot', 19:18:08 INFO - 'create-virtualenv', 19:18:08 INFO - 'install', 19:18:08 INFO - 'run-tests'), 19:18:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:18:08 INFO - 'download_minidump_stackwalk': True, 19:18:08 INFO - 'download_symbols': 'ondemand', 19:18:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:18:08 INFO - 'tooltool.py': '/tools/tooltool.py', 19:18:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:18:08 INFO - '/tools/misc-python/virtualenv.py')}, 19:18:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:18:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:18:08 INFO - 'installer_path': 'installer.exe', 19:18:08 INFO - 'log_level': 'info', 19:18:08 INFO - 'log_name': 'talos', 19:18:08 INFO - 'log_to_console': True, 19:18:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:18:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:18:08 INFO - 'opt_config_files': (), 19:18:08 INFO - 'pip_index': False, 19:18:08 INFO - 'sps_profile': False, 19:18:08 INFO - 'sps_profile_interval': 0, 19:18:08 INFO - 'suite': 'dromaeojs', 19:18:08 INFO - 'system_bits': '32', 19:18:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:18:08 INFO - 'title': 'talos-linux64-ix-027', 19:18:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:18:08 INFO - 'use_talos_json': True, 19:18:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:18:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:18:08 INFO - 'work_dir': 'build'} 19:18:08 INFO - [mozharness: 2016-08-23 02:18:08.980594Z] Running clobber step. 19:18:08 INFO - Running pre-action listener: _resource_record_pre_action 19:18:08 INFO - Running main action method: clobber 19:18:08 INFO - rmtree: /builds/slave/test/build 19:18:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:18:09 INFO - Running post-action listener: _resource_record_post_action 19:18:09 INFO - [mozharness: 2016-08-23 02:18:09.982932Z] Finished clobber step (success) 19:18:09 INFO - [mozharness: 2016-08-23 02:18:09.983033Z] Running read-buildbot-config step. 19:18:09 INFO - Running pre-action listener: _resource_record_pre_action 19:18:09 INFO - Running main action method: read_buildbot_config 19:18:09 INFO - Using buildbot properties: 19:18:09 INFO - { 19:18:09 INFO - "project": "", 19:18:09 INFO - "product": "firefox", 19:18:09 INFO - "script_repo_revision": "production", 19:18:09 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 19:18:09 INFO - "repository": "", 19:18:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 19:18:09 INFO - "buildid": "20160822191704", 19:18:09 INFO - "stage_platform": "linux64-pgo", 19:18:09 INFO - "basedir": "/builds/slave/test", 19:18:09 INFO - "buildnumber": 2277, 19:18:09 INFO - "platform": "ubuntu64_hw", 19:18:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:18:09 INFO - "slavebuilddir": "test", 19:18:09 INFO - "branch": "mozilla-inbound", 19:18:09 INFO - "revision": "7fab74549bbabc0ab0e64a19fd83f4c5660716b4", 19:18:09 INFO - "slavename": "talos-linux64-ix-027", 19:18:09 INFO - "builduid": "069cff7812bf4c79ac140547ddcd18fc", 19:18:09 INFO - "repo_path": "integration/mozilla-inbound" 19:18:09 INFO - } 19:18:09 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:18:09 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 19:18:09 INFO - Running post-action listener: _resource_record_post_action 19:18:09 INFO - [mozharness: 2016-08-23 02:18:09.985404Z] Finished read-buildbot-config step (success) 19:18:09 INFO - [mozharness: 2016-08-23 02:18:09.985492Z] Running download-and-extract step. 19:18:09 INFO - Running pre-action listener: _resource_record_pre_action 19:18:09 INFO - Running main action method: download_and_extract 19:18:09 INFO - mkdir: /builds/slave/test/build/tests 19:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:09 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:18:09 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:10 INFO - Downloaded 1361 bytes. 19:18:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:18:10 INFO - Using the following test package requirements: 19:18:10 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:18:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:18:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:18:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'jsshell-linux-x86_64.zip'], 19:18:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:18:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:18:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:18:10 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:18:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:18:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:18:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:18:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:10 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:18:10 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:10 INFO - Downloaded 21914480 bytes. 19:18:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 19:18:14 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 19:18:14 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')]} 19:18:14 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:18:14 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:14 INFO - Downloaded 21914480 bytes. 19:18:14 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 19:18:19 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')]} 19:18:19 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:18:19 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:19 INFO - Downloaded 14413981 bytes. 19:18:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 19:18:20 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')]} 19:18:20 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:18:20 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/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 19:18:23 INFO - Downloaded 59767404 bytes. 19:18:23 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:18:23 INFO - mkdir: /builds/slave/test/properties 19:18:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:18:23 INFO - Writing to file /builds/slave/test/properties/build_url 19:18:23 INFO - Contents: 19:18:23 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:18:24 INFO - Running post-action listener: _resource_record_post_action 19:18:24 INFO - Running post-action listener: set_extra_try_arguments 19:18:24 INFO - [mozharness: 2016-08-23 02:18:24.017128Z] Finished download-and-extract step (success) 19:18:24 INFO - [mozharness: 2016-08-23 02:18:24.017353Z] Running populate-webroot step. 19:18:24 INFO - Running pre-action listener: _resource_record_pre_action 19:18:24 INFO - Running main action method: populate_webroot 19:18:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:18:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:18:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:18:24 INFO - '--tppagecycles', 19:18:24 INFO - '7'], 19:18:24 INFO - 'tests': ['tsvgm']}}, 19:18:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:18:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:18:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:18:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:18:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['damp', 'tps']}, 19:18:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'tests': ['damp', 'tps']}, 19:18:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['dromaeo_dom']}, 19:18:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:18:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['basic_compositor_video']}, 19:18:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:18:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['a11yr', 19:18:24 INFO - 'ts_paint', 19:18:24 INFO - 'tpaint', 19:18:24 INFO - 'sessionrestore', 19:18:24 INFO - 'sessionrestore_no_auto_restore', 19:18:24 INFO - 'tabpaint']}, 19:18:24 INFO - 'other-e10s': {'tests': ['a11yr', 19:18:24 INFO - 'ts_paint', 19:18:24 INFO - 'tpaint', 19:18:24 INFO - 'sessionrestore', 19:18:24 INFO - 'sessionrestore_no_auto_restore', 19:18:24 INFO - 'tabpaint']}, 19:18:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:18:24 INFO - 'ts_paint', 19:18:24 INFO - 'tpaint', 19:18:24 INFO - 'sessionrestore', 19:18:24 INFO - 'sessionrestore_no_auto_restore', 19:18:24 INFO - 'tabpaint']}, 19:18:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:18:24 INFO - 'ts_paint', 19:18:24 INFO - 'tpaint', 19:18:24 INFO - 'sessionrestore', 19:18:24 INFO - 'sessionrestore_no_auto_restore', 19:18:24 INFO - 'tabpaint']}, 19:18:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['a11yr', 19:18:24 INFO - 'ts_paint', 19:18:24 INFO - 'tpaint', 19:18:24 INFO - 'sessionrestore', 19:18:24 INFO - 'sessionrestore_no_auto_restore', 19:18:24 INFO - 'tabpaint']}, 19:18:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['a11yr', 19:18:24 INFO - 'ts_paint', 19:18:24 INFO - 'tpaint', 19:18:24 INFO - 'sessionrestore', 19:18:24 INFO - 'sessionrestore_no_auto_restore', 19:18:24 INFO - 'tabpaint']}, 19:18:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['tsvgx', 19:18:24 INFO - 'tsvgr_opacity', 19:18:24 INFO - 'tart', 19:18:24 INFO - 'tscrollx', 19:18:24 INFO - 'cart']}, 19:18:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:18:24 INFO - 'tsvgr_opacity', 19:18:24 INFO - 'tart', 19:18:24 INFO - 'tscrollx', 19:18:24 INFO - 'cart']}, 19:18:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'talos_options': ['--disable-e10s'], 19:18:24 INFO - 'tests': ['tp5o']}, 19:18:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'tests': ['tp5o']}, 19:18:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'talos_options': ['--disable-e10s', 19:18:24 INFO - '--xperf_path', 19:18:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:24 INFO - 'tests': ['tp5n']}, 19:18:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:24 INFO - 'talos_options': ['--xperf_path', 19:18:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:24 INFO - 'tests': ['tp5n']}}, 19:18:24 INFO - 'talos.zip': {'path': '', 19:18:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:18:24 INFO - Running post-action listener: _resource_record_post_action 19:18:24 INFO - [mozharness: 2016-08-23 02:18:24.035005Z] Finished populate-webroot step (success) 19:18:24 INFO - [mozharness: 2016-08-23 02:18:24.035092Z] Running create-virtualenv step. 19:18:24 INFO - Running pre-action listener: _resource_record_pre_action 19:18:24 INFO - Running main action method: create_virtualenv 19:18:24 INFO - Creating virtualenv /builds/slave/test/build/venv 19:18:24 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 19:18:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:18:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:18:24 INFO - Using real prefix '/usr' 19:18:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:18:24 INFO - Installing distribute.............................................................................................................................................................................................done. 19:18:26 INFO - Installing pip.................done. 19:18:26 INFO - Return code: 0 19:18:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:26 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:26 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 19:18:26 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 19:18:26 INFO - Using env: {'DISPLAY': ':0', 19:18:26 INFO - 'HOME': '/home/cltbld', 19:18:26 INFO - 'LANG': 'en_US.UTF-8', 19:18:26 INFO - 'LANGUAGE': 'en_US:en', 19:18:26 INFO - 'LOGNAME': 'cltbld', 19:18:26 INFO - 'MAIL': '/var/mail/cltbld', 19:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:26 INFO - 'MOZ_NO_REMOTE': '1', 19:18:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:26 INFO - 'NO_EM_RESTART': '1', 19:18:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:26 INFO - 'PWD': '/builds/slave/test', 19:18:26 INFO - 'SHELL': '/bin/bash', 19:18:26 INFO - 'SHLVL': '1', 19:18:26 INFO - 'TERM': 'linux', 19:18:26 INFO - 'TMOUT': '86400', 19:18:26 INFO - 'USER': 'cltbld', 19:18:26 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:26 INFO - '_': '/tools/buildbot/bin/python'} 19:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:26 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 19:18:26 INFO - Cleaning up... 19:18:26 INFO - Return code: 0 19:18:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:26 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:26 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 19:18:26 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 19:18:26 INFO - Using env: {'DISPLAY': ':0', 19:18:26 INFO - 'HOME': '/home/cltbld', 19:18:26 INFO - 'LANG': 'en_US.UTF-8', 19:18:26 INFO - 'LANGUAGE': 'en_US:en', 19:18:26 INFO - 'LOGNAME': 'cltbld', 19:18:26 INFO - 'MAIL': '/var/mail/cltbld', 19:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:26 INFO - 'MOZ_NO_REMOTE': '1', 19:18:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:26 INFO - 'NO_EM_RESTART': '1', 19:18:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:26 INFO - 'PWD': '/builds/slave/test', 19:18:26 INFO - 'SHELL': '/bin/bash', 19:18:26 INFO - 'SHLVL': '1', 19:18:26 INFO - 'TERM': 'linux', 19:18:26 INFO - 'TMOUT': '86400', 19:18:26 INFO - 'USER': 'cltbld', 19:18:26 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:26 INFO - '_': '/tools/buildbot/bin/python'} 19:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:27 INFO - Downloading/unpacking psutil>=3.1.1 19:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:18:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:18:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:18:29 INFO - Installing collected packages: psutil 19:18:29 INFO - Running setup.py install for psutil 19:18:29 INFO - building 'psutil._psutil_linux' extension 19:18:29 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 19:18:29 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 19:18:29 INFO - building 'psutil._psutil_posix' extension 19:18:29 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 19:18:29 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 19:18:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:18:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:18:29 INFO - Successfully installed psutil 19:18:29 INFO - Cleaning up... 19:18:29 INFO - Return code: 0 19:18:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:18:29 INFO - Using env: {'DISPLAY': ':0', 19:18:29 INFO - 'HOME': '/home/cltbld', 19:18:29 INFO - 'LANG': 'en_US.UTF-8', 19:18:29 INFO - 'LANGUAGE': 'en_US:en', 19:18:29 INFO - 'LOGNAME': 'cltbld', 19:18:29 INFO - 'MAIL': '/var/mail/cltbld', 19:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:29 INFO - 'MOZ_NO_REMOTE': '1', 19:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:29 INFO - 'NO_EM_RESTART': '1', 19:18:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:29 INFO - 'PWD': '/builds/slave/test', 19:18:29 INFO - 'SHELL': '/bin/bash', 19:18:29 INFO - 'SHLVL': '1', 19:18:29 INFO - 'TERM': 'linux', 19:18:29 INFO - 'TMOUT': '86400', 19:18:29 INFO - 'USER': 'cltbld', 19:18:29 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:29 INFO - '_': '/tools/buildbot/bin/python'} 19:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:18:29 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 19:18:29 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 19:18:29 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 19:18:29 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 19:18:29 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 19:18:29 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 19:18:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:18:31 INFO - Installing collected packages: mozsystemmonitor 19:18:31 INFO - Running setup.py install for mozsystemmonitor 19:18:31 INFO - Successfully installed mozsystemmonitor 19:18:31 INFO - Cleaning up... 19:18:31 INFO - Return code: 0 19:18:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:18:31 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')]} 19:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:31 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')]} 19:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:31 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:31 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 19:18:31 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 19:18:31 INFO - Using env: {'DISPLAY': ':0', 19:18:31 INFO - 'HOME': '/home/cltbld', 19:18:31 INFO - 'LANG': 'en_US.UTF-8', 19:18:31 INFO - 'LANGUAGE': 'en_US:en', 19:18:31 INFO - 'LOGNAME': 'cltbld', 19:18:31 INFO - 'MAIL': '/var/mail/cltbld', 19:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:31 INFO - 'MOZ_NO_REMOTE': '1', 19:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:31 INFO - 'NO_EM_RESTART': '1', 19:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:31 INFO - 'PWD': '/builds/slave/test', 19:18:31 INFO - 'SHELL': '/bin/bash', 19:18:31 INFO - 'SHLVL': '1', 19:18:31 INFO - 'TERM': 'linux', 19:18:31 INFO - 'TMOUT': '86400', 19:18:31 INFO - 'USER': 'cltbld', 19:18:31 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:31 INFO - '_': '/tools/buildbot/bin/python'} 19:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:32 INFO - Downloading/unpacking jsonschema==2.5.1 19:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:18:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:18:33 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 19:18:33 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 19:18:33 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 19:18:33 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 19:18:33 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 19:18:33 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 19:18:34 INFO - Downloading functools32-3.2.3-2.tar.gz 19:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:18:34 INFO - warning: no files found matching '*.txt' 19:18:34 INFO - no previously-included directories found matching 'build' 19:18:34 INFO - no previously-included directories found matching 'dist' 19:18:34 INFO - no previously-included directories found matching '.git*' 19:18:34 INFO - Installing collected packages: jsonschema, functools32 19:18:34 INFO - Running setup.py install for functools32 19:18:34 INFO - warning: no files found matching '*.txt' 19:18:34 INFO - no previously-included directories found matching 'build' 19:18:34 INFO - no previously-included directories found matching 'dist' 19:18:34 INFO - no previously-included directories found matching '.git*' 19:18:34 INFO - Successfully installed jsonschema functools32 19:18:34 INFO - Cleaning up... 19:18:34 INFO - Return code: 0 19:18:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:34 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:34 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 19:18:34 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 19:18:34 INFO - Using env: {'DISPLAY': ':0', 19:18:34 INFO - 'HOME': '/home/cltbld', 19:18:34 INFO - 'LANG': 'en_US.UTF-8', 19:18:34 INFO - 'LANGUAGE': 'en_US:en', 19:18:34 INFO - 'LOGNAME': 'cltbld', 19:18:34 INFO - 'MAIL': '/var/mail/cltbld', 19:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:34 INFO - 'MOZ_NO_REMOTE': '1', 19:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:34 INFO - 'NO_EM_RESTART': '1', 19:18:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:34 INFO - 'PWD': '/builds/slave/test', 19:18:34 INFO - 'SHELL': '/bin/bash', 19:18:34 INFO - 'SHLVL': '1', 19:18:34 INFO - 'TERM': 'linux', 19:18:34 INFO - 'TMOUT': '86400', 19:18:34 INFO - 'USER': 'cltbld', 19:18:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:34 INFO - '_': '/tools/buildbot/bin/python'} 19:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:18:34 INFO - Cleaning up... 19:18:34 INFO - Return code: 0 19:18:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:34 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:34 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 19:18:34 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 19:18:34 INFO - Using env: {'DISPLAY': ':0', 19:18:34 INFO - 'HOME': '/home/cltbld', 19:18:34 INFO - 'LANG': 'en_US.UTF-8', 19:18:34 INFO - 'LANGUAGE': 'en_US:en', 19:18:34 INFO - 'LOGNAME': 'cltbld', 19:18:34 INFO - 'MAIL': '/var/mail/cltbld', 19:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:34 INFO - 'MOZ_NO_REMOTE': '1', 19:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:34 INFO - 'NO_EM_RESTART': '1', 19:18:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:34 INFO - 'PWD': '/builds/slave/test', 19:18:34 INFO - 'SHELL': '/bin/bash', 19:18:34 INFO - 'SHLVL': '1', 19:18:34 INFO - 'TERM': 'linux', 19:18:34 INFO - 'TMOUT': '86400', 19:18:34 INFO - 'USER': 'cltbld', 19:18:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:34 INFO - '_': '/tools/buildbot/bin/python'} 19:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:34 INFO - Downloading/unpacking blobuploader==1.2.4 19:18:34 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 19:18:34 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 19:18:34 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 19:18:34 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 19:18:34 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 19:18:34 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 19:18:36 INFO - Downloading blobuploader-1.2.4.tar.gz 19:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:18:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:18:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:18:37 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 19:18:37 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 19:18:37 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 19:18:37 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 19:18:37 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 19:18:37 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 19:18:37 INFO - Downloading docopt-0.6.1.tar.gz 19:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:18:37 INFO - Installing collected packages: blobuploader, requests, docopt 19:18:37 INFO - Running setup.py install for blobuploader 19:18:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:18:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:18:37 INFO - Running setup.py install for requests 19:18:37 INFO - Running setup.py install for docopt 19:18:38 INFO - Successfully installed blobuploader requests docopt 19:18:38 INFO - Cleaning up... 19:18:38 INFO - Return code: 0 19:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:18: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')]} 19:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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')]} 19:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:38 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:38 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 19:18:38 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 19:18:38 INFO - Using env: {'DISPLAY': ':0', 19:18:38 INFO - 'HOME': '/home/cltbld', 19:18:38 INFO - 'LANG': 'en_US.UTF-8', 19:18:38 INFO - 'LANGUAGE': 'en_US:en', 19:18:38 INFO - 'LOGNAME': 'cltbld', 19:18:38 INFO - 'MAIL': '/var/mail/cltbld', 19:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:38 INFO - 'MOZ_NO_REMOTE': '1', 19:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:38 INFO - 'NO_EM_RESTART': '1', 19:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:38 INFO - 'PWD': '/builds/slave/test', 19:18:38 INFO - 'SHELL': '/bin/bash', 19:18:38 INFO - 'SHLVL': '1', 19:18:38 INFO - 'TERM': 'linux', 19:18:38 INFO - 'TMOUT': '86400', 19:18:38 INFO - 'USER': 'cltbld', 19:18:38 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:38 INFO - '_': '/tools/buildbot/bin/python'} 19:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:18:38 INFO - Running setup.py (path:/tmp/pip-BfBZSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:18:38 INFO - Running setup.py (path:/tmp/pip-pBm69D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:18:38 INFO - Running setup.py (path:/tmp/pip-Tgh3oW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:18:38 INFO - Running setup.py (path:/tmp/pip-iakoBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:18:38 INFO - Running setup.py (path:/tmp/pip-RIlbJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:18:38 INFO - Running setup.py (path:/tmp/pip-tzpy9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:18:38 INFO - Running setup.py (path:/tmp/pip-zaWwka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:18:38 INFO - Running setup.py (path:/tmp/pip-n8BLlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:18:38 INFO - Running setup.py (path:/tmp/pip-XKMWv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:18:38 INFO - Running setup.py (path:/tmp/pip-9NnP02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:18:39 INFO - Running setup.py (path:/tmp/pip-9MB4vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:18:39 INFO - Running setup.py (path:/tmp/pip-XpgOSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:18:39 INFO - Running setup.py (path:/tmp/pip-7ZxrzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:18:39 INFO - Running setup.py (path:/tmp/pip-LA1M4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:39 INFO - Running setup.py (path:/tmp/pip-PZouY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:18:39 INFO - Running setup.py (path:/tmp/pip-n7ZTpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:18:39 INFO - Running setup.py (path:/tmp/pip-6SVzG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:18:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:18:39 INFO - Running setup.py install for manifestparser 19:18:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:18:39 INFO - Running setup.py install for mozcrash 19:18:39 INFO - Running setup.py install for mozdebug 19:18:39 INFO - Running setup.py install for mozdevice 19:18:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:18:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:18:39 INFO - Running setup.py install for mozfile 19:18:40 INFO - Running setup.py install for mozhttpd 19:18:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Running setup.py install for mozinfo 19:18:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Running setup.py install for mozInstall 19:18:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Running setup.py install for mozleak 19:18:40 INFO - Running setup.py install for mozlog 19:18:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Running setup.py install for moznetwork 19:18:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Running setup.py install for mozprocess 19:18:40 INFO - Running setup.py install for mozprofile 19:18:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:18:40 INFO - Running setup.py install for mozrunner 19:18:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:18:41 INFO - Running setup.py install for mozscreenshot 19:18:41 INFO - Running setup.py install for moztest 19:18:41 INFO - Running setup.py install for mozversion 19:18:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:18:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:18:41 INFO - Cleaning up... 19:18:41 INFO - Return code: 0 19:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:18: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')]} 19:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18: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')]} 19:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:41 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:41 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 19:18:41 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 19:18:41 INFO - Using env: {'DISPLAY': ':0', 19:18:41 INFO - 'HOME': '/home/cltbld', 19:18:41 INFO - 'LANG': 'en_US.UTF-8', 19:18:41 INFO - 'LANGUAGE': 'en_US:en', 19:18:41 INFO - 'LOGNAME': 'cltbld', 19:18:41 INFO - 'MAIL': '/var/mail/cltbld', 19:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:41 INFO - 'MOZ_NO_REMOTE': '1', 19:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:41 INFO - 'NO_EM_RESTART': '1', 19:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:41 INFO - 'PWD': '/builds/slave/test', 19:18:41 INFO - 'SHELL': '/bin/bash', 19:18:41 INFO - 'SHLVL': '1', 19:18:41 INFO - 'TERM': 'linux', 19:18:41 INFO - 'TMOUT': '86400', 19:18:41 INFO - 'USER': 'cltbld', 19:18:41 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:41 INFO - '_': '/tools/buildbot/bin/python'} 19:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:18:41 INFO - Running setup.py (path:/tmp/pip-n_myUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:18:41 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)) 19:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:18:41 INFO - Running setup.py (path:/tmp/pip-jtgZSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:18:41 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)) 19:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:18:41 INFO - Running setup.py (path:/tmp/pip-lIoybo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:18:41 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)) 19:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:18:41 INFO - Running setup.py (path:/tmp/pip-ubyHse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:18:41 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)) 19:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:18:41 INFO - Running setup.py (path:/tmp/pip-8gGzQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:18:41 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)) 19:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:18:41 INFO - Running setup.py (path:/tmp/pip-1QXdgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:18:41 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)) 19:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:18:41 INFO - Running setup.py (path:/tmp/pip-91q2Me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:18:42 INFO - Running setup.py (path:/tmp/pip-XK_IT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:18:42 INFO - Running setup.py (path:/tmp/pip-8hxSHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:18:42 INFO - Running setup.py (path:/tmp/pip-XNAJxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:18:42 INFO - Running setup.py (path:/tmp/pip-guE4fQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:18:42 INFO - Running setup.py (path:/tmp/pip-6DZ6uE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:18:42 INFO - Running setup.py (path:/tmp/pip-igjZLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:18:42 INFO - Running setup.py (path:/tmp/pip-Pb7r4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:42 INFO - Running setup.py (path:/tmp/pip-rKVKzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:18:42 INFO - Running setup.py (path:/tmp/pip-2E8aC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:18:42 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)) 19:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:18:42 INFO - Running setup.py (path:/tmp/pip-9Gmks2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:18:42 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)) 19:18:42 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)) 19:18:42 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)) 19:18:42 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)) 19:18:42 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)) 19:18:42 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)) 19:18:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:18:42 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 19:18:42 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 19:18:42 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 19:18:42 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 19:18:42 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 19:18:42 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 19:18:44 INFO - Downloading blessings-1.6.tar.gz 19:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:18:44 INFO - Installing collected packages: blessings 19:18:44 INFO - Running setup.py install for blessings 19:18:44 INFO - Successfully installed blessings 19:18:44 INFO - Cleaning up... 19:18:44 INFO - Return code: 0 19:18:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:18:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:18:45 INFO - Reading from file tmpfile_stdout 19:18:45 INFO - Current package versions: 19:18:45 INFO - argparse == 1.2.1 19:18:45 INFO - blessings == 1.6 19:18:45 INFO - blobuploader == 1.2.4 19:18:45 INFO - docopt == 0.6.1 19:18:45 INFO - functools32 == 3.2.3-2 19:18:45 INFO - jsonschema == 2.5.1 19:18:45 INFO - manifestparser == 1.1 19:18:45 INFO - mozInstall == 1.12 19:18:45 INFO - mozcrash == 0.17 19:18:45 INFO - mozdebug == 0.1 19:18:45 INFO - mozdevice == 0.48 19:18:45 INFO - mozfile == 1.2 19:18:45 INFO - mozhttpd == 0.7 19:18:45 INFO - mozinfo == 0.9 19:18:45 INFO - mozleak == 0.1 19:18:45 INFO - mozlog == 3.3 19:18:45 INFO - moznetwork == 0.27 19:18:45 INFO - mozprocess == 0.23 19:18:45 INFO - mozprofile == 0.28 19:18:45 INFO - mozrunner == 6.12 19:18:45 INFO - mozscreenshot == 0.1 19:18:45 INFO - mozsystemmonitor == 0.3 19:18:45 INFO - moztest == 0.7 19:18:45 INFO - mozversion == 1.4 19:18:45 INFO - psutil == 3.1.1 19:18:45 INFO - requests == 1.2.3 19:18:45 INFO - wsgiref == 0.1.2 19:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:18:45 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')]} 19:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:45 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')]} 19:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:45 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:45 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 19:18:45 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 19:18:45 INFO - Using env: {'DISPLAY': ':0', 19:18:45 INFO - 'HOME': '/home/cltbld', 19:18:45 INFO - 'LANG': 'en_US.UTF-8', 19:18:45 INFO - 'LANGUAGE': 'en_US:en', 19:18:45 INFO - 'LOGNAME': 'cltbld', 19:18:45 INFO - 'MAIL': '/var/mail/cltbld', 19:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:45 INFO - 'MOZ_NO_REMOTE': '1', 19:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:45 INFO - 'NO_EM_RESTART': '1', 19:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:45 INFO - 'PWD': '/builds/slave/test', 19:18:45 INFO - 'SHELL': '/bin/bash', 19:18:45 INFO - 'SHLVL': '1', 19:18:45 INFO - 'TERM': 'linux', 19:18:45 INFO - 'TMOUT': '86400', 19:18:45 INFO - 'USER': 'cltbld', 19:18:45 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:45 INFO - '_': '/tools/buildbot/bin/python'} 19:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 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)) 19:18:45 INFO - Cleaning up... 19:18:45 INFO - Return code: 0 19:18:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:18:45 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')]} 19:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:45 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')]} 19:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:45 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 0x7f70d5764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, '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': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', '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 19:18:45 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 19:18:45 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 19:18:45 INFO - Using env: {'DISPLAY': ':0', 19:18:45 INFO - 'HOME': '/home/cltbld', 19:18:45 INFO - 'LANG': 'en_US.UTF-8', 19:18:45 INFO - 'LANGUAGE': 'en_US:en', 19:18:45 INFO - 'LOGNAME': 'cltbld', 19:18:45 INFO - 'MAIL': '/var/mail/cltbld', 19:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:45 INFO - 'MOZ_NO_REMOTE': '1', 19:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:18:45 INFO - 'NO_EM_RESTART': '1', 19:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:45 INFO - 'PWD': '/builds/slave/test', 19:18:45 INFO - 'SHELL': '/bin/bash', 19:18:45 INFO - 'SHLVL': '1', 19:18:45 INFO - 'TERM': 'linux', 19:18:45 INFO - 'TMOUT': '86400', 19:18:45 INFO - 'USER': 'cltbld', 19:18:45 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:45 INFO - '_': '/tools/buildbot/bin/python'} 19:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:18:45 INFO - Cleaning up... 19:18:45 INFO - Return code: 0 19:18:45 INFO - Running post-action listener: _resource_record_post_action 19:18:45 INFO - Running post-action listener: _start_resource_monitoring 19:18:45 INFO - Starting resource monitoring. 19:18:45 INFO - [mozharness: 2016-08-23 02:18:45.581113Z] Finished create-virtualenv step (success) 19:18:45 INFO - [mozharness: 2016-08-23 02:18:45.581604Z] Running install step. 19:18:45 INFO - Running pre-action listener: _resource_record_pre_action 19:18:45 INFO - Running main action method: install 19:18:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:18:45 INFO - Reading from file tmpfile_stdout 19:18:45 INFO - Detecting whether we're running mozinstall >=1.0... 19:18:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:18:45 INFO - Reading from file tmpfile_stdout 19:18:45 INFO - Output received: 19:18:45 INFO - Usage: mozinstall [options] installer 19:18:45 INFO - Options: 19:18:45 INFO - -h, --help show this help message and exit 19:18:45 INFO - -d DEST, --destination=DEST 19:18:45 INFO - Directory to install application into. [default: 19:18:45 INFO - "/builds/slave/test"] 19:18:45 INFO - --app=APP Application being installed. [default: firefox] 19:18:45 INFO - mkdir: /builds/slave/test/build/application 19:18:45 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'] 19:18:45 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 19:19:02 INFO - Reading from file tmpfile_stdout 19:19:02 INFO - Output received: 19:19:02 INFO - /builds/slave/test/build/application/firefox/firefox 19:19:02 INFO - Running post-action listener: _resource_record_post_action 19:19:02 INFO - [mozharness: 2016-08-23 02:19:02.871621Z] Finished install step (success) 19:19:02 INFO - [mozharness: 2016-08-23 02:19:02.871873Z] Running run-tests step. 19:19:02 INFO - Running pre-action listener: _resource_record_pre_action 19:19:02 INFO - Running main action method: run_tests 19:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:19:02 INFO - Python 2.7.3 19:19:02 INFO - Return code: 0 19:19:02 INFO - grabbing minidump binary from tooltool 19:19:02 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')]} 19:19:02 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 0x11b6090>, '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 0x11b99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11b9e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:19:02 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 19:19:02 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 19:19:02 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 19:19:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:19:02 INFO - Return code: 0 19:19:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:19:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:19:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:19:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:19:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 19:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 19:19:02 INFO - Using env: {'DISPLAY': ':0', 19:19:02 INFO - 'HOME': '/home/cltbld', 19:19:02 INFO - 'LANG': 'en_US.UTF-8', 19:19:02 INFO - 'LANGUAGE': 'en_US:en', 19:19:02 INFO - 'LOGNAME': 'cltbld', 19:19:02 INFO - 'MAIL': '/var/mail/cltbld', 19:19:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:19:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:02 INFO - 'MOZ_NO_REMOTE': '1', 19:19:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:19:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:02 INFO - 'NO_EM_RESTART': '1', 19:19:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:02 INFO - 'PWD': '/builds/slave/test', 19:19:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:19:02 INFO - 'SHELL': '/bin/bash', 19:19:02 INFO - 'SHLVL': '1', 19:19:02 INFO - 'TERM': 'linux', 19:19:02 INFO - 'TMOUT': '86400', 19:19:02 INFO - 'USER': 'cltbld', 19:19:02 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239', 19:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:02 INFO - '_': '/tools/buildbot/bin/python'} 19:19:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 19:19:03 INFO - mozversion application_buildid: 20160822163003 19:19:03 INFO - mozversion application_changeset: 7fab74549bbabc0ab0e64a19fd83f4c5660716b4 19:19:03 INFO - mozversion application_display_name: Nightly 19:19:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:19:03 INFO - mozversion application_name: Firefox 19:19:03 INFO - mozversion application_remotingname: firefox 19:19:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:19:03 INFO - mozversion application_vendor: Mozilla 19:19:03 INFO - mozversion application_version: 51.0a1 19:19:03 INFO - mozversion platform_buildid: 20160822163003 19:19:03 INFO - mozversion platform_changeset: 7fab74549bbabc0ab0e64a19fd83f4c5660716b4 19:19:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:19:03 INFO - mozversion platform_version: 51.0a1 19:19:03 INFO - using testdate: 1471918743 19:19:03 INFO - actual date: 1471918743 19:19:03 INFO - starting webserver on 'localhost:58798' 19:19:03 INFO - SUITE-START | Running 2 tests 19:19:03 INFO - TEST-START | dromaeo_css 19:19:03 INFO - operating with platform_type : linux_ 19:19:03 INFO - Initialising browser for dromaeo_css test... 19:19:03 INFO - TEST-INFO | started process 24511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3JsV8P/profile http://localhost:58798/getInfo.html) 19:19:07 INFO - PROCESS | 24511 | __metrics Screen width/height:1600/1200 19:19:07 INFO - PROCESS | 24511 | colorDepth:24 19:19:07 INFO - PROCESS | 24511 | Browser inner width/height: 1024/697 19:19:07 INFO - PROCESS | 24511 | __metrics 19:19:07 INFO - PROCESS | 24511 | 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" 19:19:07 INFO - PROCESS | 24511 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:19:08 INFO - PROCESS | 24511 | console.error: PushService: 19:19:08 INFO - PROCESS | 24511 | stateChangeProcessEnqueue: Error transitioning state 19:19:08 INFO - PROCESS | 24511 | UnknownError 19:19:09 INFO - TEST-INFO | 24511: exit 0 19:19:09 INFO - Browser initialized. 19:19:09 INFO - Running cycle 1/1 for dromaeo_css test... 19:19:09 INFO - TEST-INFO | started process 24595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3JsV8P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:21 INFO - PROCESS | 24595 | RSS: Main: 181682176 19:19:21 INFO - PROCESS | 24595 | 19:20:12 INFO - PROCESS | 24595 | 1471918812764 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] 19:22:22 INFO - PROCESS | 24595 | Cycle 1(1): loaded http://localhost:58798/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58798/tests/dromaeo/cssquery-ext.html) 19:22:23 INFO - PROCESS | 24595 | RSS: Main: 301563904 19:22:23 INFO - PROCESS | 24595 | 19:25:26 INFO - PROCESS | 24595 | Cycle 1(1): loaded http://localhost:58798/tests/dromaeo/cssquery-ext.html (next: http://localhost:58798/tests/dromaeo/cssquery-jquery.html) 19:25:27 INFO - PROCESS | 24595 | RSS: Main: 272048128 19:25:27 INFO - PROCESS | 24595 | 19:28:29 INFO - PROCESS | 24595 | Cycle 1(1): loaded http://localhost:58798/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58798/tests/dromaeo/cssquery-mootools.html) 19:28:29 INFO - PROCESS | 24595 | RSS: Main: 219656192 19:28:29 INFO - PROCESS | 24595 | 19:31:32 INFO - PROCESS | 24595 | Cycle 1(1): loaded http://localhost:58798/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58798/tests/dromaeo/cssquery-prototype.html) 19:31:32 INFO - PROCESS | 24595 | RSS: Main: 258342912 19:31:32 INFO - PROCESS | 24595 | 19:34:35 INFO - PROCESS | 24595 | Cycle 1(1): loaded http://localhost:58798/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58798/tests/dromaeo/cssquery-yui.html) 19:34:36 INFO - PROCESS | 24595 | RSS: Main: 221089792 19:34:36 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | Cycle 1(1): loaded http://localhost:58798/tests/dromaeo/cssquery-yui.html (next: http://localhost:58798/tests/dromaeo/cssquery-dojo.html) 19:37:39 INFO - PROCESS | 24595 | RSS: Main: 310484992 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | __start_tp_report 19:37:39 INFO - PROCESS | 24595 | _x_x_mozilla_page_load 19:37:39 INFO - PROCESS | 24595 | _x_x_mozilla_page_load_details 19:37:39 INFO - PROCESS | 24595 | |i|pagename|runs| 19:37:39 INFO - PROCESS | 24595 | |0;dojo.html;3006.5189569668855;3014.1259034879886;3031.621047369079;3055.5874956880843;3088.8301143437116;5236.502532259438;5293.947060529403;5300.257963885055;5376.059189641816;5424.403315635282;5311.9468805311935;5394.676319420828;5417.079096553585;5421.9457805421935;5497.505224529791;4919.26211068339;4970.627202959774;4979.377577802775;4990.251462280668;4994.500549944994;4901.068796928572;4918.532739389752;4947.356843610343;4985.252212168186;5027.994401119772;5044.167712327461;5076.695398276097;5083.085044691954;5088;5109.616778741591;4790.544898234697;4958.107540642683;4966.776495057731;5014.323066386064;5020.623453241022;4807.543283388072;4837.032593481283;4841.685290456109;4845.806167753286;4869;5311.442298558673;5325.627206095536;5352.545033672142;5378.139497680352;5398.703071331075;5823.505002074787;5826.300843898759;5831.358550559435;5834.6790926499025;5843;5639.379668236491;5709.543236541067;5718.656880587178;5762.567807414419;5855.180274761537;5355.250264962909;5362.1420572708175;5371.57027437804;5377.704226267515;5445.646033007882;5870.178175055496;5891.440313170242;5892.351841297454;5893.233879595625;5900.64596124234;5561.77752889888;5642.548596112301;5680.11958146488;5711.428857114255;5729.799457018984;5772.509336706427;5773.971130144322;5778.711064446845;5779.33537643168;5819.272590926134;5748.597598168088;5762.135679648086;5764.827055188351;5766.4233576642;5766.884662306814;5500.80747173847;5608.46719561641;5610.607257491936;5701.71491425427;5771.971140144273;4413.205622987829;4414.6247568957;4418.977905110454;4420.80106395213;4451.465824101064;3959.5842436544326;3978.403239514096;4007.699422543321;4010.6791456683395;4014.0566966762785;5481.835544933659;5488.4785945335125;5491.533219676292;5492.725363731797;5496.7526461309335;5653.773849045992;5739.225204597409;5772.6536407815665;5821.79623713168;5856.970715146397;5489.451054894558;5503.559715222773;5517.972410137923;5568.805091821767;5586.720663966786;4988.4263309719845;5019.799208031711;5039.84880453587;5054.267131265946;5073.086844367976;5856.531477481876;5975.731092100953;6014.1580178775;6016.849578760479;6030.698465076641;5839;5840.240768700041;5848.64908105515;5905.5570832186895;5910.734016969161;5983.431574000463;6035.094735789491;6035.969820150871;6037.215162028908;6038.547108966757;5606.6916319602815;5626.690532020778;5660.801871934463;5739.512141468022;5760.107183386582;5765.365809760839;5765.625234359753;5769.798057067983;5815.9709201453725;5848.239728835224;5624.746886390204;5634.661920284796;5635.6618602883955;5637.351704554028;5641;5530.474604912527;5625.8593535161945;5640.464155905182;5641;5658.094704847204;5627.324721033502;5642.23829782974;5646.689432081275;5692.686902220417;5707.800226992035;5627.52166065889;5715.685637289989;5724.8282551523525;5728.541716662574;5731.656100633975;5543.1685247213245;5569.888602228014;5571.693556854412;5583.609147359646;5608 19:37:39 INFO - PROCESS | 24595 | |1;ext.html;555.9277293951785;584.7953216374269;584.8771757930834;585.765693722511;589.7699897040158;28964.275893102684;36252.45621315679;37474.25051498972;37488;40093.19813603726;37740.24519509608;38122.23755524895;38155.236895262075;38164.42753358702;38206.42690359647;7737.922620773805;8451.450655707391;8606.56967151643;8607.053224145338;8608.48349099053;9850.50747462624;10184.59261629534;10214.795704085915;10547.208959328043;10564.313319634237;10315.071643552079;10403.271770976035;10414.166866650688;10418;10425.947870260638;10220.182385409149;10222.977702229791;10271.126954208901;10281.331713438678;10284.177265818755;10257.281990260684;10300.330478518872;10319.638812641597;10321.329113607666;10329.690109296733;35842;36059.81970090159;36135.09662258464;36156;36182.638173618456;2982.302818440019;3069.3707789903015;3129.85915633797;3273.7053665170247;3298.5217143514296;28467.145985620256;28937.56593651076;29260.70739292601;29476.852615737;30449.391012179854;4263.936040959388;4313.416489501411;4364.8472303469225;4391.802368893407;4429.867103986886;312552;314057;314558.99999999773;314939;315197;47806.5219347809;48385.27422088671;48403;48434;48519.5148048522;31615.20961975923;32181;32377.51433728496;33893.49159762605;33918.49122263168;13254.536091236952;13281.269530175932;13295.20228786276;13297.601071967663;13441.260730659713;9248.21390181842;10641.5211315491;10785.514651840685;10964.725881853017;11027.503762330714;15574.688506230039;15704.214789260492;15762.842371576367;15780.13209273478;15785.36858525669;5950.196723442366;6038.215032045894;6048.243969503812;6079.8480037998515;6132.724027418777;851.8637018077078;898.1647068226612;923.4782347973373;939.6476321379482;999.8150342186718;27701.584476232874;28933.42133157367;29091.7090829089;29378.853105734335;29675.554866677016;986.5461887531673;994.9950250248702;1025.2823023883311;1041.994790026045;1049.5959055763433;14919.254037298091;15196;15274.236288185546;15495;15570;545731;548566;554115;555943;800830;49047.50952490429;49597.25604115941;49716;49772.253416198786;49783.751081245086;1043.426115636412;1141.2182654881433;1159.4376727287258;1173.538944014707;1180.1974657233163;26986.86506567533;28678.283042924093;29270.7072929268;29285.560716589694;29322.85338573379;45362.319565205886;46892.12431502702;46898.53101468942;51487;51520;492650;494917;495363;496440;497832;29971;32009.679903201602;33678.66321336755;38527.807360964136;39495.802520987214;40389.394159088224;40472.79763601163;41157;41166.1766764669;41620.58379416167;35619.28761424809;35916;35978.28043439169;36432.08919777027;36508.634913651586;36381.63618363888;36874.44688329731;36966.260674785815;37359.81320093382;37620;34561.48157777585;35046.82476587703;35075.12312192113;35082.473762894086;35828.82085589555;30184.849075754482;32528.34943301168;32593.348133036732;32639.67360326366;32731.181720457178;25267.241982740547;25788.484230315666;25830.741692582833;25994;26202.344941376617 19:37:39 INFO - PROCESS | 24595 | |2;jquery.html;351.6430822714946;370.88131797824707;371.9776813391196;376.55566431610697;390.9589493103224;17452;18450.63098738026;18598.535036624093;18717.34489292875;19386.418407447763;15328.15695136767;16135.418333066711;16171.595710107253;16178.433754818583;16256.268467918942;5426.972865135669;5511.145772405283;5641.069223578104;5655.604107712462;5657.462541058613;4037.37420699792;5592.273004509408;5642.266505354298;5658.462446067617;5921.526277897758;6102.481289090432;6243.937560624404;6256.217972753406;6259.561830671855;6261.686915654199;6202.317745048041;6228.688565571726;6256.624602523863;6287.559870809046;6298.4331410172845;5542.140968149944;5559.972200138974;5565.554755619582;5566.304211973504;5566.777328906839;19269.325573604998;20101;20147.697784533244;20696.379108626767;21726.13095476179;2311.202635090891;2478.3680161558686;2684.3825920038503;2809.1712944681162;2950.1592046266924;9199.816003679889;9562.091601297865;9937.5031248438;11831.940840295743;12979.766922142477;1796.119901248379;1887.1507821480388;1888.3296429767372;1895.4408449507428;1907.7233801098903;74646.25353746502;75706.99999999945;78642.60678696627;82531;84819.5759021207;10252.436116013541;10316.896831031743;10643.787124257626;10652.840207396895;10855;14285.92857035729;14453.49412770548;14461.638459038608;14486;14502.927485362507;6940.7917762467205;7424.331810137113;7424.85150296991;7467.215942326086;7580.241975802375;6623.701933413008;6830.487713421513;6989;7017.508774385846;7308.342249197598;4219.641330486882;4334.544872788375;4354.542773008789;4408.977955110204;4563;1821.2714914034227;1839.45736007878;1873.7938826729048;1892.2525602387254;1944.7666280046485;731.7109741652017;864.2826454043129;886.3485338276362;910.9863352049724;934.7896723237217;16818;17733.822661773476;18218.63562728738;18235.27058917655;18387.632247354977;847.1316900177368;901.1304091551684;910.4446287764591;932.1005229953128;947.8957314695378;9842.163416109712;10331.74170645725;10357.482125893675;10383.94808025955;10417.791644167075;396723;435412;472787;476069;477876;93050.7644159142;94663;96677.51661241648;97141;98120.50939745255;839.8530257205086;874.6151693255075;884.3014018924977;886.1183122792862;886.7605746448423;15669.608259793597;16456;16778.24497897622;16841.494755157364;16996;40354.596454036255;42837.35743963781;43463.56536434595;44121.55878441175;44881.55118448774;51868.481315186364;59812.401875982425;61457;62226.68886655538;62834;6905.1713794345;7498.962505187439;7593.810154746176;7726.497777644435;7851.568163751049;11327.490262938352;12064.75870482568;12741.8088728671;13069;13393.397297121459;11181.217314787888;11203.831942521032;11466.082713383075;11618.825717614074;11823.231489953125;10752.35485870819;10890.346579205274;11072.446377680923;11624.302541847515;11675.182737208313;11501.367424791717;11568.768624627628;11629.127815414047;11827.52689892429;12035.676075631849;7282;7358.264173582599;7404.888926665996;7708.653110610148;7928.207179282025;10629.149668026384;10645.414502202453;11156.219064665396;11310.321380716854;11466.713332166762 19:37:39 INFO - PROCESS | 24595 | |3;mootools.html;1148.6381789736238;1247.6319485751706;1248.2073883084238;1262.463453032461;1414.4342263094761;7464.813379665505;8236.094029656733;8521.744347669564;8535.829283414329;8731.99582048063;8529.488230706147;8558.101399353072;8564.400491965565;8585.399022068443;8594.140585941403;2404.7234568024683;2431.756824317572;2435.841670291434;2438.5244877248942;2440;2403.387136280246;2405.458771776354;2417.685700858886;2421.6125419932814;2451.963220551693;2348.43637526994;2379.274321331993;2381.3570336009266;2416.685830841997;2420.9636855447093;2331.7318508371586;2334.2880421471446;2347.5304939012267;2352.2590384028977;2389.1518510928545;2320.3734991552355;2344.9648255276184;2350.8589484630975;2352.6588644646604;2360.8465449745713;8491.915080849236;8603.225709686096;8653.567321633956;8658.307335413216;8665.306775457948;5031;5240.423553409122;5242.764075616568;5259.815906443256;5270.631055826094;3583.211693427442;3680.1351682354625;3704.3517384457614;3741.8316175772156;3750.49368335274;1235.5552001279532;1273.1469915156868;1284.6659868434274;1311.4557458654647;1335.038772084098;12231.204971676812;12323;12334.62996110109;12378.504859805596;12400.255984640951;4317.201317756225;4505.256632655575;4615.284630882219;4660.04469083837;4667.696599721007;9451.905480945241;9470.479123648265;9476.715698529055;9480.525973701288;9499.24006079512;5113.590912726973;5118.61610379223;5127.07712611726;5132.435432102466;5134.383873935151;4146.813393397304;4521.163584736834;4679.625629949596;4691.13214055394;4779.761011949388;3654.3239500691925;3697.8705745298785;3774.6602805747616;3781.262653782486;3791.4881490998364;1174.4597485156753;1176.5293882446917;1179.4220831792531;1189.79178643737;1195.8863907928733;808.5391326943586;809.4293523066224;826.3884725303233;876.7895705030746;887.9556022198864;5698.857528561819;5779.306483221956;6006.489448396847;6067.757289708362;6101.511879049665;867.9262262707614;877.3989816975264;887.3344991256557;901.3150005995491;906.6146887572755;3961.8217180226957;4013.7190396671954;4047.372657238133;4059.65492933104;4108.465899433055;10878.728031799268;11294.322340659422;11322.716932076764;11341.092712583139;11357.318560886208;11177.552897884156;11186.664400068012;11203.887961120448;11209.43952802373;11227.66317010491;7614.923850761532;7791.454598178073;7872.881906771403;8030.755232938904;8173.959130204311;5912.142739302863;6056.1824153740445;6077.361877003028;6095.9390406097145;6112.1748563945075;10232.181425486093;10236.488175591041;10249.641262555775;10266.691999240034;10303.3817970922;9981.20338339106;10689.732756681145;10694.786104278026;10695.679129626124;10699.89300106989;6965.547239429421;7311.524750891388;7460.0301960744755;7763.0684317882215;7914.960425197837;8326.167383261867;8446.113158118309;8469.66121355139;8488.787780305542;8496.745097647081;7303.233160518282;7500.174980752222;7592.8861067082935;7623.771286861403;7648.34989025939;7302.342789149002;7313.561186328838;7321.707131714672;7369.189389167294;7665.271799179069;6795.28649491816;6905.620190889549;6920.550164239308;6920.688569014507;6927.549709268881;4839.370881785346;5076.238564215398;5135.922961155511;5145.382554093533;5173.1205695031185;4916.901661966669;5109.8722531937;5143;5145.536901678867;5328.120860058047 19:37:39 INFO - PROCESS | 24595 | |4;prototype.html;315.11091009398643;327.15430611868317;331.26128732925577;333.43982110055106;334.2429397414857;5027.974860125703;5171.4311425743135;5223.8694032649155;5295.6028297877665;5315.89368212636;5172.250023746564;5181.43004269531;5216.921746173811;5233.607479439038;5246.137621144487;4110.8972275693195;4116.238495878257;4118.320477121271;4128.8348466061325;4145.730527515701;3933.4099885017336;4071.979640101795;4139.089400331937;4144.23331683638;4150;4020.5778393268724;4041.4139949707273;4065.597368907737;4074.775887326196;4074.816633251496;4017.27689015977;4025.4565633639377;4025.5773143819915;4042.171354872244;4043.3934909763475;3569.4824250483794;3571.785692858437;3575.3921833288405;3582.695470884978;3600.387934051191;5073.746312684351;5080.491950804927;5105.33630628016;5140.768665410028;5142.07442660321;4627.907441851178;4640.814367425299;4649.837255696035;4657.953420465786;4658.976705116486;5413.593980451482;5437.32033495813;5455.1271796512365;5465.344158700942;5469.425710300401;3809.3714537101623;3860.4016377461257;3875.379939209713;3882.5340959084806;3925.3719404895364;5602.691851948141;5686.260786097822;5690.658560486343;5727.599068065237;5728.169415434781;3995.9800200998807;3999.7800120993043;4002.3596224603925;4006.659433948147;4019.738716983387;3874;4026.617471340218;4056.675465962716;4098.282800509899;4127.484064491939;4635.814567417266;4639.930401043987;4642.628589712815;4643.976780116078;4664.370310008173;5540.196671482612;5541.750621222055;5556.749516445846;5563.638363506359;5564.610477266633;1319.8680131986725;1377.793331000358;1385.0055919476124;1385.9951535136945;1392.0534036854833;934.4860326820221;941.8116376724682;945.5461378538203;961.7980224152866;964.8697425847421;5276.815311464157;5286.81496147633;5293.470652934676;5310.893782124336;5316.202569614511;5619.43805619443;5625.493705566519;5642.435756424325;5647.858803529863;5650.124230744241;5194.72732178725;5324.148136298251;5329.280547126166;5337;5348.652337598043;2978.463876502207;2979.702029797003;2986.357933044406;2996.580478732979;3003.354278830062;5834.766609335664;5843.970780146072;5865.413458654186;5867.9413205867395;5876.265466816648;5562.360328562187;5759.107338362561;5763.221965034667;5811.854703632358;5815.273090863642;5867;5876.588638795329;5894.4400281974;5897.410258974069;5898.997170481029;5412.75642596092;5624.943750562442;5684.175794509855;5701.144828275792;5718.399568045295;5605.887882242251;5627.831165065055;5665.745041473226;5668.093105103164;5670.914936276042;5884.852878678067;5898.20374249488;5908.720660033722;5909.3795151508475;5918.526517878646;3988.501437320335;4021.1354558770004;4034.8789536313957;4050.554439011765;4051.1897620475434;5393.325834270716;5629.268195134607;5634.915476267777;5652.915206271827;5655.717214139194;5401.3248343957;5533.833984980457;5539.556835453073;5546.89062187556;5559.305086864142;5498.285222920995;5534.280543529316;5537.667739935617;5540.833774986757;5541.2796336476;5264.868378290574;5424.83725488236;5472.890542189054;5479;5490.972545137249;5541.362743284574;5560.499555040065;5565.443455654402;5570.749316280858;5576.804811831566;5454.754536045808;5477.452254774491;5483.725813709379;5485;5518.91721624184 19:37:39 INFO - PROCESS | 24595 | |5;yui.html;588.8378122943398;601.6841158391846;606.854354954811;670.0518765946186;683.8700646877095;14060.789088163685;14114.64713382166;14609.561713148596;14786.630334241649;14796.408143674267;14003.649908752286;14045;14078.577642670736;14085.788713169284;14108.15351078936;576.7347020370635;590.5865893874284;594.9286085669726;595.8301883963071;596.1177457363106;476.10254669947227;572.9484346408823;587.6063037764708;589.6609449566495;592.0940960330679;606.8968275393194;610.2585358789071;610.825914614335;611.5291225756165;611.8623309755292;608.4523928464396;608.5892022884535;608.7443273825004;608.7534548507862;609.3327806052388;395.489818134606;403.5238418665973;405.1673810319792;438.48259054315895;447.4094195661728;12312;12572.371381430983;12616.432260548298;12632.2420654761;12637.557685480964;12139.939300303531;12441.315727634976;13053;13456.730865382735;13968.092074015258;12652.114351995364;12764.297963611998;12979.610611681665;12998.545050923267;13722.45110195591;4415.558444155591;4437.378766972626;4487.035287413222;4638.373819534387;4642.535746425364;89140;90357.54821225918;91429.54285228532;91903;92049;10397.84403233952;10438.63464778729;10774.676759697222;10788.190885683605;10804.08165305942;18160.1827917744;18342.423227470514;18418.907905460655;18442;18472.630547389246;8218.753437396768;8349.916500835036;8543.35924805642;8796.252318552866;8803.251723603438;4753.619710423158;4785.521447855256;4902.63230257725;4903.8528844134735;4961.280614310905;3168.588083549124;3361.394948909171;3369.3935091683734;3370.0732298618077;3372.1401042734437;596.582392325374;597.9521638268928;601.6330038676404;603.7916918663054;608.6104892868568;560.0507140396988;585.280105470274;673.7204060315013;674.5413119079074;686.9381755642016;8730.257928076184;9445.763855903657;9495.525223738923;10040.548175332127;10441.947790261;651.9119918810994;675.2167485716545;687.5908834243717;694.6769752065213;699.7375984005997;4408.360787685768;4580.891821475969;4584.535774703911;4747.406574178228;4801.68789028712;110051.44974275077;127967.3601631986;134895;135599;138260.70435773683;64264;67422.32577674258;71823;94952;110001.44999274952;57973;76445.6177719119;78358.6082069586;79141;82171.17828821755;9702.514874256118;9993.495886520666;10094.394336339843;10260.281780275309;10426.27016108865;52632.9473410522;53515;53739.92520149653;53741.73129134329;53819;42409.78795106005;43102.78448607737;43936.780316098215;78207.60896195483;81346.59326703328;11762.58830940913;12308.815367769312;12444.315562644142;12689.302088385228;13065.542706005244;13826.86173138296;14129.788053179416;14661.633459163606;15022.774658379914;15205.847941520444;13524.39140238672;14007;14108.435662573382;14161.504347347793;15116.622084447978;12364.876351236373;12406.317652529198;12551;12803.80794288105;12957.416916238602;13721.862781372058;13773.311334433038;13794.724105518035;13847.446102155802;13873.306334683428;10570.682879513626;10595.38925482118;10675.199360048182;10981.835272471078;10983.286086404358;10604.257701960789;11587.130965177477;11779.29324240514;11859.407029648655;11911.463984120563 19:37:39 INFO - PROCESS | 24595 | __end_tp_report 19:37:39 INFO - PROCESS | 24595 | __start_cc_report 19:37:39 INFO - PROCESS | 24595 | _x_x_mozilla_cycle_collect,1163 19:37:39 INFO - PROCESS | 24595 | __end_cc_report 19:37:39 INFO - PROCESS | 24595 | __startTimestamp1471919859293__endTimestamp 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | ------- Summary: start ------- 19:37:39 INFO - PROCESS | 24595 | Number of tests: 6 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | [#0] dojo.html Cycles:165 Average:5375.92 Median:5606.69 stddev:611.61 (10.9%) stddev-sans-first:584.56 19:37:39 INFO - PROCESS | 24595 | Values: 3006.5 3014.1 3031.6 3055.6 3088.8 5236.5 5293.9 5300.3 5376.1 5424.4 5311.9 5394.7 5417.1 5421.9 5497.5 4919.3 4970.6 4979.4 4990.3 4994.5 4901.1 4918.5 4947.4 4985.3 5028.0 5044.2 5076.7 5083.1 5088.0 5109.6 4790.5 4958.1 4966.8 5014.3 5020.6 4807.5 4837.0 4841.7 4845.8 4869.0 5311.4 5325.6 5352.5 5378.1 5398.7 5823.5 5826.3 5831.4 5834.7 5843.0 5639.4 5709.5 5718.7 5762.6 5855.2 5355.3 5362.1 5371.6 5377.7 5445.6 5870.2 5891.4 5892.4 5893.2 5900.6 5561.8 5642.5 5680.1 5711.4 5729.8 5772.5 5774.0 5778.7 5779.3 5819.3 5748.6 5762.1 5764.8 5766.4 5766.9 5500.8 5608.5 5610.6 5701.7 5772.0 4413.2 4414.6 4419.0 4420.8 4451.5 3959.6 3978.4 4007.7 4010.7 4014.1 5481.8 5488.5 5491.5 5492.7 5496.8 5653.8 5739.2 5772.7 5821.8 5857.0 5489.5 5503.6 5518.0 5568.8 5586.7 4988.4 5019.8 5039.8 5054.3 5073.1 5856.5 5975.7 6014.2 6016.8 6030.7 5839.0 5840.2 5848.6 5905.6 5910.7 5983.4 6035.1 6036.0 6037.2 6038.5 5606.7 5626.7 5660.8 5739.5 5760.1 5765.4 5765.6 5769.8 5816.0 5848.2 5624.7 5634.7 5635.7 5637.4 5641.0 5530.5 5625.9 5640.5 5641.0 5658.1 5627.3 5642.2 5646.7 5692.7 5707.8 5627.5 5715.7 5724.8 5728.5 5731.7 5543.2 5569.9 5571.7 5583.6 5608.0 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | [#1] ext.html Cycles:180 Average:59740.50 Median:34804.15 stddev:130708.62 (375.6%) stddev-sans-first:130999.76 19:37:39 INFO - PROCESS | 24595 | Values: 555.9 584.8 584.9 585.8 589.8 28964.3 36252.5 37474.3 37488.0 40093.2 37740.2 38122.2 38155.2 38164.4 38206.4 7737.9 8451.5 8606.6 8607.1 8608.5 9850.5 10184.6 10214.8 10547.2 10564.3 10315.1 10403.3 10414.2 10418.0 10425.9 10220.2 10223.0 10271.1 10281.3 10284.2 10257.3 10300.3 10319.6 10321.3 10329.7 35842.0 36059.8 36135.1 36156.0 36182.6 2982.3 3069.4 3129.9 3273.7 3298.5 28467.1 28937.6 29260.7 29476.9 30449.4 4263.9 4313.4 4364.8 4391.8 4429.9 312552.0 314057.0 314559.0 314939.0 315197.0 47806.5 48385.3 48403.0 48434.0 48519.5 31615.2 32181.0 32377.5 33893.5 33918.5 13254.5 13281.3 13295.2 13297.6 13441.3 9248.2 10641.5 10785.5 10964.7 11027.5 15574.7 15704.2 15762.8 15780.1 15785.4 5950.2 6038.2 6048.2 6079.8 6132.7 851.9 898.2 923.5 939.6 999.8 27701.6 28933.4 29091.7 29378.9 29675.6 986.5 995.0 1025.3 1042.0 1049.6 14919.3 15196.0 15274.2 15495.0 15570.0 545731.0 548566.0 554115.0 555943.0 800830.0 49047.5 49597.3 49716.0 49772.3 49783.8 1043.4 1141.2 1159.4 1173.5 1180.2 26986.9 28678.3 29270.7 29285.6 29322.9 45362.3 46892.1 46898.5 51487.0 51520.0 492650.0 494917.0 495363.0 496440.0 497832.0 29971.0 32009.7 33678.7 38527.8 39495.8 40389.4 40472.8 41157.0 41166.2 41620.6 35619.3 35916.0 35978.3 36432.1 36508.6 36381.6 36874.4 36966.3 37359.8 37620.0 34561.5 35046.8 35075.1 35082.5 35828.8 30184.8 32528.3 32593.3 32639.7 32731.2 25267.2 25788.5 25830.7 25994.0 26202.3 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | [#2] jquery.html Cycles:180 Average:27642.62 Median:23585.95 stddev:75075.53 (318.3%) stddev-sans-first:75258.17 19:37:39 INFO - PROCESS | 24595 | Values: 351.6 370.9 372.0 376.6 391.0 17452.0 18450.6 18598.5 18717.3 19386.4 15328.2 16135.4 16171.6 16178.4 16256.3 5427.0 5511.1 5641.1 5655.6 5657.5 4037.4 5592.3 5642.3 5658.5 5921.5 6102.5 6243.9 6256.2 6259.6 6261.7 6202.3 6228.7 6256.6 6287.6 6298.4 5542.1 5560.0 5565.6 5566.3 5566.8 19269.3 20101.0 20147.7 20696.4 21726.1 2311.2 2478.4 2684.4 2809.2 2950.2 9199.8 9562.1 9937.5 11831.9 12979.8 1796.1 1887.2 1888.3 1895.4 1907.7 74646.3 75707.0 78642.6 82531.0 84819.6 10252.4 10316.9 10643.8 10652.8 10855.0 14285.9 14453.5 14461.6 14486.0 14502.9 6940.8 7424.3 7424.9 7467.2 7580.2 6623.7 6830.5 6989.0 7017.5 7308.3 4219.6 4334.5 4354.5 4409.0 4563.0 1821.3 1839.5 1873.8 1892.3 1944.8 731.7 864.3 886.3 911.0 934.8 16818.0 17733.8 18218.6 18235.3 18387.6 847.1 901.1 910.4 932.1 947.9 9842.2 10331.7 10357.5 10383.9 10417.8 396723.0 435412.0 472787.0 476069.0 477876.0 93050.8 94663.0 96677.5 97141.0 98120.5 839.9 874.6 884.3 886.1 886.8 15669.6 16456.0 16778.2 16841.5 16996.0 40354.6 42837.4 43463.6 44121.6 44881.6 51868.5 59812.4 61457.0 62226.7 62834.0 6905.2 7499.0 7593.8 7726.5 7851.6 11327.5 12064.8 12741.8 13069.0 13393.4 11181.2 11203.8 11466.1 11618.8 11823.2 10752.4 10890.3 11072.4 11624.3 11675.2 11501.4 11568.8 11629.1 11827.5 12035.7 7282.0 7358.3 7404.9 7708.7 7928.2 10629.1 10645.4 11156.2 11310.3 11466.7 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | [#3] mootools.html Cycles:180 Average:5741.46 Median:4973.95 stddev:3286.47 (66.1%) stddev-sans-first:3277.56 19:37:39 INFO - PROCESS | 24595 | Values: 1148.6 1247.6 1248.2 1262.5 1414.4 7464.8 8236.1 8521.7 8535.8 8732.0 8529.5 8558.1 8564.4 8585.4 8594.1 2404.7 2431.8 2435.8 2438.5 2440.0 2403.4 2405.5 2417.7 2421.6 2452.0 2348.4 2379.3 2381.4 2416.7 2421.0 2331.7 2334.3 2347.5 2352.3 2389.2 2320.4 2345.0 2350.9 2352.7 2360.8 8491.9 8603.2 8653.6 8658.3 8665.3 5031.0 5240.4 5242.8 5259.8 5270.6 3583.2 3680.1 3704.4 3741.8 3750.5 1235.6 1273.1 1284.7 1311.5 1335.0 12231.2 12323.0 12334.6 12378.5 12400.3 4317.2 4505.3 4615.3 4660.0 4667.7 9451.9 9470.5 9476.7 9480.5 9499.2 5113.6 5118.6 5127.1 5132.4 5134.4 4146.8 4521.2 4679.6 4691.1 4779.8 3654.3 3697.9 3774.7 3781.3 3791.5 1174.5 1176.5 1179.4 1189.8 1195.9 808.5 809.4 826.4 876.8 888.0 5698.9 5779.3 6006.5 6067.8 6101.5 867.9 877.4 887.3 901.3 906.6 3961.8 4013.7 4047.4 4059.7 4108.5 10878.7 11294.3 11322.7 11341.1 11357.3 11177.6 11186.7 11203.9 11209.4 11227.7 7614.9 7791.5 7872.9 8030.8 8174.0 5912.1 6056.2 6077.4 6095.9 6112.2 10232.2 10236.5 10249.6 10266.7 10303.4 9981.2 10689.7 10694.8 10695.7 10699.9 6965.5 7311.5 7460.0 7763.1 7915.0 8326.2 8446.1 8469.7 8488.8 8496.7 7303.2 7500.2 7592.9 7623.8 7648.3 7302.3 7313.6 7321.7 7369.2 7665.3 6795.3 6905.6 6920.6 6920.7 6927.5 4839.4 5076.2 5135.9 5145.4 5173.1 4916.9 5109.9 5143.0 5145.5 5328.1 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | [#4] prototype.html Cycles:180 Average:4638.49 Median:5316.05 stddev:1374.65 (25.9%) stddev-sans-first:1339.66 19:37:39 INFO - PROCESS | 24595 | Values: 315.1 327.2 331.3 333.4 334.2 5028.0 5171.4 5223.9 5295.6 5315.9 5172.3 5181.4 5216.9 5233.6 5246.1 4110.9 4116.2 4118.3 4128.8 4145.7 3933.4 4072.0 4139.1 4144.2 4150.0 4020.6 4041.4 4065.6 4074.8 4074.8 4017.3 4025.5 4025.6 4042.2 4043.4 3569.5 3571.8 3575.4 3582.7 3600.4 5073.7 5080.5 5105.3 5140.8 5142.1 4627.9 4640.8 4649.8 4658.0 4659.0 5413.6 5437.3 5455.1 5465.3 5469.4 3809.4 3860.4 3875.4 3882.5 3925.4 5602.7 5686.3 5690.7 5727.6 5728.2 3996.0 3999.8 4002.4 4006.7 4019.7 3874.0 4026.6 4056.7 4098.3 4127.5 4635.8 4639.9 4642.6 4644.0 4664.4 5540.2 5541.8 5556.7 5563.6 5564.6 1319.9 1377.8 1385.0 1386.0 1392.1 934.5 941.8 945.5 961.8 964.9 5276.8 5286.8 5293.5 5310.9 5316.2 5619.4 5625.5 5642.4 5647.9 5650.1 5194.7 5324.1 5329.3 5337.0 5348.7 2978.5 2979.7 2986.4 2996.6 3003.4 5834.8 5844.0 5865.4 5867.9 5876.3 5562.4 5759.1 5763.2 5811.9 5815.3 5867.0 5876.6 5894.4 5897.4 5899.0 5412.8 5624.9 5684.2 5701.1 5718.4 5605.9 5627.8 5665.7 5668.1 5670.9 5884.9 5898.2 5908.7 5909.4 5918.5 3988.5 4021.1 4034.9 4050.6 4051.2 5393.3 5629.3 5634.9 5652.9 5655.7 5401.3 5533.8 5539.6 5546.9 5559.3 5498.3 5534.3 5537.7 5540.8 5541.3 5264.9 5424.8 5472.9 5479.0 5491.0 5541.4 5560.5 5565.4 5570.7 5576.8 5454.8 5477.5 5483.7 5485.0 5518.9 19:37:39 INFO - PROCESS | 24595 | 19:37:39 INFO - PROCESS | 24595 | [#5] yui.html Cycles:180 Average:20143.07 Median:21770.63 stddev:30161.33 (138.5%) stddev-sans-first:30210.21 19:37:39 INFO - PROCESS | 24595 | Values: 588.8 601.7 606.9 670.1 683.9 14060.8 14114.6 14609.6 14786.6 14796.4 14003.6 14045.0 14078.6 14085.8 14108.2 576.7 590.6 594.9 595.8 596.1 476.1 572.9 587.6 589.7 592.1 606.9 610.3 610.8 611.5 611.9 608.5 608.6 608.7 608.8 609.3 395.5 403.5 405.2 438.5 447.4 12312.0 12572.4 12616.4 12632.2 12637.6 12139.9 12441.3 13053.0 13456.7 13968.1 12652.1 12764.3 12979.6 12998.5 13722.5 4415.6 4437.4 4487.0 4638.4 4642.5 89140.0 90357.5 91429.5 91903.0 92049.0 10397.8 10438.6 10774.7 10788.2 10804.1 18160.2 18342.4 18418.9 18442.0 18472.6 8218.8 8349.9 8543.4 8796.3 8803.3 4753.6 4785.5 4902.6 4903.9 4961.3 3168.6 3361.4 3369.4 3370.1 3372.1 596.6 598.0 601.6 603.8 608.6 560.1 585.3 673.7 674.5 686.9 8730.3 9445.8 9495.5 10040.5 10441.9 651.9 675.2 687.6 694.7 699.7 4408.4 4580.9 4584.5 4747.4 4801.7 110051.4 127967.4 134895.0 135599.0 138260.7 64264.0 67422.3 71823.0 94952.0 110001.4 57973.0 76445.6 78358.6 79141.0 82171.2 9702.5 9993.5 10094.4 10260.3 10426.3 52632.9 53515.0 53739.9 53741.7 53819.0 42409.8 43102.8 43936.8 78207.6 81346.6 11762.6 12308.8 12444.3 12689.3 13065.5 13826.9 14129.8 14661.6 15022.8 15205.8 13524.4 14007.0 14108.4 14161.5 15116.6 12364.9 12406.3 12551.0 12803.8 12957.4 13721.9 13773.3 13794.7 13847.4 13873.3 10570.7 10595.4 10675.2 10981.8 10983.3 10604.3 11587.1 11779.3 11859.4 11911.5 19:37:39 INFO - PROCESS | 24595 | -------- Summary: end -------- 19:37:39 INFO - PROCESS | 24595 | 19:37:40 INFO - TEST-INFO | 24595: exit 0 19:37:40 INFO - TEST-OK | dromaeo_css | took 1117119ms 19:37:40 INFO - TEST-START | kraken 19:37:40 INFO - operating with platform_type : linux_ 19:37:40 INFO - Initialising browser for kraken test... 19:37:40 INFO - TEST-INFO | started process 24901 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaCr4b3/profile http://localhost:58798/getInfo.html) 19:37:44 INFO - PROCESS | 24901 | __metrics Screen width/height:1600/1200 19:37:44 INFO - PROCESS | 24901 | colorDepth:24 19:37:44 INFO - PROCESS | 24901 | Browser inner width/height: 1024/697 19:37:44 INFO - PROCESS | 24901 | __metrics 19:37:44 INFO - PROCESS | 24901 | 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" 19:37:44 INFO - PROCESS | 24901 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:37:45 INFO - PROCESS | 24901 | console.error: PushService: 19:37:45 INFO - PROCESS | 24901 | stateChangeProcessEnqueue: Error transitioning state 19:37:45 INFO - PROCESS | 24901 | UnknownError 19:37:45 INFO - TEST-INFO | 24901: exit 0 19:37:45 INFO - Browser initialized. 19:37:45 INFO - Running cycle 1/1 for kraken test... 19:37:45 INFO - TEST-INFO | started process 24981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaCr4b3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:37:58 INFO - PROCESS | 24981 | RSS: Main: 181592064 19:37:58 INFO - PROCESS | 24981 | 19:38:00 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58798/tests/kraken/driver.html?testName=audio-beat-detection) 19:38:01 INFO - PROCESS | 24981 | RSS: Main: 243441664 19:38:01 INFO - PROCESS | 24981 | 19:38:04 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58798/tests/kraken/driver.html?testName=audio-dft) 19:38:04 INFO - PROCESS | 24981 | RSS: Main: 226078720 19:38:04 INFO - PROCESS | 24981 | 19:38:07 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58798/tests/kraken/driver.html?testName=audio-fft) 19:38:08 INFO - PROCESS | 24981 | RSS: Main: 304001024 19:38:08 INFO - PROCESS | 24981 | 19:38:10 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58798/tests/kraken/driver.html?testName=audio-oscillator) 19:38:11 INFO - PROCESS | 24981 | RSS: Main: 214462464 19:38:11 INFO - PROCESS | 24981 | 19:38:13 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58798/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:38:14 INFO - PROCESS | 24981 | RSS: Main: 215498752 19:38:14 INFO - PROCESS | 24981 | 19:38:17 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58798/tests/kraken/driver.html?testName=imaging-darkroom) 19:38:17 INFO - PROCESS | 24981 | RSS: Main: 255143936 19:38:17 INFO - PROCESS | 24981 | 19:38:21 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58798/tests/kraken/driver.html?testName=imaging-desaturate) 19:38:21 INFO - PROCESS | 24981 | RSS: Main: 253988864 19:38:21 INFO - PROCESS | 24981 | 19:38:24 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58798/tests/kraken/driver.html?testName=json-parse-financial) 19:38:25 INFO - PROCESS | 24981 | RSS: Main: 253431808 19:38:25 INFO - PROCESS | 24981 | 19:38:26 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58798/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:38:27 INFO - PROCESS | 24981 | RSS: Main: 216502272 19:38:27 INFO - PROCESS | 24981 | 19:38:29 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:38:29 INFO - PROCESS | 24981 | RSS: Main: 223580160 19:38:29 INFO - PROCESS | 24981 | 19:38:31 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:38:32 INFO - PROCESS | 24981 | RSS: Main: 223084544 19:38:32 INFO - PROCESS | 24981 | 19:38:35 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:38:35 INFO - PROCESS | 24981 | RSS: Main: 217718784 19:38:35 INFO - PROCESS | 24981 | 19:38:38 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:38:39 INFO - PROCESS | 24981 | RSS: Main: 221728768 19:38:39 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | Cycle 1(1): loaded http://localhost:58798/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58798/tests/kraken/driver.html?testName=ai-astar) 19:38:41 INFO - PROCESS | 24981 | RSS: Main: 272265216 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | __start_tp_report 19:38:41 INFO - PROCESS | 24981 | _x_x_mozilla_page_load 19:38:41 INFO - PROCESS | 24981 | _x_x_mozilla_page_load_details 19:38:41 INFO - PROCESS | 24981 | |i|pagename|runs| 19:38:41 INFO - PROCESS | 24981 | |0;ai-astar;110;110;125;111;110;109;111;109;109;110 19:38:41 INFO - PROCESS | 24981 | |1;audio-beat-detection;153;119;152;182;118;150;160;119;179;118 19:38:41 INFO - PROCESS | 24981 | |2;audio-dft;163;162;160;161;166;162;163;163;160;161 19:38:41 INFO - PROCESS | 24981 | |3;audio-fft;76;76;80;177;77;75;77;76;173;77 19:38:41 INFO - PROCESS | 24981 | |4;audio-oscillator;85;85;151;85;86;87;145;86;86;147 19:38:41 INFO - PROCESS | 24981 | |5;imaging-gaussian-blur;117;116;117;117;117;116;117;117;117;116 19:38:41 INFO - PROCESS | 24981 | |6;imaging-darkroom;146;146;146;147;146;146;146;146;146;146 19:38:41 INFO - PROCESS | 24981 | |7;imaging-desaturate;94;95;94;95;95;95;95;95;95;95 19:38:41 INFO - PROCESS | 24981 | |8;json-parse-financial;53;51;53;53;52;53;52;53;51;50 19:38:41 INFO - PROCESS | 24981 | |9;json-stringify-tinderbox;63;62;62;62;63;62;62;62;62;62 19:38:41 INFO - PROCESS | 24981 | |10;stanford-crypto-aes;85;83;78;78;87;80;80;79;78;84 19:38:41 INFO - PROCESS | 24981 | |11;stanford-crypto-ccm;129;132;124;125;122;123;124;123;146;123 19:38:41 INFO - PROCESS | 24981 | |12;stanford-crypto-pbkdf2;161;166;167;164;161;164;163;162;166;165 19:38:41 INFO - PROCESS | 24981 | |13;stanford-crypto-sha256-iterative;65;68;67;65;64;70;65;64;64;63 19:38:41 INFO - PROCESS | 24981 | __end_tp_report 19:38:41 INFO - PROCESS | 24981 | __start_cc_report 19:38:41 INFO - PROCESS | 24981 | _x_x_mozilla_cycle_collect,1701 19:38:41 INFO - PROCESS | 24981 | __end_cc_report 19:38:41 INFO - PROCESS | 24981 | __startTimestamp1471919921348__endTimestamp 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | ------- Summary: start ------- 19:38:41 INFO - PROCESS | 24981 | Number of tests: 14 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#0] ai-astar Cycles:10 Average:111.40 Median:110.00 stddev:4.84 (4.4%) stddev-sans-first:5.10 19:38:41 INFO - PROCESS | 24981 | Values: 110.0 110.0 125.0 111.0 110.0 109.0 111.0 109.0 109.0 110.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:152.50 stddev:25.16 (16.5%) stddev-sans-first:26.52 19:38:41 INFO - PROCESS | 24981 | Values: 153.0 119.0 152.0 182.0 118.0 150.0 160.0 119.0 179.0 118.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.79 (1.1%) stddev-sans-first:1.87 19:38:41 INFO - PROCESS | 24981 | Values: 163.0 162.0 160.0 161.0 166.0 162.0 163.0 163.0 160.0 161.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#3] audio-fft Cycles:10 Average:96.40 Median:76.50 stddev:41.46 (54.2%) stddev-sans-first:43.31 19:38:41 INFO - PROCESS | 24981 | Values: 76.0 76.0 80.0 177.0 77.0 75.0 77.0 76.0 173.0 77.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#4] audio-oscillator Cycles:10 Average:104.30 Median:85.50 stddev:29.97 (35.0%) stddev-sans-first:30.96 19:38:41 INFO - PROCESS | 24981 | Values: 85.0 85.0 151.0 85.0 86.0 87.0 145.0 86.0 86.0 147.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:38:41 INFO - PROCESS | 24981 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 19:38:41 INFO - PROCESS | 24981 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:38:41 INFO - PROCESS | 24981 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:53.00 stddev:1.10 (2.1%) stddev-sans-first:1.12 19:38:41 INFO - PROCESS | 24981 | Values: 53.0 51.0 53.0 53.0 52.0 53.0 52.0 53.0 51.0 50.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 19:38:41 INFO - PROCESS | 24981 | Values: 63.0 62.0 62.0 62.0 63.0 62.0 62.0 62.0 62.0 62.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.50 stddev:3.29 (4.0%) stddev-sans-first:3.19 19:38:41 INFO - PROCESS | 24981 | Values: 85.0 83.0 78.0 78.0 87.0 80.0 80.0 79.0 78.0 84.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:124.50 stddev:7.34 (5.9%) stddev-sans-first:7.75 19:38:41 INFO - PROCESS | 24981 | Values: 129.0 132.0 124.0 125.0 122.0 123.0 124.0 123.0 146.0 123.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.90 Median:164.50 stddev:2.13 (1.3%) stddev-sans-first:1.99 19:38:41 INFO - PROCESS | 24981 | Values: 161.0 166.0 167.0 164.0 161.0 164.0 163.0 162.0 166.0 165.0 19:38:41 INFO - PROCESS | 24981 | 19:38:41 INFO - PROCESS | 24981 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.00 stddev:2.17 (3.3%) stddev-sans-first:2.30 19:38:41 INFO - PROCESS | 24981 | Values: 65.0 68.0 67.0 65.0 64.0 70.0 65.0 64.0 64.0 63.0 19:38:41 INFO - PROCESS | 24981 | -------- Summary: end -------- 19:38:41 INFO - PROCESS | 24981 | 19:38:42 INFO - TEST-INFO | 24981: exit 0 19:38:42 INFO - TEST-OK | kraken | took 62279ms 19:38:42 INFO - SUITE-END | took 1179s 19:38:42 INFO - Completed test suite (00:19:39) 19:38:42 INFO - javascript benchmark 19:38:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3006.5189569668855, 3014.1259034879886, 3031.621047369079, 3055.5874956880843, 3088.8301143437116, 5236.502532259438, 5293.947060529403, 5300.257963885055, 5376.059189641816, 5424.403315635282, 5311.9468805311935, 5394.676319420828, 5417.079096553585, 5421.9457805421935, 5497.505224529791, 4919.26211068339, 4970.627202959774, 4979.377577802775, 4990.251462280668, 4994.500549944994, 4901.068796928572, 4918.532739389752, 4947.356843610343, 4985.252212168186, 5027.994401119772, 5044.167712327461, 5076.695398276097, 5083.085044691954, 5088.0, 5109.616778741591, 4790.544898234697, 4958.107540642683, 4966.776495057731, 5014.323066386064, 5020.623453241022, 4807.543283388072, 4837.032593481283, 4841.685290456109, 4845.806167753286, 4869.0, 5311.442298558673, 5325.627206095536, 5352.545033672142, 5378.139497680352, 5398.703071331075, 5823.505002074787, 5826.300843898759, 5831.358550559435, 5834.6790926499025, 5843.0, 5639.379668236491, 5709.543236541067, 5718.656880587178, 5762.567807414419, 5855.180274761537, 5355.250264962909, 5362.1420572708175, 5371.57027437804, 5377.704226267515, 5445.646033007882, 5870.178175055496, 5891.440313170242, 5892.351841297454, 5893.233879595625, 5900.64596124234, 5561.77752889888, 5642.548596112301, 5680.11958146488, 5711.428857114255, 5729.799457018984, 5772.509336706427, 5773.971130144322, 5778.711064446845, 5779.33537643168, 5819.272590926134, 5748.597598168088, 5762.135679648086, 5764.827055188351, 5766.4233576642, 5766.884662306814, 5500.80747173847, 5608.46719561641, 5610.607257491936, 5701.71491425427, 5771.971140144273, 4413.205622987829, 4414.6247568957, 4418.977905110454, 4420.80106395213, 4451.465824101064, 3959.5842436544326, 3978.403239514096, 4007.699422543321, 4010.6791456683395, 4014.0566966762785, 5481.835544933659, 5488.4785945335125, 5491.533219676292, 5492.725363731797, 5496.7526461309335, 5653.773849045992, 5739.225204597409, 5772.6536407815665, 5821.79623713168, 5856.970715146397, 5489.451054894558, 5503.559715222773, 5517.972410137923, 5568.805091821767, 5586.720663966786, 4988.4263309719845, 5019.799208031711, 5039.84880453587, 5054.267131265946, 5073.086844367976, 5856.531477481876, 5975.731092100953, 6014.1580178775, 6016.849578760479, 6030.698465076641, 5839.0, 5840.240768700041, 5848.64908105515, 5905.5570832186895, 5910.734016969161, 5983.431574000463, 6035.094735789491, 6035.969820150871, 6037.215162028908, 6038.547108966757, 5606.6916319602815, 5626.690532020778, 5660.801871934463, 5739.512141468022, 5760.107183386582, 5765.365809760839, 5765.625234359753, 5769.798057067983, 5815.9709201453725, 5848.239728835224, 5624.746886390204, 5634.661920284796, 5635.6618602883955, 5637.351704554028, 5641.0, 5530.474604912527, 5625.8593535161945, 5640.464155905182, 5641.0, 5658.094704847204, 5627.324721033502, 5642.23829782974, 5646.689432081275, 5692.686902220417, 5707.800226992035, 5627.52166065889, 5715.685637289989, 5724.8282551523525, 5728.541716662574, 5731.656100633975, 5543.1685247213245, 5569.888602228014, 5571.693556854412, 5583.609147359646, 5608.0], "value": 5333.75144237932, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.9277293951785, 584.7953216374269, 584.8771757930834, 585.765693722511, 589.7699897040158, 28964.275893102684, 36252.45621315679, 37474.25051498972, 37488.0, 40093.19813603726, 37740.24519509608, 38122.23755524895, 38155.236895262075, 38164.42753358702, 38206.42690359647, 7737.922620773805, 8451.450655707391, 8606.56967151643, 8607.053224145338, 8608.48349099053, 9850.50747462624, 10184.59261629534, 10214.795704085915, 10547.208959328043, 10564.313319634237, 10315.071643552079, 10403.271770976035, 10414.166866650688, 10418.0, 10425.947870260638, 10220.182385409149, 10222.977702229791, 10271.126954208901, 10281.331713438678, 10284.177265818755, 10257.281990260684, 10300.330478518872, 10319.638812641597, 10321.329113607666, 10329.690109296733, 35842.0, 36059.81970090159, 36135.09662258464, 36156.0, 36182.638173618456, 2982.302818440019, 3069.3707789903015, 3129.85915633797, 3273.7053665170247, 3298.5217143514296, 28467.145985620256, 28937.56593651076, 29260.70739292601, 29476.852615737, 30449.391012179854, 4263.936040959388, 4313.416489501411, 4364.8472303469225, 4391.802368893407, 4429.867103986886, 312552.0, 314057.0, 314558.99999999773, 314939.0, 315197.0, 47806.5219347809, 48385.27422088671, 48403.0, 48434.0, 48519.5148048522, 31615.20961975923, 32181.0, 32377.51433728496, 33893.49159762605, 33918.49122263168, 13254.536091236952, 13281.269530175932, 13295.20228786276, 13297.601071967663, 13441.260730659713, 9248.21390181842, 10641.5211315491, 10785.514651840685, 10964.725881853017, 11027.503762330714, 15574.688506230039, 15704.214789260492, 15762.842371576367, 15780.13209273478, 15785.36858525669, 5950.196723442366, 6038.215032045894, 6048.243969503812, 6079.8480037998515, 6132.724027418777, 851.8637018077078, 898.1647068226612, 923.4782347973373, 939.6476321379482, 999.8150342186718, 27701.584476232874, 28933.42133157367, 29091.7090829089, 29378.853105734335, 29675.554866677016, 986.5461887531673, 994.9950250248702, 1025.2823023883311, 1041.994790026045, 1049.5959055763433, 14919.254037298091, 15196.0, 15274.236288185546, 15495.0, 15570.0, 545731.0, 548566.0, 554115.0, 555943.0, 800830.0, 49047.50952490429, 49597.25604115941, 49716.0, 49772.253416198786, 49783.751081245086, 1043.426115636412, 1141.2182654881433, 1159.4376727287258, 1173.538944014707, 1180.1974657233163, 26986.86506567533, 28678.283042924093, 29270.7072929268, 29285.560716589694, 29322.85338573379, 45362.319565205886, 46892.12431502702, 46898.53101468942, 51487.0, 51520.0, 492650.0, 494917.0, 495363.0, 496440.0, 497832.0, 29971.0, 32009.679903201602, 33678.66321336755, 38527.807360964136, 39495.802520987214, 40389.394159088224, 40472.79763601163, 41157.0, 41166.1766764669, 41620.58379416167, 35619.28761424809, 35916.0, 35978.28043439169, 36432.08919777027, 36508.634913651586, 36381.63618363888, 36874.44688329731, 36966.260674785815, 37359.81320093382, 37620.0, 34561.48157777585, 35046.82476587703, 35075.12312192113, 35082.473762894086, 35828.82085589555, 30184.849075754482, 32528.34943301168, 32593.348133036732, 32639.67360326366, 32731.181720457178, 25267.241982740547, 25788.484230315666, 25830.741692582833, 25994.0, 26202.344941376617], "value": 18474.315030505088, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.6430822714946, 370.88131797824707, 371.9776813391196, 376.55566431610697, 390.9589493103224, 17452.0, 18450.63098738026, 18598.535036624093, 18717.34489292875, 19386.418407447763, 15328.15695136767, 16135.418333066711, 16171.595710107253, 16178.433754818583, 16256.268467918942, 5426.972865135669, 5511.145772405283, 5641.069223578104, 5655.604107712462, 5657.462541058613, 4037.37420699792, 5592.273004509408, 5642.266505354298, 5658.462446067617, 5921.526277897758, 6102.481289090432, 6243.937560624404, 6256.217972753406, 6259.561830671855, 6261.686915654199, 6202.317745048041, 6228.688565571726, 6256.624602523863, 6287.559870809046, 6298.4331410172845, 5542.140968149944, 5559.972200138974, 5565.554755619582, 5566.304211973504, 5566.777328906839, 19269.325573604998, 20101.0, 20147.697784533244, 20696.379108626767, 21726.13095476179, 2311.202635090891, 2478.3680161558686, 2684.3825920038503, 2809.1712944681162, 2950.1592046266924, 9199.816003679889, 9562.091601297865, 9937.5031248438, 11831.940840295743, 12979.766922142477, 1796.119901248379, 1887.1507821480388, 1888.3296429767372, 1895.4408449507428, 1907.7233801098903, 74646.25353746502, 75706.99999999945, 78642.60678696627, 82531.0, 84819.5759021207, 10252.436116013541, 10316.896831031743, 10643.787124257626, 10652.840207396895, 10855.0, 14285.92857035729, 14453.49412770548, 14461.638459038608, 14486.0, 14502.927485362507, 6940.7917762467205, 7424.331810137113, 7424.85150296991, 7467.215942326086, 7580.241975802375, 6623.701933413008, 6830.487713421513, 6989.0, 7017.508774385846, 7308.342249197598, 4219.641330486882, 4334.544872788375, 4354.542773008789, 4408.977955110204, 4563.0, 1821.2714914034227, 1839.45736007878, 1873.7938826729048, 1892.2525602387254, 1944.7666280046485, 731.7109741652017, 864.2826454043129, 886.3485338276362, 910.9863352049724, 934.7896723237217, 16818.0, 17733.822661773476, 18218.63562728738, 18235.27058917655, 18387.632247354977, 847.1316900177368, 901.1304091551684, 910.4446287764591, 932.1005229953128, 947.8957314695378, 9842.163416109712, 10331.74170645725, 10357.482125893675, 10383.94808025955, 10417.791644167075, 396723.0, 435412.0, 472787.0, 476069.0, 477876.0, 93050.7644159142, 94663.0, 96677.51661241648, 97141.0, 98120.50939745255, 839.8530257205086, 874.6151693255075, 884.3014018924977, 886.1183122792862, 886.7605746448423, 15669.608259793597, 16456.0, 16778.24497897622, 16841.494755157364, 16996.0, 40354.596454036255, 42837.35743963781, 43463.56536434595, 44121.55878441175, 44881.55118448774, 51868.481315186364, 59812.401875982425, 61457.0, 62226.68886655538, 62834.0, 6905.1713794345, 7498.962505187439, 7593.810154746176, 7726.497777644435, 7851.568163751049, 11327.490262938352, 12064.75870482568, 12741.8088728671, 13069.0, 13393.397297121459, 11181.217314787888, 11203.831942521032, 11466.082713383075, 11618.825717614074, 11823.231489953125, 10752.35485870819, 10890.346579205274, 11072.446377680923, 11624.302541847515, 11675.182737208313, 11501.367424791717, 11568.768624627628, 11629.127815414047, 11827.52689892429, 12035.676075631849, 7282.0, 7358.264173582599, 7404.888926665996, 7708.653110610148, 7928.207179282025, 10629.149668026384, 10645.414502202453, 11156.219064665396, 11310.321380716854, 11466.713332166762], "value": 8818.930433014506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1148.6381789736238, 1247.6319485751706, 1248.2073883084238, 1262.463453032461, 1414.4342263094761, 7464.813379665505, 8236.094029656733, 8521.744347669564, 8535.829283414329, 8731.99582048063, 8529.488230706147, 8558.101399353072, 8564.400491965565, 8585.399022068443, 8594.140585941403, 2404.7234568024683, 2431.756824317572, 2435.841670291434, 2438.5244877248942, 2440.0, 2403.387136280246, 2405.458771776354, 2417.685700858886, 2421.6125419932814, 2451.963220551693, 2348.43637526994, 2379.274321331993, 2381.3570336009266, 2416.685830841997, 2420.9636855447093, 2331.7318508371586, 2334.2880421471446, 2347.5304939012267, 2352.2590384028977, 2389.1518510928545, 2320.3734991552355, 2344.9648255276184, 2350.8589484630975, 2352.6588644646604, 2360.8465449745713, 8491.915080849236, 8603.225709686096, 8653.567321633956, 8658.307335413216, 8665.306775457948, 5031.0, 5240.423553409122, 5242.764075616568, 5259.815906443256, 5270.631055826094, 3583.211693427442, 3680.1351682354625, 3704.3517384457614, 3741.8316175772156, 3750.49368335274, 1235.5552001279532, 1273.1469915156868, 1284.6659868434274, 1311.4557458654647, 1335.038772084098, 12231.204971676812, 12323.0, 12334.62996110109, 12378.504859805596, 12400.255984640951, 4317.201317756225, 4505.256632655575, 4615.284630882219, 4660.04469083837, 4667.696599721007, 9451.905480945241, 9470.479123648265, 9476.715698529055, 9480.525973701288, 9499.24006079512, 5113.590912726973, 5118.61610379223, 5127.07712611726, 5132.435432102466, 5134.383873935151, 4146.813393397304, 4521.163584736834, 4679.625629949596, 4691.13214055394, 4779.761011949388, 3654.3239500691925, 3697.8705745298785, 3774.6602805747616, 3781.262653782486, 3791.4881490998364, 1174.4597485156753, 1176.5293882446917, 1179.4220831792531, 1189.79178643737, 1195.8863907928733, 808.5391326943586, 809.4293523066224, 826.3884725303233, 876.7895705030746, 887.9556022198864, 5698.857528561819, 5779.306483221956, 6006.489448396847, 6067.757289708362, 6101.511879049665, 867.9262262707614, 877.3989816975264, 887.3344991256557, 901.3150005995491, 906.6146887572755, 3961.8217180226957, 4013.7190396671954, 4047.372657238133, 4059.65492933104, 4108.465899433055, 10878.728031799268, 11294.322340659422, 11322.716932076764, 11341.092712583139, 11357.318560886208, 11177.552897884156, 11186.664400068012, 11203.887961120448, 11209.43952802373, 11227.66317010491, 7614.923850761532, 7791.454598178073, 7872.881906771403, 8030.755232938904, 8173.959130204311, 5912.142739302863, 6056.1824153740445, 6077.361877003028, 6095.9390406097145, 6112.1748563945075, 10232.181425486093, 10236.488175591041, 10249.641262555775, 10266.691999240034, 10303.3817970922, 9981.20338339106, 10689.732756681145, 10694.786104278026, 10695.679129626124, 10699.89300106989, 6965.547239429421, 7311.524750891388, 7460.0301960744755, 7763.0684317882215, 7914.960425197837, 8326.167383261867, 8446.113158118309, 8469.66121355139, 8488.787780305542, 8496.745097647081, 7303.233160518282, 7500.174980752222, 7592.8861067082935, 7623.771286861403, 7648.34989025939, 7302.342789149002, 7313.561186328838, 7321.707131714672, 7369.189389167294, 7665.271799179069, 6795.28649491816, 6905.620190889549, 6920.550164239308, 6920.688569014507, 6927.549709268881, 4839.370881785346, 5076.238564215398, 5135.922961155511, 5145.382554093533, 5173.1205695031185, 4916.901661966669, 5109.8722531937, 5143.0, 5145.536901678867, 5328.120860058047], "value": 4577.139938397333, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.11091009398643, 327.15430611868317, 331.26128732925577, 333.43982110055106, 334.2429397414857, 5027.974860125703, 5171.4311425743135, 5223.8694032649155, 5295.6028297877665, 5315.89368212636, 5172.250023746564, 5181.43004269531, 5216.921746173811, 5233.607479439038, 5246.137621144487, 4110.8972275693195, 4116.238495878257, 4118.320477121271, 4128.8348466061325, 4145.730527515701, 3933.4099885017336, 4071.979640101795, 4139.089400331937, 4144.23331683638, 4150.0, 4020.5778393268724, 4041.4139949707273, 4065.597368907737, 4074.775887326196, 4074.816633251496, 4017.27689015977, 4025.4565633639377, 4025.5773143819915, 4042.171354872244, 4043.3934909763475, 3569.4824250483794, 3571.785692858437, 3575.3921833288405, 3582.695470884978, 3600.387934051191, 5073.746312684351, 5080.491950804927, 5105.33630628016, 5140.768665410028, 5142.07442660321, 4627.907441851178, 4640.814367425299, 4649.837255696035, 4657.953420465786, 4658.976705116486, 5413.593980451482, 5437.32033495813, 5455.1271796512365, 5465.344158700942, 5469.425710300401, 3809.3714537101623, 3860.4016377461257, 3875.379939209713, 3882.5340959084806, 3925.3719404895364, 5602.691851948141, 5686.260786097822, 5690.658560486343, 5727.599068065237, 5728.169415434781, 3995.9800200998807, 3999.7800120993043, 4002.3596224603925, 4006.659433948147, 4019.738716983387, 3874.0, 4026.617471340218, 4056.675465962716, 4098.282800509899, 4127.484064491939, 4635.814567417266, 4639.930401043987, 4642.628589712815, 4643.976780116078, 4664.370310008173, 5540.196671482612, 5541.750621222055, 5556.749516445846, 5563.638363506359, 5564.610477266633, 1319.8680131986725, 1377.793331000358, 1385.0055919476124, 1385.9951535136945, 1392.0534036854833, 934.4860326820221, 941.8116376724682, 945.5461378538203, 961.7980224152866, 964.8697425847421, 5276.815311464157, 5286.81496147633, 5293.470652934676, 5310.893782124336, 5316.202569614511, 5619.43805619443, 5625.493705566519, 5642.435756424325, 5647.858803529863, 5650.124230744241, 5194.72732178725, 5324.148136298251, 5329.280547126166, 5337.0, 5348.652337598043, 2978.463876502207, 2979.702029797003, 2986.357933044406, 2996.580478732979, 3003.354278830062, 5834.766609335664, 5843.970780146072, 5865.413458654186, 5867.9413205867395, 5876.265466816648, 5562.360328562187, 5759.107338362561, 5763.221965034667, 5811.854703632358, 5815.273090863642, 5867.0, 5876.588638795329, 5894.4400281974, 5897.410258974069, 5898.997170481029, 5412.75642596092, 5624.943750562442, 5684.175794509855, 5701.144828275792, 5718.399568045295, 5605.887882242251, 5627.831165065055, 5665.745041473226, 5668.093105103164, 5670.914936276042, 5884.852878678067, 5898.20374249488, 5908.720660033722, 5909.3795151508475, 5918.526517878646, 3988.501437320335, 4021.1354558770004, 4034.8789536313957, 4050.554439011765, 4051.1897620475434, 5393.325834270716, 5629.268195134607, 5634.915476267777, 5652.915206271827, 5655.717214139194, 5401.3248343957, 5533.833984980457, 5539.556835453073, 5546.89062187556, 5559.305086864142, 5498.285222920995, 5534.280543529316, 5537.667739935617, 5540.833774986757, 5541.2796336476, 5264.868378290574, 5424.83725488236, 5472.890542189054, 5479.0, 5490.972545137249, 5541.362743284574, 5560.499555040065, 5565.443455654402, 5570.749316280858, 5576.804811831566, 5454.754536045808, 5477.452254774491, 5483.725813709379, 5485.0, 5518.91721624184], "value": 4199.829642881798, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.8378122943398, 601.6841158391846, 606.854354954811, 670.0518765946186, 683.8700646877095, 14060.789088163685, 14114.64713382166, 14609.561713148596, 14786.630334241649, 14796.408143674267, 14003.649908752286, 14045.0, 14078.577642670736, 14085.788713169284, 14108.15351078936, 576.7347020370635, 590.5865893874284, 594.9286085669726, 595.8301883963071, 596.1177457363106, 476.10254669947227, 572.9484346408823, 587.6063037764708, 589.6609449566495, 592.0940960330679, 606.8968275393194, 610.2585358789071, 610.825914614335, 611.5291225756165, 611.8623309755292, 608.4523928464396, 608.5892022884535, 608.7443273825004, 608.7534548507862, 609.3327806052388, 395.489818134606, 403.5238418665973, 405.1673810319792, 438.48259054315895, 447.4094195661728, 12312.0, 12572.371381430983, 12616.432260548298, 12632.2420654761, 12637.557685480964, 12139.939300303531, 12441.315727634976, 13053.0, 13456.730865382735, 13968.092074015258, 12652.114351995364, 12764.297963611998, 12979.610611681665, 12998.545050923267, 13722.45110195591, 4415.558444155591, 4437.378766972626, 4487.035287413222, 4638.373819534387, 4642.535746425364, 89140.0, 90357.54821225918, 91429.54285228532, 91903.0, 92049.0, 10397.84403233952, 10438.63464778729, 10774.676759697222, 10788.190885683605, 10804.08165305942, 18160.1827917744, 18342.423227470514, 18418.907905460655, 18442.0, 18472.630547389246, 8218.753437396768, 8349.916500835036, 8543.35924805642, 8796.252318552866, 8803.251723603438, 4753.619710423158, 4785.521447855256, 4902.63230257725, 4903.8528844134735, 4961.280614310905, 3168.588083549124, 3361.394948909171, 3369.3935091683734, 3370.0732298618077, 3372.1401042734437, 596.582392325374, 597.9521638268928, 601.6330038676404, 603.7916918663054, 608.6104892868568, 560.0507140396988, 585.280105470274, 673.7204060315013, 674.5413119079074, 686.9381755642016, 8730.257928076184, 9445.763855903657, 9495.525223738923, 10040.548175332127, 10441.947790261, 651.9119918810994, 675.2167485716545, 687.5908834243717, 694.6769752065213, 699.7375984005997, 4408.360787685768, 4580.891821475969, 4584.535774703911, 4747.406574178228, 4801.68789028712, 110051.44974275077, 127967.3601631986, 134895.0, 135599.0, 138260.70435773683, 64264.0, 67422.32577674258, 71823.0, 94952.0, 110001.44999274952, 57973.0, 76445.6177719119, 78358.6082069586, 79141.0, 82171.17828821755, 9702.514874256118, 9993.495886520666, 10094.394336339843, 10260.281780275309, 10426.27016108865, 52632.9473410522, 53515.0, 53739.92520149653, 53741.73129134329, 53819.0, 42409.78795106005, 43102.78448607737, 43936.780316098215, 78207.60896195483, 81346.59326703328, 11762.58830940913, 12308.815367769312, 12444.315562644142, 12689.302088385228, 13065.542706005244, 13826.86173138296, 14129.788053179416, 14661.633459163606, 15022.774658379914, 15205.847941520444, 13524.39140238672, 14007.0, 14108.435662573382, 14161.504347347793, 15116.622084447978, 12364.876351236373, 12406.317652529198, 12551.0, 12803.80794288105, 12957.416916238602, 13721.862781372058, 13773.311334433038, 13794.724105518035, 13847.446102155802, 13873.306334683428, 10570.682879513626, 10595.38925482118, 10675.199360048182, 10981.835272471078, 10983.286086404358, 10604.257701960789, 11587.130965177477, 11779.29324240514, 11859.407029648655, 11911.463984120563], "value": 6991.5850119658835, "unit": "score"}], "name": "dromaeo_css", "value": 6991.602615868549, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 125.0, 111.0, 110.0, 109.0, 111.0, 109.0, 109.0, 110.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 152.0, 182.0, 118.0, 150.0, 160.0, 119.0, 179.0, 118.0], "value": 145.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 160.0, 161.0, 166.0, 162.0, 163.0, 163.0, 160.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 80.0, 177.0, 77.0, 75.0, 77.0, 76.0, 173.0, 77.0], "value": 96.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 151.0, 85.0, 86.0, 87.0, 145.0, 86.0, 86.0, 147.0], "value": 104.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 53.0, 53.0, 52.0, 53.0, 52.0, 53.0, 51.0, 50.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 78.0, 78.0, 87.0, 80.0, 80.0, 79.0, 78.0, 84.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 132.0, 124.0, 125.0, 122.0, 123.0, 124.0, 123.0, 146.0, 123.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 166.0, 167.0, 164.0, 161.0, 164.0, 163.0, 162.0, 166.0, 165.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 65.0, 64.0, 70.0, 65.0, 64.0, 64.0, 63.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1528.8, "alertThreshold": 2.0}]} 19:38:43 INFO - Return code: 0 19:38:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:38:43 INFO - # TBPL SUCCESS # 19:38:43 INFO - Running post-action listener: _resource_record_post_action 19:38:43 INFO - [mozharness: 2016-08-23 02:38:43.121463Z] Finished run-tests step (success) 19:38:43 INFO - Running post-run listener: _resource_record_post_run 19:38:43 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 412942336; Read time: 20; Write time: 288300 19:38:43 INFO - TinderboxPrint: CPU usage
13.3% 19:38:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 19:38:43 INFO - TinderboxPrint: I/O write bytes / time
412,942,336 / 288,300 19:38:43 INFO - TinderboxPrint: CPU idle
8,283.3 (86.7%) 19:38:43 INFO - TinderboxPrint: CPU user
1,200.6 (12.6%) 19:38:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:38:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 125128704; Read time: 0; Write time: 107904 19:38:43 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 287813632; Read time: 0; Write time: 180396 19:38:43 INFO - Running post-run listener: _upload_blobber_files 19:38:43 INFO - Blob upload gear active. 19:38:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:38:43 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 19:38:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:38:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:38:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:38:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:38:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:47 INFO - (blobuploader) - INFO - Done attempting. 19:38:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:38:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:38:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:49 INFO - (blobuploader) - INFO - Done attempting. 19:38:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:38:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:38:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:50 INFO - (blobuploader) - INFO - Done attempting. 19:38:50 INFO - (blobuploader) - INFO - Iteration through files over. 19:38:50 INFO - Return code: 0 19:38:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:38:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:38:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d755c0d6a61cc14f526e85c32cea2973d796e3b4303c1014af1ec57ded59885f6a5b19efa22f4d5199ea106347d475090041adb91c78bd23735b9590bdb5f288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/be4ed40605920d0045a45caf63821362016bd63085d8597692a769483563da78c815c25acafe3354d194ece12f25b96cee9f6aa552b02f6267ae6059e3870a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a2ffc94e5fbfb9b9731048a4870b51f766210183b7d86c263f75d840821b351579ccf400e67860713487b3d2e62fd8f85530278e851de99a3744ca1b7b3e2851"} 19:38:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:38:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:38:50 INFO - Contents: 19:38:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d755c0d6a61cc14f526e85c32cea2973d796e3b4303c1014af1ec57ded59885f6a5b19efa22f4d5199ea106347d475090041adb91c78bd23735b9590bdb5f288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/be4ed40605920d0045a45caf63821362016bd63085d8597692a769483563da78c815c25acafe3354d194ece12f25b96cee9f6aa552b02f6267ae6059e3870a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a2ffc94e5fbfb9b9731048a4870b51f766210183b7d86c263f75d840821b351579ccf400e67860713487b3d2e62fd8f85530278e851de99a3744ca1b7b3e2851"} 19:38:50 INFO - Running post-run listener: copy_logs_to_upload_dir 19:38:50 INFO - Copying logs to upload dir... 19:38:50 INFO - mkdir: /builds/slave/test/build/upload/logs 19:38:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.388145 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-22 19:38:50.098861) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 19:38:50.102115) ========= 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=1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d755c0d6a61cc14f526e85c32cea2973d796e3b4303c1014af1ec57ded59885f6a5b19efa22f4d5199ea106347d475090041adb91c78bd23735b9590bdb5f288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/be4ed40605920d0045a45caf63821362016bd63085d8597692a769483563da78c815c25acafe3354d194ece12f25b96cee9f6aa552b02f6267ae6059e3870a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a2ffc94e5fbfb9b9731048a4870b51f766210183b7d86c263f75d840821b351579ccf400e67860713487b3d2e62fd8f85530278e851de99a3744ca1b7b3e2851"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011526 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1471908603/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d755c0d6a61cc14f526e85c32cea2973d796e3b4303c1014af1ec57ded59885f6a5b19efa22f4d5199ea106347d475090041adb91c78bd23735b9590bdb5f288", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/be4ed40605920d0045a45caf63821362016bd63085d8597692a769483563da78c815c25acafe3354d194ece12f25b96cee9f6aa552b02f6267ae6059e3870a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a2ffc94e5fbfb9b9731048a4870b51f766210183b7d86c263f75d840821b351579ccf400e67860713487b3d2e62fd8f85530278e851de99a3744ca1b7b3e2851"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 19:38:50.128069) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:38:50.128407) ========= 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=1f5564757af27664ca02d71f000001b0-1471918687.173986-1935521239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004511 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:38:50.210713) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 19:38:50.211062) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 19:38:50.211407) ========= ========= Total master_lag: 0.18 =========