builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1480738744.01 results: success (0) buildid: 20161202201849 builduid: f2847a5fc9b941dc9397ddb19539925b revision: d2c5b6581d6ab82d04b6ee8d454bb7debe9f4d0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.015050) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.015533) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.016116) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.101329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.101635) ========= 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=745a4373a2a16df560f216e000000264-1480729352.164523-1073842972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-02 20:19:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-12-02 20:19:04 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103516 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.239046) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.239617) ========= 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=745a4373a2a16df560f216e000000264-1480729352.164523-1073842972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013337 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:04.314871) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 20:19:04.315226) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d2c5b6581d6ab82d04b6ee8d454bb7debe9f4d0e --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 d2c5b6581d6ab82d04b6ee8d454bb7debe9f4d0e --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=745a4373a2a16df560f216e000000264-1480729352.164523-1073842972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-02 20:19:05,055 truncating revision to first 12 chars 2016-12-02 20:19:05,055 Setting DEBUG logging. 2016-12-02 20:19:05,056 attempt 1/10 2016-12-02 20:19:05,056 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/d2c5b6581d6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-02 20:19:05,732 unpacking tar archive at: mozilla-inbound-d2c5b6581d6a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.997835 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-02 20:19:05.335123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:05.335785) ========= script_repo_revision: d2c5b6581d6ab82d04b6ee8d454bb7debe9f4d0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:05.336732) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:05.337442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 20:19:05.349390) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-02 20:19:05.349687) ========= /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=745a4373a2a16df560f216e000000264-1480729352.164523-1073842972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:19:06 INFO - MultiFileLogger online at 20161202 20:19:06 in /builds/slave/test 20:19:06 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 20:19:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:19:06 INFO - {'append_to_log': False, 20:19:06 INFO - 'base_work_dir': '/builds/slave/test', 20:19:06 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 20:19:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:19:06 INFO - 'branch': 'Mozilla-Inbound', 20:19:06 INFO - 'buildbot_json_path': 'buildprops.json', 20:19:06 INFO - 'config_files': ('talos/linux_config.py',), 20:19:06 INFO - 'default_actions': ('clobber', 20:19:06 INFO - 'read-buildbot-config', 20:19:06 INFO - 'download-and-extract', 20:19:06 INFO - 'populate-webroot', 20:19:06 INFO - 'create-virtualenv', 20:19:06 INFO - 'install', 20:19:06 INFO - 'run-tests'), 20:19:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:19:06 INFO - 'download_minidump_stackwalk': True, 20:19:06 INFO - 'download_symbols': 'ondemand', 20:19:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:19:06 INFO - 'tooltool.py': '/tools/tooltool.py', 20:19:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:19:06 INFO - '/tools/misc-python/virtualenv.py')}, 20:19:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:19:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:19:06 INFO - 'installer_path': 'installer.exe', 20:19:06 INFO - 'log_level': 'info', 20:19:06 INFO - 'log_name': 'talos', 20:19:06 INFO - 'log_to_console': True, 20:19:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:19:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:19:06 INFO - 'opt_config_files': (), 20:19:06 INFO - 'pip_index': False, 20:19:06 INFO - 'sps_profile': False, 20:19:06 INFO - 'sps_profile_interval': 0, 20:19:06 INFO - 'suite': 'dromaeojs', 20:19:06 INFO - 'system_bits': '32', 20:19:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:19:06 INFO - 'title': 'talos-linux64-ix-032', 20:19:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:19:06 INFO - 'use_talos_json': True, 20:19:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:19:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:19:06 INFO - 'work_dir': 'build'} 20:19:06 INFO - [mozharness: 2016-12-03 04:19:06.178082Z] Running clobber step. 20:19:06 INFO - Running pre-action listener: _resource_record_pre_action 20:19:06 INFO - Running main action method: clobber 20:19:06 INFO - rmtree: /builds/slave/test/build 20:19:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:19:07 INFO - Running post-action listener: _resource_record_post_action 20:19:07 INFO - [mozharness: 2016-12-03 04:19:07.154281Z] Finished clobber step (success) 20:19:07 INFO - [mozharness: 2016-12-03 04:19:07.154379Z] Running read-buildbot-config step. 20:19:07 INFO - Running pre-action listener: _resource_record_pre_action 20:19:07 INFO - Running main action method: read_buildbot_config 20:19:07 INFO - Using buildbot properties: 20:19:07 INFO - { 20:19:07 INFO - "project": "", 20:19:07 INFO - "product": "firefox", 20:19:07 INFO - "script_repo_revision": "production", 20:19:07 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 20:19:07 INFO - "repository": "", 20:19:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 20:19:07 INFO - "buildid": "20161202201849", 20:19:07 INFO - "stage_platform": "linux64-pgo", 20:19:07 INFO - "basedir": "/builds/slave/test", 20:19:07 INFO - "buildnumber": 2975, 20:19:07 INFO - "platform": "ubuntu64_hw", 20:19:07 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:19:07 INFO - "slavebuilddir": "test", 20:19:07 INFO - "branch": "mozilla-inbound", 20:19:07 INFO - "revision": "d2c5b6581d6ab82d04b6ee8d454bb7debe9f4d0e", 20:19:07 INFO - "slavename": "talos-linux64-ix-032", 20:19:07 INFO - "builduid": "f2847a5fc9b941dc9397ddb19539925b", 20:19:07 INFO - "repo_path": "integration/mozilla-inbound" 20:19:07 INFO - } 20:19:07 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 20:19:07 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 20:19:07 INFO - Running post-action listener: _resource_record_post_action 20:19:07 INFO - [mozharness: 2016-12-03 04:19:07.156698Z] Finished read-buildbot-config step (success) 20:19:07 INFO - [mozharness: 2016-12-03 04:19:07.156783Z] Running download-and-extract step. 20:19:07 INFO - Running pre-action listener: _resource_record_pre_action 20:19:07 INFO - Running main action method: download_and_extract 20:19:07 INFO - mkdir: /builds/slave/test/build/tests 20:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:07 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 20:19:07 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 20:19:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:19:07 INFO - Downloaded 1361 bytes. 20:19:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 20:19:07 INFO - Using the following test package requirements: 20:19:07 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 20:19:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:19:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:19:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'jsshell-linux-x86_64.zip'], 20:19:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:19:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 20:19:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:19:07 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:19:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:19:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:19:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:19:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 20:19:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:19:07 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:19:07 INFO - Expected file size: 21781476 20:19:07 INFO - Obtained file size: 21781476 20:19:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:19:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 20:19:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:19:11 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:19:12 INFO - Expected file size: 21781476 20:19:12 INFO - Obtained file size: 21781476 20:19:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 20:19:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:19:16 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 20:19:16 INFO - Expected file size: 14409734 20:19:16 INFO - Obtained file size: 14409734 20:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:17 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:19:17 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:19:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:19:20 INFO - Downloaded 59843710 bytes. 20:19:20 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:19:20 INFO - mkdir: /builds/slave/test/properties 20:19:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:19:20 INFO - Writing to file /builds/slave/test/properties/build_url 20:19:20 INFO - Contents: 20:19:20 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:19:21 INFO - Running post-action listener: _resource_record_post_action 20:19:21 INFO - Running post-action listener: set_extra_try_arguments 20:19:21 INFO - [mozharness: 2016-12-03 04:19:21.161107Z] Finished download-and-extract step (success) 20:19:21 INFO - [mozharness: 2016-12-03 04:19:21.161333Z] Running populate-webroot step. 20:19:21 INFO - Running pre-action listener: _resource_record_pre_action 20:19:21 INFO - Running main action method: populate_webroot 20:19:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:19:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:19:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:19:21 INFO - '--tppagecycles', 20:19:21 INFO - '7'], 20:19:21 INFO - 'tests': ['tsvgm']}}, 20:19:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:19:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:19:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:19:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:19:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:19:21 INFO - 'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:19:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:19:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:19:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:19:21 INFO - 'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['damp', 'tps']}, 20:19:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:19:21 INFO - 'tests': ['damp', 'tps']}, 20:19:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['dromaeo_dom']}, 20:19:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:19:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['basic_compositor_video']}, 20:19:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:19:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['a11yr', 20:19:21 INFO - 'ts_paint', 20:19:21 INFO - 'tpaint', 20:19:21 INFO - 'sessionrestore', 20:19:21 INFO - 'sessionrestore_no_auto_restore', 20:19:21 INFO - 'tabpaint']}, 20:19:21 INFO - 'other-e10s': {'tests': ['a11yr', 20:19:21 INFO - 'ts_paint', 20:19:21 INFO - 'tpaint', 20:19:21 INFO - 'sessionrestore', 20:19:21 INFO - 'sessionrestore_no_auto_restore', 20:19:21 INFO - 'tabpaint']}, 20:19:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:19:21 INFO - 'ts_paint', 20:19:21 INFO - 'tpaint', 20:19:21 INFO - 'sessionrestore', 20:19:21 INFO - 'sessionrestore_no_auto_restore', 20:19:21 INFO - 'tabpaint']}, 20:19:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:19:21 INFO - 'ts_paint', 20:19:21 INFO - 'tpaint', 20:19:21 INFO - 'sessionrestore', 20:19:21 INFO - 'sessionrestore_no_auto_restore', 20:19:21 INFO - 'tabpaint']}, 20:19:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['a11yr', 20:19:21 INFO - 'ts_paint', 20:19:21 INFO - 'tpaint', 20:19:21 INFO - 'sessionrestore', 20:19:21 INFO - 'sessionrestore_no_auto_restore', 20:19:21 INFO - 'tabpaint']}, 20:19:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['a11yr', 20:19:21 INFO - 'ts_paint', 20:19:21 INFO - 'tpaint', 20:19:21 INFO - 'sessionrestore', 20:19:21 INFO - 'sessionrestore_no_auto_restore', 20:19:21 INFO - 'tabpaint']}, 20:19:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['tsvgx', 20:19:21 INFO - 'tsvgr_opacity', 20:19:21 INFO - 'tart', 20:19:21 INFO - 'tscrollx', 20:19:21 INFO - 'cart', 20:19:21 INFO - 'tsvg_static']}, 20:19:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:19:21 INFO - 'tsvgr_opacity', 20:19:21 INFO - 'tart', 20:19:21 INFO - 'tscrollx', 20:19:21 INFO - 'cart', 20:19:21 INFO - 'tsvg_static']}, 20:19:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:19:21 INFO - 'talos_options': ['--disable-e10s'], 20:19:21 INFO - 'tests': ['tp5o']}, 20:19:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:19:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:19:21 INFO - 'talos_options': ['--disable-e10s', 20:19:21 INFO - '--xperf_path', 20:19:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:19:21 INFO - 'tests': ['tp5n']}, 20:19:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:19:21 INFO - 'talos_options': ['--xperf_path', 20:19:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:19:21 INFO - 'tests': ['tp5n']}}, 20:19:21 INFO - 'talos.zip': {'path': '', 20:19:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:19:21 INFO - Running post-action listener: _resource_record_post_action 20:19:21 INFO - [mozharness: 2016-12-03 04:19:21.181962Z] Finished populate-webroot step (success) 20:19:21 INFO - [mozharness: 2016-12-03 04:19:21.182049Z] Running create-virtualenv step. 20:19:21 INFO - Running pre-action listener: _resource_record_pre_action 20:19:21 INFO - Running main action method: create_virtualenv 20:19:21 INFO - Creating virtualenv /builds/slave/test/build/venv 20:19:21 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 20:19:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:19:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:19:21 INFO - Using real prefix '/usr' 20:19:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:19:21 INFO - Installing distribute.............................................................................................................................................................................................done. 20:19:23 INFO - Installing pip.................done. 20:19:23 INFO - Return code: 0 20:19:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:19:23 INFO - Reading from file tmpfile_stdout 20:19:23 INFO - Output received: 20:19:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:19:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:19:23 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')]} 20:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:23 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')]} 20:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:23 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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:23 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 20:19:23 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 20:19:23 INFO - Using env: {'DISPLAY': ':0', 20:19:23 INFO - 'HOME': '/home/cltbld', 20:19:23 INFO - 'LANG': 'en_US.UTF-8', 20:19:23 INFO - 'LANGUAGE': 'en_US:en', 20:19:23 INFO - 'LOGNAME': 'cltbld', 20:19:23 INFO - 'MAIL': '/var/mail/cltbld', 20:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:23 INFO - 'MOZ_NO_REMOTE': '1', 20:19:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:23 INFO - 'NO_EM_RESTART': '1', 20:19:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:23 INFO - 'PWD': '/builds/slave/test', 20:19:23 INFO - 'SHELL': '/bin/bash', 20:19:23 INFO - 'SHLVL': '1', 20:19:23 INFO - 'TERM': 'linux', 20:19:23 INFO - 'TMOUT': '86400', 20:19:23 INFO - 'USER': 'cltbld', 20:19:23 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:23 INFO - '_': '/tools/buildbot/bin/python'} 20:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:24 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 20:19:24 INFO - Cleaning up... 20:19:24 INFO - Return code: 0 20:19:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:24 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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:24 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 20:19:24 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 20:19:24 INFO - Using env: {'DISPLAY': ':0', 20:19:24 INFO - 'HOME': '/home/cltbld', 20:19:24 INFO - 'LANG': 'en_US.UTF-8', 20:19:24 INFO - 'LANGUAGE': 'en_US:en', 20:19:24 INFO - 'LOGNAME': 'cltbld', 20:19:24 INFO - 'MAIL': '/var/mail/cltbld', 20:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:24 INFO - 'MOZ_NO_REMOTE': '1', 20:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:24 INFO - 'NO_EM_RESTART': '1', 20:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:24 INFO - 'PWD': '/builds/slave/test', 20:19:24 INFO - 'SHELL': '/bin/bash', 20:19:24 INFO - 'SHLVL': '1', 20:19:24 INFO - 'TERM': 'linux', 20:19:24 INFO - 'TMOUT': '86400', 20:19:24 INFO - 'USER': 'cltbld', 20:19:24 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:24 INFO - '_': '/tools/buildbot/bin/python'} 20:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:24 INFO - Downloading/unpacking psutil>=3.1.1 20:19:24 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 20:19:24 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 20:19:24 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 20:19:24 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 20:19:24 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 20:19:24 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 20:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:19:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:19:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:19:26 INFO - Installing collected packages: psutil 20:19:26 INFO - Running setup.py install for psutil 20:19:26 INFO - building 'psutil._psutil_linux' extension 20:19:26 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 20:19:26 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 20:19:26 INFO - building 'psutil._psutil_posix' extension 20:19:26 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 20:19:27 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 20:19:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:19:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:19:27 INFO - Successfully installed psutil 20:19:27 INFO - Cleaning up... 20:19:27 INFO - Return code: 0 20:19:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:27 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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:27 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 20:19:27 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 20:19:27 INFO - Using env: {'DISPLAY': ':0', 20:19:27 INFO - 'HOME': '/home/cltbld', 20:19:27 INFO - 'LANG': 'en_US.UTF-8', 20:19:27 INFO - 'LANGUAGE': 'en_US:en', 20:19:27 INFO - 'LOGNAME': 'cltbld', 20:19:27 INFO - 'MAIL': '/var/mail/cltbld', 20:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:27 INFO - 'MOZ_NO_REMOTE': '1', 20:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:27 INFO - 'NO_EM_RESTART': '1', 20:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:27 INFO - 'PWD': '/builds/slave/test', 20:19:27 INFO - 'SHELL': '/bin/bash', 20:19:27 INFO - 'SHLVL': '1', 20:19:27 INFO - 'TERM': 'linux', 20:19:27 INFO - 'TMOUT': '86400', 20:19:27 INFO - 'USER': 'cltbld', 20:19:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:27 INFO - '_': '/tools/buildbot/bin/python'} 20:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:19:29 INFO - Installing collected packages: mozsystemmonitor 20:19:29 INFO - Running setup.py install for mozsystemmonitor 20:19:29 INFO - Successfully installed mozsystemmonitor 20:19:29 INFO - Cleaning up... 20:19:29 INFO - Return code: 0 20:19:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:19: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')]} 20:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19: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')]} 20:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:19:29 INFO - Using env: {'DISPLAY': ':0', 20:19:29 INFO - 'HOME': '/home/cltbld', 20:19:29 INFO - 'LANG': 'en_US.UTF-8', 20:19:29 INFO - 'LANGUAGE': 'en_US:en', 20:19:29 INFO - 'LOGNAME': 'cltbld', 20:19:29 INFO - 'MAIL': '/var/mail/cltbld', 20:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:29 INFO - 'MOZ_NO_REMOTE': '1', 20:19:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:29 INFO - 'NO_EM_RESTART': '1', 20:19:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:29 INFO - 'PWD': '/builds/slave/test', 20:19:29 INFO - 'SHELL': '/bin/bash', 20:19:29 INFO - 'SHLVL': '1', 20:19:29 INFO - 'TERM': 'linux', 20:19:29 INFO - 'TMOUT': '86400', 20:19:29 INFO - 'USER': 'cltbld', 20:19:29 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:29 INFO - '_': '/tools/buildbot/bin/python'} 20:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:29 INFO - Downloading/unpacking jsonschema==2.5.1 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:19:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:19:31 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 20:19:31 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 20:19:31 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 20:19:31 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 20:19:31 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 20:19:31 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 20:19:32 INFO - Downloading functools32-3.2.3-2.tar.gz 20:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:19:32 INFO - warning: no files found matching '*.txt' 20:19:32 INFO - no previously-included directories found matching 'build' 20:19:32 INFO - no previously-included directories found matching 'dist' 20:19:32 INFO - no previously-included directories found matching '.git*' 20:19:32 INFO - Installing collected packages: jsonschema, functools32 20:19:32 INFO - Running setup.py install for functools32 20:19:32 INFO - warning: no files found matching '*.txt' 20:19:32 INFO - no previously-included directories found matching 'build' 20:19:32 INFO - no previously-included directories found matching 'dist' 20:19:32 INFO - no previously-included directories found matching '.git*' 20:19:32 INFO - Successfully installed jsonschema functools32 20:19:32 INFO - Cleaning up... 20:19:32 INFO - Return code: 0 20:19:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:19:32 INFO - Using env: {'DISPLAY': ':0', 20:19:32 INFO - 'HOME': '/home/cltbld', 20:19:32 INFO - 'LANG': 'en_US.UTF-8', 20:19:32 INFO - 'LANGUAGE': 'en_US:en', 20:19:32 INFO - 'LOGNAME': 'cltbld', 20:19:32 INFO - 'MAIL': '/var/mail/cltbld', 20:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:32 INFO - 'MOZ_NO_REMOTE': '1', 20:19:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:32 INFO - 'NO_EM_RESTART': '1', 20:19:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:32 INFO - 'PWD': '/builds/slave/test', 20:19:32 INFO - 'SHELL': '/bin/bash', 20:19:32 INFO - 'SHLVL': '1', 20:19:32 INFO - 'TERM': 'linux', 20:19:32 INFO - 'TMOUT': '86400', 20:19:32 INFO - 'USER': 'cltbld', 20:19:32 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:32 INFO - '_': '/tools/buildbot/bin/python'} 20:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:19:32 INFO - Cleaning up... 20:19:32 INFO - Return code: 0 20:19:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:19:32 INFO - Using env: {'DISPLAY': ':0', 20:19:32 INFO - 'HOME': '/home/cltbld', 20:19:32 INFO - 'LANG': 'en_US.UTF-8', 20:19:32 INFO - 'LANGUAGE': 'en_US:en', 20:19:32 INFO - 'LOGNAME': 'cltbld', 20:19:32 INFO - 'MAIL': '/var/mail/cltbld', 20:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:32 INFO - 'MOZ_NO_REMOTE': '1', 20:19:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:32 INFO - 'NO_EM_RESTART': '1', 20:19:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:32 INFO - 'PWD': '/builds/slave/test', 20:19:32 INFO - 'SHELL': '/bin/bash', 20:19:32 INFO - 'SHLVL': '1', 20:19:32 INFO - 'TERM': 'linux', 20:19:32 INFO - 'TMOUT': '86400', 20:19:32 INFO - 'USER': 'cltbld', 20:19:32 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:32 INFO - '_': '/tools/buildbot/bin/python'} 20:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:32 INFO - Downloading/unpacking blobuploader==1.2.4 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19:34 INFO - Downloading blobuploader-1.2.4.tar.gz 20:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:19:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:19:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:19:35 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 20:19:35 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 20:19:35 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 20:19:35 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 20:19:35 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 20:19:35 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 20:19:35 INFO - Downloading docopt-0.6.1.tar.gz 20:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:19:35 INFO - Installing collected packages: blobuploader, requests, docopt 20:19:35 INFO - Running setup.py install for blobuploader 20:19:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:19:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:19:35 INFO - Running setup.py install for requests 20:19:35 INFO - Running setup.py install for docopt 20:19:36 INFO - Successfully installed blobuploader requests docopt 20:19:36 INFO - Cleaning up... 20:19:36 INFO - Return code: 0 20:19:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:36 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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:36 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 20:19:36 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 20:19:36 INFO - Using env: {'DISPLAY': ':0', 20:19:36 INFO - 'HOME': '/home/cltbld', 20:19:36 INFO - 'LANG': 'en_US.UTF-8', 20:19:36 INFO - 'LANGUAGE': 'en_US:en', 20:19:36 INFO - 'LOGNAME': 'cltbld', 20:19:36 INFO - 'MAIL': '/var/mail/cltbld', 20:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:36 INFO - 'MOZ_NO_REMOTE': '1', 20:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:36 INFO - 'NO_EM_RESTART': '1', 20:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:36 INFO - 'PWD': '/builds/slave/test', 20:19:36 INFO - 'SHELL': '/bin/bash', 20:19:36 INFO - 'SHLVL': '1', 20:19:36 INFO - 'TERM': 'linux', 20:19:36 INFO - 'TMOUT': '86400', 20:19:36 INFO - 'USER': 'cltbld', 20:19:36 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:36 INFO - '_': '/tools/buildbot/bin/python'} 20:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:19:36 INFO - Running setup.py (path:/tmp/pip-WqaYWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:19:36 INFO - Running setup.py (path:/tmp/pip-t3EAhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:19:36 INFO - Running setup.py (path:/tmp/pip-4wjHwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:19:36 INFO - Running setup.py (path:/tmp/pip-2V6XvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:19:36 INFO - Running setup.py (path:/tmp/pip-hatTBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:19:36 INFO - Running setup.py (path:/tmp/pip-5WBS0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:19:36 INFO - Running setup.py (path:/tmp/pip-Pwn1qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:19:36 INFO - Running setup.py (path:/tmp/pip-ZuIMSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:19:36 INFO - Running setup.py (path:/tmp/pip-4qjDS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:19:36 INFO - Running setup.py (path:/tmp/pip-ohN7Lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:19:37 INFO - Running setup.py (path:/tmp/pip-pdxkag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:19:37 INFO - Running setup.py (path:/tmp/pip-ciQs7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:19:37 INFO - Running setup.py (path:/tmp/pip-aOlS_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:19:37 INFO - Running setup.py (path:/tmp/pip-Ho0X2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:37 INFO - Running setup.py (path:/tmp/pip-OYkEWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:19:37 INFO - Running setup.py (path:/tmp/pip-4f7Wb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:19:37 INFO - Running setup.py (path:/tmp/pip-qujp1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:19:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:19:37 INFO - Running setup.py install for manifestparser 20:19:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:19:37 INFO - Running setup.py install for mozcrash 20:19:37 INFO - Running setup.py install for mozdebug 20:19:37 INFO - Running setup.py install for mozdevice 20:19:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:19:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Running setup.py install for mozfile 20:19:38 INFO - Running setup.py install for mozhttpd 20:19:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Running setup.py install for mozinfo 20:19:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Running setup.py install for mozInstall 20:19:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Running setup.py install for mozleak 20:19:38 INFO - Running setup.py install for mozlog 20:19:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Running setup.py install for moznetwork 20:19:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Running setup.py install for mozprocess 20:19:38 INFO - Running setup.py install for mozprofile 20:19:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Running setup.py install for mozrunner 20:19:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:19:39 INFO - Running setup.py install for mozscreenshot 20:19:39 INFO - Running setup.py install for moztest 20:19:39 INFO - Running setup.py install for mozversion 20:19:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:19:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:19:39 INFO - Cleaning up... 20:19:39 INFO - Return code: 0 20:19:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:39 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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:39 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 20:19:39 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 20:19:39 INFO - Using env: {'DISPLAY': ':0', 20:19:39 INFO - 'HOME': '/home/cltbld', 20:19:39 INFO - 'LANG': 'en_US.UTF-8', 20:19:39 INFO - 'LANGUAGE': 'en_US:en', 20:19:39 INFO - 'LOGNAME': 'cltbld', 20:19:39 INFO - 'MAIL': '/var/mail/cltbld', 20:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:39 INFO - 'MOZ_NO_REMOTE': '1', 20:19:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:39 INFO - 'NO_EM_RESTART': '1', 20:19:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:39 INFO - 'PWD': '/builds/slave/test', 20:19:39 INFO - 'SHELL': '/bin/bash', 20:19:39 INFO - 'SHLVL': '1', 20:19:39 INFO - 'TERM': 'linux', 20:19:39 INFO - 'TMOUT': '86400', 20:19:39 INFO - 'USER': 'cltbld', 20:19:39 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:39 INFO - '_': '/tools/buildbot/bin/python'} 20:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:19:39 INFO - Running setup.py (path:/tmp/pip-mMXabn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:19:39 INFO - Running setup.py (path:/tmp/pip-OtqLjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:19:39 INFO - Running setup.py (path:/tmp/pip-PZ4XH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:19:39 INFO - Running setup.py (path:/tmp/pip-7bJCH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:19:39 INFO - Running setup.py (path:/tmp/pip-o2G2VY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:19:39 INFO - Running setup.py (path:/tmp/pip-jB2Kcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:19:40 INFO - Running setup.py (path:/tmp/pip-JLWiqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:19:40 INFO - Running setup.py (path:/tmp/pip-2yo5LV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:19:40 INFO - Running setup.py (path:/tmp/pip-B5VXwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:19:40 INFO - Running setup.py (path:/tmp/pip-eeEvqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:19:40 INFO - Running setup.py (path:/tmp/pip-kUIuox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:19:40 INFO - Running setup.py (path:/tmp/pip-cdyFDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:19:40 INFO - Running setup.py (path:/tmp/pip-qOvFbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:19:40 INFO - Running setup.py (path:/tmp/pip-3y9S4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:40 INFO - Running setup.py (path:/tmp/pip-U3PKpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:19:40 INFO - Running setup.py (path:/tmp/pip-7suwIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:19:40 INFO - Running setup.py (path:/tmp/pip-lhAGa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:19:40 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)) 20:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:19:40 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)) 20:19:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:19:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:19:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:42 INFO - Downloading blessings-1.6.tar.gz 20:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:19:42 INFO - Installing collected packages: blessings 20:19:42 INFO - Running setup.py install for blessings 20:19:43 INFO - Successfully installed blessings 20:19:43 INFO - Cleaning up... 20:19:43 INFO - Return code: 0 20:19:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:19:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:19:43 INFO - Reading from file tmpfile_stdout 20:19:43 INFO - Current package versions: 20:19:43 INFO - argparse == 1.2.1 20:19:43 INFO - blessings == 1.6 20:19:43 INFO - blobuploader == 1.2.4 20:19:43 INFO - docopt == 0.6.1 20:19:43 INFO - functools32 == 3.2.3-2 20:19:43 INFO - jsonschema == 2.5.1 20:19:43 INFO - manifestparser == 1.1 20:19:43 INFO - mozInstall == 1.12 20:19:43 INFO - mozcrash == 1.0 20:19:43 INFO - mozdebug == 0.1 20:19:43 INFO - mozdevice == 0.49 20:19:43 INFO - mozfile == 1.2 20:19:43 INFO - mozhttpd == 0.7 20:19:43 INFO - mozinfo == 0.9 20:19:43 INFO - mozleak == 0.1 20:19:43 INFO - mozlog == 3.4 20:19:43 INFO - moznetwork == 0.27 20:19:43 INFO - mozprocess == 0.23 20:19:43 INFO - mozprofile == 0.28 20:19:43 INFO - mozrunner == 6.13 20:19:43 INFO - mozscreenshot == 0.1 20:19:43 INFO - mozsystemmonitor == 0.3 20:19:43 INFO - moztest == 0.7 20:19:43 INFO - mozversion == 1.4 20:19:43 INFO - psutil == 3.1.1 20:19:43 INFO - requests == 1.2.3 20:19:43 INFO - wsgiref == 0.1.2 20:19:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:43 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 20:19:43 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 20:19:43 INFO - Using env: {'DISPLAY': ':0', 20:19:43 INFO - 'HOME': '/home/cltbld', 20:19:43 INFO - 'LANG': 'en_US.UTF-8', 20:19:43 INFO - 'LANGUAGE': 'en_US:en', 20:19:43 INFO - 'LOGNAME': 'cltbld', 20:19:43 INFO - 'MAIL': '/var/mail/cltbld', 20:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:43 INFO - 'MOZ_NO_REMOTE': '1', 20:19:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:43 INFO - 'NO_EM_RESTART': '1', 20:19:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:43 INFO - 'PWD': '/builds/slave/test', 20:19:43 INFO - 'SHELL': '/bin/bash', 20:19:43 INFO - 'SHLVL': '1', 20:19:43 INFO - 'TERM': 'linux', 20:19:43 INFO - 'TMOUT': '86400', 20:19:43 INFO - 'USER': 'cltbld', 20:19:43 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:43 INFO - '_': '/tools/buildbot/bin/python'} 20:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 INFO - Cleaning up... 20:19:43 INFO - Return code: 0 20:19:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 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 0x7facb80f8e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1279120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, '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': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', '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 20:19:43 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 20:19:43 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 20:19:43 INFO - Using env: {'DISPLAY': ':0', 20:19:43 INFO - 'HOME': '/home/cltbld', 20:19:43 INFO - 'LANG': 'en_US.UTF-8', 20:19:43 INFO - 'LANGUAGE': 'en_US:en', 20:19:43 INFO - 'LOGNAME': 'cltbld', 20:19:43 INFO - 'MAIL': '/var/mail/cltbld', 20:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:43 INFO - 'MOZ_NO_REMOTE': '1', 20:19:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:19:43 INFO - 'NO_EM_RESTART': '1', 20:19:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:43 INFO - 'PWD': '/builds/slave/test', 20:19:43 INFO - 'SHELL': '/bin/bash', 20:19:43 INFO - 'SHLVL': '1', 20:19:43 INFO - 'TERM': 'linux', 20:19:43 INFO - 'TMOUT': '86400', 20:19:43 INFO - 'USER': 'cltbld', 20:19:43 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:43 INFO - '_': '/tools/buildbot/bin/python'} 20:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:19:43 INFO - Cleaning up... 20:19:43 INFO - Return code: 0 20:19:43 INFO - Running post-action listener: _resource_record_post_action 20:19:43 INFO - Running post-action listener: _start_resource_monitoring 20:19:43 INFO - Starting resource monitoring. 20:19:43 INFO - [mozharness: 2016-12-03 04:19:43.799037Z] Finished create-virtualenv step (success) 20:19:43 INFO - [mozharness: 2016-12-03 04:19:43.799563Z] Running install step. 20:19:43 INFO - Running pre-action listener: _resource_record_pre_action 20:19:43 INFO - Running main action method: install 20:19:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:19:44 INFO - Reading from file tmpfile_stdout 20:19:44 INFO - Detecting whether we're running mozinstall >=1.0... 20:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:19:44 INFO - Reading from file tmpfile_stdout 20:19:44 INFO - Output received: 20:19:44 INFO - Usage: mozinstall [options] installer 20:19:44 INFO - Options: 20:19:44 INFO - -h, --help show this help message and exit 20:19:44 INFO - -d DEST, --destination=DEST 20:19:44 INFO - Directory to install application into. [default: 20:19:44 INFO - "/builds/slave/test"] 20:19:44 INFO - --app=APP Application being installed. [default: firefox] 20:19:44 INFO - mkdir: /builds/slave/test/build/application 20:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:20:01 INFO - Reading from file tmpfile_stdout 20:20:01 INFO - Output received: 20:20:01 INFO - /builds/slave/test/build/application/firefox/firefox 20:20:01 INFO - Running post-action listener: _resource_record_post_action 20:20:01 INFO - [mozharness: 2016-12-03 04:20:01.085314Z] Finished install step (success) 20:20:01 INFO - [mozharness: 2016-12-03 04:20:01.085621Z] Running run-tests step. 20:20:01 INFO - Running pre-action listener: _resource_record_pre_action 20:20:01 INFO - Running main action method: run_tests 20:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:20:01 INFO - Python 2.7.3 20:20:01 INFO - Return code: 0 20:20:01 INFO - grabbing minidump binary from tooltool 20:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:20:01 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 0x1309f20>, '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 0x1276c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1276f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:20:01 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 20:20:01 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 20:20:01 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 20:20:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:20:01 INFO - Return code: 0 20:20:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:20:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:20:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:20:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:20:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:20:01 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/1480732623/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 20:20:01 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/1480732623/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 20:20:01 INFO - Using env: {'DISPLAY': ':0', 20:20:01 INFO - 'HOME': '/home/cltbld', 20:20:01 INFO - 'LANG': 'en_US.UTF-8', 20:20:01 INFO - 'LANGUAGE': 'en_US:en', 20:20:01 INFO - 'LOGNAME': 'cltbld', 20:20:01 INFO - 'MAIL': '/var/mail/cltbld', 20:20:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:20:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:01 INFO - 'MOZ_NO_REMOTE': '1', 20:20:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:01 INFO - 'NO_EM_RESTART': '1', 20:20:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:01 INFO - 'PWD': '/builds/slave/test', 20:20:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:20:01 INFO - 'SHELL': '/bin/bash', 20:20:01 INFO - 'SHLVL': '1', 20:20:01 INFO - 'TERM': 'linux', 20:20:01 INFO - 'TMOUT': '86400', 20:20:01 INFO - 'USER': 'cltbld', 20:20:01 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1480729352.164523-1073842972', 20:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:01 INFO - '_': '/tools/buildbot/bin/python'} 20:20:01 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/1480732623/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 20:20:01 INFO - mozversion application_buildid: 20161202183703 20:20:01 INFO - mozversion application_changeset: d2c5b6581d6ab82d04b6ee8d454bb7debe9f4d0e 20:20:01 INFO - mozversion application_display_name: Nightly 20:20:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:20:01 INFO - mozversion application_name: Firefox 20:20:01 INFO - mozversion application_remotingname: firefox 20:20:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:20:01 INFO - mozversion application_vendor: Mozilla 20:20:01 INFO - mozversion application_version: 53.0a1 20:20:01 INFO - mozversion platform_buildid: 20161202183703 20:20:01 INFO - mozversion platform_changeset: d2c5b6581d6ab82d04b6ee8d454bb7debe9f4d0e 20:20:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:20:01 INFO - mozversion platform_version: 53.0a1 20:20:01 INFO - using testdate: 1480738801 20:20:01 INFO - actual date: 1480738801 20:20:01 INFO - starting webserver on 'localhost:36664' 20:20:01 INFO - SUITE-START | Running 2 tests 20:20:01 INFO - TEST-START | dromaeo_css 20:20:01 INFO - operating with platform_type : linux_ 20:20:01 INFO - Initialising browser for dromaeo_css test... 20:20:01 INFO - TEST-INFO | started process 14295 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsj7we4/profile http://localhost:36664/getInfo.html) 20:20:05 INFO - PROCESS | 14295 | __metrics Screen width/height:1600/1200 20:20:05 INFO - PROCESS | 14295 | colorDepth:24 20:20:05 INFO - PROCESS | 14295 | Browser inner width/height: 1024/697 20:20:05 INFO - PROCESS | 14295 | __metrics 20:20:06 INFO - PROCESS | 14295 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:20:06 INFO - PROCESS | 14295 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:20:06 INFO - PROCESS | 14295 | console.error: 20:20:06 INFO - PROCESS | 14295 | Message: Error: Connection closed before committing the transaction. 20:20:06 INFO - PROCESS | 14295 | Stack: 20:20:06 INFO - PROCESS | 14295 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 20:20:06 INFO - PROCESS | 14295 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 20:20:06 INFO - PROCESS | 14295 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 20:20:06 INFO - PROCESS | 14295 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 20:20:06 INFO - PROCESS | 14295 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 20:20:06 INFO - PROCESS | 14295 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 20:20:06 INFO - PROCESS | 14295 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 20:20:06 INFO - PROCESS | 14295 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 20:20:06 INFO - PROCESS | 14295 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 20:20:06 INFO - PROCESS | 14295 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 20:20:06 INFO - PROCESS | 14295 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 20:20:06 INFO - PROCESS | 14295 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 20:20:06 INFO - PROCESS | 14295 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 20:20:06 INFO - PROCESS | 14295 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 20:20:06 INFO - PROCESS | 14295 | 20:20:07 INFO - PROCESS | 14295 | console.error: PushService: 20:20:07 INFO - PROCESS | 14295 | stateChangeProcessEnqueue: Error transitioning state 20:20:07 INFO - PROCESS | 14295 | UnknownError 20:20:08 INFO - TEST-INFO | 14295: exit 0 20:20:08 INFO - Browser initialized. 20:20:08 INFO - Running cycle 1/1 for dromaeo_css test... 20:20:08 INFO - TEST-INFO | started process 14380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsj7we4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:20:20 INFO - PROCESS | 14380 | RSS: Main: 172322816 20:20:20 INFO - PROCESS | 14380 | 20:21:11 INFO - PROCESS | 14380 | 1480738871526 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] 20:23:22 INFO - PROCESS | 14380 | Cycle 1(1): loaded http://localhost:36664/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36664/tests/dromaeo/cssquery-ext.html) 20:23:23 INFO - PROCESS | 14380 | RSS: Main: 341188608 20:23:23 INFO - PROCESS | 14380 | 20:26:26 INFO - PROCESS | 14380 | Cycle 1(1): loaded http://localhost:36664/tests/dromaeo/cssquery-ext.html (next: http://localhost:36664/tests/dromaeo/cssquery-jquery.html) 20:26:27 INFO - PROCESS | 14380 | RSS: Main: 272437248 20:26:27 INFO - PROCESS | 14380 | 20:29:29 INFO - PROCESS | 14380 | Cycle 1(1): loaded http://localhost:36664/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36664/tests/dromaeo/cssquery-mootools.html) 20:29:29 INFO - PROCESS | 14380 | RSS: Main: 238522368 20:29:29 INFO - PROCESS | 14380 | 20:32:31 INFO - PROCESS | 14380 | Cycle 1(1): loaded http://localhost:36664/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36664/tests/dromaeo/cssquery-prototype.html) 20:32:32 INFO - PROCESS | 14380 | RSS: Main: 251801600 20:32:32 INFO - PROCESS | 14380 | 20:35:35 INFO - PROCESS | 14380 | Cycle 1(1): loaded http://localhost:36664/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36664/tests/dromaeo/cssquery-yui.html) 20:35:35 INFO - PROCESS | 14380 | RSS: Main: 216473600 20:35:35 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | Cycle 1(1): loaded http://localhost:36664/tests/dromaeo/cssquery-yui.html (next: http://localhost:36664/tests/dromaeo/cssquery-dojo.html) 20:38:38 INFO - PROCESS | 14380 | RSS: Main: 296067072 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | __start_tp_report 20:38:38 INFO - PROCESS | 14380 | _x_x_mozilla_page_load 20:38:38 INFO - PROCESS | 14380 | _x_x_mozilla_page_load_details 20:38:38 INFO - PROCESS | 14380 | |i|pagename|runs| 20:38:38 INFO - PROCESS | 14380 | |0;dojo.html;3220.533022711707;3234.8220847853368;3267.1178781728936;3291.358185153893;3318.236805534727;5211.140161873296;5296.073187192245;5331.22697208905;5403.864903377417;5416.51251387375;5314.92027619586;5331.520163185312;5410.864728381793;5416.864578385543;5459.617826752129;4795.160846851804;4854.732989685566;4854.732989685584;4895.069936712013;4946.480619534933;4823.42118945727;4958.429780575226;4995.475475075101;4998.475160108191;5031.32077169581;4726.858194254178;4837.782299796518;4840.225563909793;4881.438634557036;4950.975245123769;4756.809727610891;4804.1352556539805;4807.062622788558;4808.61531077513;4813.5427134422525;4774.307725379836;4780.043991201774;4783.402074740658;4784.976075119637;4785.688930219525;5216.895662086737;5280.313559237275;5281.181416880389;5282.181261904411;5283.022640811424;5651.2935883014625;5676.631018983794;5687.317521897357;5731.856703582402;5780.913286300709;5739.426057394269;5747.798827041033;5749.367569567345;5756.65460072397;5758.078707406795;5149.845504634867;5238.423773384925;5374.7043912584795;5382.6770393776105;5388.865278368072;5743.8564035899435;5745.827625171168;5793.159991801165;5824.650520968756;5891.675957822361;5621.297337832771;5630.239917611152;5641.605087643826;5644.57665675076;5646.858828529237;5584.804531841366;5695.345035320991;5702.857428564319;5703;5711.314642242957;5605;5640.887182256414;5648.8870222595315;5692.6015178937905;5772;5701.458361455655;5708.143778433268;5717.113847353667;5717.885642287131;5754.856128596819;4283.9504321441445;4285;4289.935650965175;4296.76367799774;4323.373110898902;3798.2973149967343;3817.522809648794;3831.578526362098;3869.9226015479535;3894.1432884765304;5403.13549832025;5406.783728650888;5411.972940135274;5440.972795136078;5452.345718513804;5725.885482290414;5771.797987070432;5772.5670574707065;5779.768809247583;5783.681897495594;5185.377754669388;5201.687898726089;5324.04167249891;5368.489993450616;5401.081816091274;4929.852104436873;4931.9753401232765;4933.087378834927;4957.3555437793575;4972.453030166679;5937.70311484424;5952;5952.672603006789;5961.672108034099;5971.0744834550715;5586.357568879631;5622.465865742748;5708.942910570841;5777.104548794943;5833.329167145663;5947.672877991752;5970.104484327386;5988.850278743066;6039.698015099315;6040.063790112539;5647.6893770843;5694.572907031907;5699.287589051369;5702.743376547983;5704.3725190228215;5619;5697.8860422790485;5730.369659337386;5783.479486846204;5786.247787787561;5591.27313449249;5634.85912852182;5637.746301416364;5670.631408958405;5722.914156287576;5649.745761440828;5652.039153343859;5656.349519805275;5658.5473162147755;5659.943400565941;5440.891182176413;5623.437656234508;5624.915626265692;5629.437056294501;5630.8029218977135;5533.557315414838;5543.473370029841;5597.720113994203;5601.747921343468;5609.074502707008;5446.074167391632;5478.342598888159;5495.972520137374;5496.862578435411;5503.3395992481155 20:38:38 INFO - PROCESS | 14380 | |1;ext.html;538.7898719499395;604.4076804731363;624.965626890521;643.6427782580666;647.6502688548185;30663.540046899285;33242;33245;33256.83371583139;33287;41096.58903410965;42078;42110.57889421105;45556;45571.544284557145;8392.244697977165;8512.78718032051;8524.445911015795;8533.74398768038;8534.957325213365;7773.106092799316;7962.522248665098;8455.450395724289;9726.70819875405;9959.203263738884;9891.752706182331;9958.551865166084;9964.501774911261;9991.850122248172;10079;10139.695809125702;10199.4390308533;10202.846957295647;10204.540795664212;10209;9871.309008369417;9910.653127140587;9917.950410247902;9920.503974801304;9999.35004224723;39088.413673794916;39418.802905985285;39637;39927.80036099801;39929;3373.1229880231317;3622.2574372253625;3718.3864662330684;3834.789086600236;3959.208158368309;30264.54603180954;30638.693613063806;30880.38239235225;30952;32479.188020299443;4083.5508094109623;4211.305134652778;4246.214450326699;4297.205017071852;4346.087321662455;325026;327796;327856;327956;328732.3563382192;54168.18747718787;54689.72655136699;54701.726491368085;54716.179257311174;54721.1791823123;57339.71330143406;57441;57501.71249143811;57546.136807947914;57550.13674794881;15022.399104035934;15438.9228053859;15455.53633391;15456.304466299043;15468.845311546966;10457.424841633783;11329.830052549218;11395.544178232944;11404.201705880509;11407.771844563062;13608.251546164858;14787.852121478647;14978.10131392098;15244.771328430083;15650.530484085495;5873.3245676746865;6025.132209015219;6030.577859549877;6137.9079313810325;6138.5396095292135;857.7555396712032;931.8509038553799;954.3891909178132;963.3834346018554;1014.9238807089498;29663;30504.237394065327;30619;30877.228069298446;31246.21884452907;1043.3114144664485;1047.1831971062584;1069.6363236499628;1091.9672409827717;1093.3877028863863;15198.544043678707;16080.678386432206;16116;16244.431444899372;16408.26162822676;419331;419499;420956;421055;421267;54578.72710636501;54745.452545474036;54774.452255476936;54777;55010.72494637501;1184.466989854569;1239.1883316427754;1281.9038572107129;1311.3508813137557;1313.9685347002558;27197.72802271952;31109.68890311068;31838.36323273475;31956.219441080233;32010.51984220192;46953;47351.28973065338;47399.76300118477;47453.52546474629;47488.762556187;367307;367433;368411;368581;369115;26136.34659133537;26818.06136785203;26951.326216844736;28423.43153136967;37989.62010379861;44284.778576108205;44334.334984975896;44900;45203.54796452125;45598;37696.43455348117;37955.49325738227;38389;38593;39099.218015640094;38111;38122;38580.80709596434;39170.80414597909;39857.60142398655;38152.61847381491;38682.419763703;38745;39988.40017399683;40161;32827.835860820545;35137.824310878284;35156.472652910736;35974.100647484025;36062.278754425286;26878.32804179911;27108.72891271141;27136.864315679086;27342.45315093727;27487.312817178932 20:38:38 INFO - PROCESS | 14380 | |2;jquery.html;370.8071802662615;377.8677462887988;401.45803165726267;407.792026066706;417.6596074199527;13803.861961380384;15444;17210.311587536515;18901.810981890176;19523.609527809436;17826.64346713068;17887.28450861964;18140.36508722195;18216.089195540237;18232.635347293046;5493.505584497394;5710.343310519281;5712.800051998181;5717.571182161334;5719;5351.678899266057;5599.468050535193;5620.325560932693;5622.409646987069;5628.605997580171;5550.195221692852;5570.582206334541;5591.245181900453;5596.27248457702;5596.4403559643915;5360.2763626910255;5638.718064096779;5670.291213598301;5716.971415142898;5721.599488035839;5397.298351214317;5556.9444305556835;5568.7215639217875;5574.191742197399;5576;13165.670858228525;13296.534621288305;13337.53318633843;13349.532766353228;16063.839361606468;2129.9787002130092;2184.6286131357706;2236.915096178352;2290.6449500327476;2333.9649905251435;10283.760615406123;10678.305910115818;10721.410322432263;12793;12995.480180792756;1884.9246030158777;1897.231621193405;1929.9035048247672;1931.2371613212842;1936.06101040995;53690;71880;72853.27146728517;78736;81321;9836.409815411098;10073.798524029478;10094.242931780145;10271.229657775548;10425.218108641882;14000.789988150185;14004.159750415009;14012.159270443806;14026.50907218263;14035.368408421646;7281.745138920112;7456.664450099758;7582.54504729718;7586.62066896653;7717.112532058773;7333.926660733431;7353.742619008309;7536.924630753732;7541.849163016709;7543.924560754323;4217.831286748557;4286.464191976003;4410.735355878657;4436.134953684001;4671.205894997858;1661.7341225404118;1726.4043904852806;1781.4744650328184;1878.6054928464903;1915.7317975483443;843.4770442325674;904.8235594059096;931.7763736703142;955.073578628729;1014.8532158660813;17796.199171037333;18950.431487055135;19216.327428539935;19355;19486;920.636348642296;927.2720914082413;929.8512238041881;933.1461712533091;976.799756050008;10143.594256229668;10791.730206744736;10820.513076911559;10829.67510974672;10831;380704;384562;388226;414130;416889;87843.56078219568;88073.55963220143;91054.54472727593;91202.31086767568;91837;914.4376208631777;935.0836180543106;965.7199412170391;965.8020105878278;972.7957129002847;12148.392580371123;15887.761683574525;16836.74744878803;17087;17176;38043;38879;39468;42372.55088215816;44178.779106104266;52802.73598631982;53122.4687753133;53889.73055134699;55803.72098139646;56125.15812262901;7464.92535074664;7882.369410447036;7988.281054705104;8089.555074470961;8228.794280143044;9690.95154524223;11538.423078845855;11555.595554155565;11728.354940478355;12042.879571204176;10146.543405546916;10259.333143345659;10379.636712715019;10610.363378197333;10706.89293107059;9687.854682179632;10286.485675716334;10499.737506562396;10600.575976960836;10694.786104278026;10351.896481035094;10609.098226650822;10625.787484250117;10701.250912436055;10701.464926753786;7754.7673569793;7966.123726389977;7983.722604383271;8108.2297181767635;8415.789605259917;8245.83508329818;10181.134603558781;10212.285140040125;10561.049505544537;10777.946110269399 20:38:38 INFO - PROCESS | 14380 | |3;mootools.html;1408.3169662713585;1431.8568143185682;1529.4341093795297;1710.5210541048511;1744.7470116833051;6890.2420733719255;7143.071400717899;7389.778306650803;7827.843443131141;7846.646900889466;7388.298111679382;7608.69565217392;7617.5810330431805;7767.339776119023;7770.33952114071;2177.5536015116904;2207.514346843692;2211.3918672365066;2212.8451008429415;2212.889355532225;1942.8639995200342;2005.0876851032817;2191.6822060801173;2247.8876056197205;2273.2952784636814;1988.4531753767758;2013.4664313956814;2151.3653472225733;2225.8330625203175;2229.888505574723;2152.3435352217643;2155.461134716321;2155.698202251686;2158.9028493717665;2159.222679835258;2129.233475948657;2170.522485053286;2188.3544354415485;2190.605690975624;2190.69330293759;7848.254415830487;7907.367410607138;7908.090569584514;7912.643931023118;7919.208079192093;4752.215884379073;4829.9034019319415;4872.707637541759;4876.463589005207;4888.608911287124;2971.6731159572428;3147.6222853257755;3205.695458931421;3209.7753157279;3226.080567038406;959.8032403357365;1034.5603118674608;1038.190211634926;1038.4703801061437;1072.1101485766794;10451.320664156881;10452;10466.476676166161;10478.161747060292;10504.684859454228;4331.45856767904;4332.9133417332105;4353.451936671666;4415.492218394926;4485.910281794411;8866.202041816268;8882.777930551658;8891.510966896753;8902.554872256362;8943.642254309754;4950.18321976877;4962.826301079445;4969.428515720739;4977.651564390458;4977.726225057657;4489.685721999429;4498.032922921588;4512.706674066241;4546.909061818745;4584.701994370355;3619;3674.2835147146584;3691.243295124547;3728.7203459740626;3746.38184699527;1229.489761748864;1229.9053842080377;1238.73986462842;1250.0499620288465;1252.3049707412292;751.8458715963214;826.7974346285199;841.5077179849734;866.6836604639387;896.3232759266712;5102.8518583318455;5217.556507696889;5309.65487243328;5366.731663416814;5395.001924643953;885.0839381240386;897.8787863638456;932.934694571387;941.712777602824;959.6497278493439;3704.6480584344445;3739.4390841373465;3796.715246356479;3842.0586956195352;3893.3575959966856;9217.769555761026;9432.811343773223;9508.429494230368;9518.666846660333;9531.285153613368;9339.20616747584;9386.06139386056;9489;9489.193418559362;9501.287403444769;7089.680964356513;7357.5217610855125;7361.043064401701;7363.447741419415;7363.48455608113;5140.794368225379;5190.636655434235;5222.190560463135;5254.843934334441;5493.23094766741;8479.78800529967;8547.700830470903;8632.30941524664;8652.913470865211;8752.824943501222;8686.82626347482;8816.294696424142;8897.91102088971;8901.599428025625;8906.73279801607;6444.935550644621;6699.062131301719;6747.831304217434;6753.763618273337;6760.121184246016;7346.265373462611;7423.517471364344;7434.405247580289;7439.55362678241;7440.330370266702;6572.572782769104;6690.264070952288;6851.246362900177;6882.69027893756;6938.618375989369;6522.34776522344;6691.832704182434;6792.388685018372;6812.046313516211;6812.114425124702;6208.951046154668;6320.367963203643;6341.587796793194;6382;6385.29761726219;4643.210654188863;4650.372199753126;4717.292406139107;4734.24252119673;4809.158397280392;4398.824047038211;4410.140022695544;4413.426254586883;4430.955690443183;4498.926449293428 20:38:38 INFO - PROCESS | 14380 | |4;prototype.html;322.0515581612153;328.93092450585374;346.01055725393434;347.4371518140612;356.39590893435604;5137.614678899084;5290.487018666132;5352.545033672132;5390.299261096062;5417.783288668458;5213.244079608464;5253.028189784893;5267.659989257797;5323.8287576733055;5351.518363347298;4172.77049762263;4216.177845320164;4227.534971153171;4228.541153302113;4251.319788833787;4007.3588225883864;4066.7153299268916;4108.301588729908;4220.57794220577;4242.15156968606;4118.505779306487;4120.001799127421;4129.504459464868;4130.607592278729;4138.627523522882;4084.775337356444;4114.177164567099;4118.711690181688;4131.710780245384;4134.312696747108;3578.3916734155255;3647.3981793004123;3656.9085772855624;3665.9266814663556;3675.5773086095173;5109.284700141983;5126.743947732792;5132.53553851149;5144.819931302424;5152.536271735562;4697.741624210667;4708.529147085298;4715.599174070208;4741.004389078298;4748.646635708807;5276.947230527684;5494.80768173112;5513.61404701671;5523.6409633373705;5530.695811730353;3961.950083227947;3988.960110398888;3999.960000400017;4002.3196056670436;4020.2763502569524;5697.430256974311;5704.8859022819315;5716.857078573027;5728.455796699307;5739.885202295931;4099.795010249476;4103.630673239423;4108.609682080198;4112.650424713873;4119.691023173274;3881.7282790204413;4062.6343629073526;4071.900586841536;4081.89795255121;4087.591240875948;4478.910421791546;4558.791920141165;4584.839530616479;4730.195866702668;4757.26262429324;5570.025245582007;5580.693061881554;5592;5605.607607467438;5613.663180209201;1404.9462902822884;1406.381192275416;1414.3847426369562;1415.1933397963062;1420;905.4612505559181;938.648006997376;941.4727752458645;947.876766032254;952.6665667016489;5155.948440515622;5159.259536835764;5162.819301324435;5208.765605547759;5230.626246071262;5468.808591699351;5478.780848766084;5492.945070549244;5503.862403439866;5509.925564514881;5084.44071152173;5166.225066240019;5377.9294971436275;5402.432744561765;5421.945780542223;2925.3564215872475;2940.0738767288235;2946;2950.668971512524;2961.9633128404885;5610.541259272619;5748.19525266463;5757;5771.249737534094;5779.815137896721;5598.720063996784;5635.943640563624;5643.971780141155;5646.350669672958;5648.684039543849;5748.540116790647;5772.826815195467;5773.6824474654295;5777.306723193244;5784.334801497714;5437.293151890229;5564.8330550083565;5578.274824272981;5606.411326810789;5606.635568688022;5533.280673512418;5544.861378465571;5549.889002220014;5554.166874968625;5561;5465.426130256425;5653.632513886584;5784.971075144597;5803.970980145073;5824.679642619697;3897.279003384383;3949.861754838567;3952.8616498422416;3956.8219430125137;3968.444417781571;5427.945720542744;5501.477359650827;5515.393306736176;5534.418886016911;5589.944100558942;5539.473749993744;5548.639338442988;5550.7779688812;5565.777368905198;5579.8884022320135;5526.696031718133;5542.8337149885565;5555.30558680165;5565.99624135319;5566.832995010156;5308.7172515818565;5496.780128794803;5624.74688639004;5631;5631.802886898939;5676.914846277226;5689.886202276014;5698;5704.260200638679;5705.172749951317;5448.346198456052;5486.807961721321;5537.861553461195;5568.888622227451;5578.107502799533 20:38:38 INFO - PROCESS | 14380 | |5;yui.html;711.4450728431825;718.3104219948849;734.6106563521332;744.4825846037003;761.4707778094223;13136.211827290357;14057.578272651825;14446.20545869978;14622.122672639622;14672.559823205322;12843;12871.549495767627;13141.342932853344;13923;13939.790903136462;780.7423550228411;789.8657228271207;795.8010497375656;799.2487062161579;800.6997375984006;793.6626933553246;793.817421992942;797.2187256488645;797.2266901105918;798.0582912163644;835.6615570693879;836.7698882807216;837.937154713399;838.0195171649185;838.170211490626;834.64110432514;835.0021724039775;835.2649668291925;836.3768992100863;844.932405407566;616.7810427298334;616.8427051101941;617.6109051297699;617.6386813713938;617.9567430279882;11561;11570.305781653127;11604.941975290154;11640.4761785719;11663.259134103479;12452.875471245263;12516.123871329011;12614.495420183182;12657.177283476636;12863.877460219053;11353.20527563071;11422;11441.313521188673;11449.656510304705;11892.762144757144;4254.191703576311;4269.338252570826;4296.548862369437;4319.092990472004;4342.457192850894;80224;80310.79533807056;80856.59571702163;80992;82192;10762.300450470695;10771.246012779031;10926.125909927187;11006;11086.833697494543;20037.899810501145;20136.59726805485;20158.798412015985;20246.291379801638;20300.79699202989;7555.584442855586;7804.882926756103;8039.316658084128;8097.35221182304;8169.183081691902;4430.955690443118;4453.621442177451;4487.439070116236;4626.329182268552;4757.191277482836;3465.7054150396993;3595.676389124991;3598.604153543108;3600.6039335673054;3603.6036036036016;592.8962431574457;593.4391999560461;597.6533610505983;609.8140067279434;617.4967401567708;639.9121493461134;664.007309072939;667.9833004174934;713.2688993781312;739.1204466684628;9242.86135707965;9484.288678349152;9485.573149208301;9570.234381249484;9760.804783904425;606.4148097086332;665.7836203233871;666.7133132752963;682.5631595778706;684.750066225824;4382.255016647177;4633.559811817872;4721.422117501429;4736.8578942631775;4740.3837501124635;80321.59839200767;82517.17482825214;84232.15767842365;85568.57215713881;87758;75285;76754;77132;77396.2260377389;77403.61298193473;64648;65545.67227163834;65729.34270657328;67549.66225168842;68188;9056.909430905607;9126.589303481493;9238.260939124719;9269.907300926905;9336.673216437392;46300.7684961573;47153;47712.52287477081;47913.76043119762;49429;62155;64365;64563.354366457614;65278.673606631666;68264;11468.770624587294;11661.416929153338;11854.209139277149;11947.641570752892;12641.051921105769;13274.469021239429;13337.466501339837;13595.252261125734;13825.446982120602;13900.304984750519;12377.876221237673;12573.685657858627;12732.427040783372;13286;13294;11837.763244734884;11970.58102966392;12397.194182378116;12768.170068945488;12773.61679149627;12551;12726.809097863725;12760.4257808397;12912.289824059391;13311.26788026629;9592.328537002342;9642.246188174042;10062.39625622465;10102.292839501164;10343.82068071264;9941.380547912557;10956;11000.119990400908;11085.22403431752;11092.223544351818 20:38:38 INFO - PROCESS | 14380 | __end_tp_report 20:38:38 INFO - PROCESS | 14380 | __start_cc_report 20:38:38 INFO - PROCESS | 14380 | _x_x_mozilla_cycle_collect,1587 20:38:38 INFO - PROCESS | 14380 | __end_cc_report 20:38:38 INFO - PROCESS | 14380 | __startTimestamp1480739918875__endTimestamp 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | ------- Summary: start ------- 20:38:38 INFO - PROCESS | 14380 | Number of tests: 6 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | [#0] dojo.html Cycles:165 Average:5314.55 Median:5543.47 stddev:599.26 (10.8%) stddev-sans-first:578.14 20:38:38 INFO - PROCESS | 14380 | Values: 3220.5 3234.8 3267.1 3291.4 3318.2 5211.1 5296.1 5331.2 5403.9 5416.5 5314.9 5331.5 5410.9 5416.9 5459.6 4795.2 4854.7 4854.7 4895.1 4946.5 4823.4 4958.4 4995.5 4998.5 5031.3 4726.9 4837.8 4840.2 4881.4 4951.0 4756.8 4804.1 4807.1 4808.6 4813.5 4774.3 4780.0 4783.4 4785.0 4785.7 5216.9 5280.3 5281.2 5282.2 5283.0 5651.3 5676.6 5687.3 5731.9 5780.9 5739.4 5747.8 5749.4 5756.7 5758.1 5149.8 5238.4 5374.7 5382.7 5388.9 5743.9 5745.8 5793.2 5824.7 5891.7 5621.3 5630.2 5641.6 5644.6 5646.9 5584.8 5695.3 5702.9 5703.0 5711.3 5605.0 5640.9 5648.9 5692.6 5772.0 5701.5 5708.1 5717.1 5717.9 5754.9 4284.0 4285.0 4289.9 4296.8 4323.4 3798.3 3817.5 3831.6 3869.9 3894.1 5403.1 5406.8 5412.0 5441.0 5452.3 5725.9 5771.8 5772.6 5779.8 5783.7 5185.4 5201.7 5324.0 5368.5 5401.1 4929.9 4932.0 4933.1 4957.4 4972.5 5937.7 5952.0 5952.7 5961.7 5971.1 5586.4 5622.5 5708.9 5777.1 5833.3 5947.7 5970.1 5988.9 6039.7 6040.1 5647.7 5694.6 5699.3 5702.7 5704.4 5619.0 5697.9 5730.4 5783.5 5786.2 5591.3 5634.9 5637.7 5670.6 5722.9 5649.7 5652.0 5656.3 5658.5 5659.9 5440.9 5623.4 5624.9 5629.4 5630.8 5533.6 5543.5 5597.7 5601.7 5609.1 5446.1 5478.3 5496.0 5496.9 5503.3 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | [#1] ext.html Cycles:180 Average:53155.43 Median:21111.89 stddev:98515.64 (466.6%) stddev-sans-first:98712.80 20:38:38 INFO - PROCESS | 14380 | Values: 538.8 604.4 625.0 643.6 647.7 30663.5 33242.0 33245.0 33256.8 33287.0 41096.6 42078.0 42110.6 45556.0 45571.5 8392.2 8512.8 8524.4 8533.7 8535.0 7773.1 7962.5 8455.5 9726.7 9959.2 9891.8 9958.6 9964.5 9991.9 10079.0 10139.7 10199.4 10202.8 10204.5 10209.0 9871.3 9910.7 9918.0 9920.5 9999.4 39088.4 39418.8 39637.0 39927.8 39929.0 3373.1 3622.3 3718.4 3834.8 3959.2 30264.5 30638.7 30880.4 30952.0 32479.2 4083.6 4211.3 4246.2 4297.2 4346.1 325026.0 327796.0 327856.0 327956.0 328732.4 54168.2 54689.7 54701.7 54716.2 54721.2 57339.7 57441.0 57501.7 57546.1 57550.1 15022.4 15438.9 15455.5 15456.3 15468.8 10457.4 11329.8 11395.5 11404.2 11407.8 13608.3 14787.9 14978.1 15244.8 15650.5 5873.3 6025.1 6030.6 6137.9 6138.5 857.8 931.9 954.4 963.4 1014.9 29663.0 30504.2 30619.0 30877.2 31246.2 1043.3 1047.2 1069.6 1092.0 1093.4 15198.5 16080.7 16116.0 16244.4 16408.3 419331.0 419499.0 420956.0 421055.0 421267.0 54578.7 54745.5 54774.5 54777.0 55010.7 1184.5 1239.2 1281.9 1311.4 1314.0 27197.7 31109.7 31838.4 31956.2 32010.5 46953.0 47351.3 47399.8 47453.5 47488.8 367307.0 367433.0 368411.0 368581.0 369115.0 26136.3 26818.1 26951.3 28423.4 37989.6 44284.8 44334.3 44900.0 45203.5 45598.0 37696.4 37955.5 38389.0 38593.0 39099.2 38111.0 38122.0 38580.8 39170.8 39857.6 38152.6 38682.4 38745.0 39988.4 40161.0 32827.8 35137.8 35156.5 35974.1 36062.3 26878.3 27108.7 27136.9 27342.5 27487.3 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | [#2] jquery.html Cycles:180 Average:25242.11 Median:404.63 stddev:65956.10 (16300.5%) stddev-sans-first:66114.69 20:38:38 INFO - PROCESS | 14380 | Values: 370.8 377.9 401.5 407.8 417.7 13803.9 15444.0 17210.3 18901.8 19523.6 17826.6 17887.3 18140.4 18216.1 18232.6 5493.5 5710.3 5712.8 5717.6 5719.0 5351.7 5599.5 5620.3 5622.4 5628.6 5550.2 5570.6 5591.2 5596.3 5596.4 5360.3 5638.7 5670.3 5717.0 5721.6 5397.3 5556.9 5568.7 5574.2 5576.0 13165.7 13296.5 13337.5 13349.5 16063.8 2130.0 2184.6 2236.9 2290.6 2334.0 10283.8 10678.3 10721.4 12793.0 12995.5 1884.9 1897.2 1929.9 1931.2 1936.1 53690.0 71880.0 72853.3 78736.0 81321.0 9836.4 10073.8 10094.2 10271.2 10425.2 14000.8 14004.2 14012.2 14026.5 14035.4 7281.7 7456.7 7582.5 7586.6 7717.1 7333.9 7353.7 7536.9 7541.8 7543.9 4217.8 4286.5 4410.7 4436.1 4671.2 1661.7 1726.4 1781.5 1878.6 1915.7 843.5 904.8 931.8 955.1 1014.9 17796.2 18950.4 19216.3 19355.0 19486.0 920.6 927.3 929.9 933.1 976.8 10143.6 10791.7 10820.5 10829.7 10831.0 380704.0 384562.0 388226.0 414130.0 416889.0 87843.6 88073.6 91054.5 91202.3 91837.0 914.4 935.1 965.7 965.8 972.8 12148.4 15887.8 16836.7 17087.0 17176.0 38043.0 38879.0 39468.0 42372.6 44178.8 52802.7 53122.5 53889.7 55803.7 56125.2 7464.9 7882.4 7988.3 8089.6 8228.8 9691.0 11538.4 11555.6 11728.4 12042.9 10146.5 10259.3 10379.6 10610.4 10706.9 9687.9 10286.5 10499.7 10600.6 10694.8 10351.9 10609.1 10625.8 10701.3 10701.5 7754.8 7966.1 7983.7 8108.2 8415.8 8245.8 10181.1 10212.3 10561.0 10777.9 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | [#3] mootools.html Cycles:180 Average:5147.89 Median:5165.72 stddev:2791.05 (54.0%) stddev-sans-first:2784.73 20:38:38 INFO - PROCESS | 14380 | Values: 1408.3 1431.9 1529.4 1710.5 1744.7 6890.2 7143.1 7389.8 7827.8 7846.6 7388.3 7608.7 7617.6 7767.3 7770.3 2177.6 2207.5 2211.4 2212.8 2212.9 1942.9 2005.1 2191.7 2247.9 2273.3 1988.5 2013.5 2151.4 2225.8 2229.9 2152.3 2155.5 2155.7 2158.9 2159.2 2129.2 2170.5 2188.4 2190.6 2190.7 7848.3 7907.4 7908.1 7912.6 7919.2 4752.2 4829.9 4872.7 4876.5 4888.6 2971.7 3147.6 3205.7 3209.8 3226.1 959.8 1034.6 1038.2 1038.5 1072.1 10451.3 10452.0 10466.5 10478.2 10504.7 4331.5 4332.9 4353.5 4415.5 4485.9 8866.2 8882.8 8891.5 8902.6 8943.6 4950.2 4962.8 4969.4 4977.7 4977.7 4489.7 4498.0 4512.7 4546.9 4584.7 3619.0 3674.3 3691.2 3728.7 3746.4 1229.5 1229.9 1238.7 1250.0 1252.3 751.8 826.8 841.5 866.7 896.3 5102.9 5217.6 5309.7 5366.7 5395.0 885.1 897.9 932.9 941.7 959.6 3704.6 3739.4 3796.7 3842.1 3893.4 9217.8 9432.8 9508.4 9518.7 9531.3 9339.2 9386.1 9489.0 9489.2 9501.3 7089.7 7357.5 7361.0 7363.4 7363.5 5140.8 5190.6 5222.2 5254.8 5493.2 8479.8 8547.7 8632.3 8652.9 8752.8 8686.8 8816.3 8897.9 8901.6 8906.7 6444.9 6699.1 6747.8 6753.8 6760.1 7346.3 7423.5 7434.4 7439.6 7440.3 6572.6 6690.3 6851.2 6882.7 6938.6 6522.3 6691.8 6792.4 6812.0 6812.1 6209.0 6320.4 6341.6 6382.0 6385.3 4643.2 4650.4 4717.3 4734.2 4809.2 4398.8 4410.1 4413.4 4431.0 4498.9 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | [#4] prototype.html Cycles:180 Average:4641.96 Median:5352.03 stddev:1356.22 (25.3%) stddev-sans-first:1320.70 20:38:38 INFO - PROCESS | 14380 | Values: 322.1 328.9 346.0 347.4 356.4 5137.6 5290.5 5352.5 5390.3 5417.8 5213.2 5253.0 5267.7 5323.8 5351.5 4172.8 4216.2 4227.5 4228.5 4251.3 4007.4 4066.7 4108.3 4220.6 4242.2 4118.5 4120.0 4129.5 4130.6 4138.6 4084.8 4114.2 4118.7 4131.7 4134.3 3578.4 3647.4 3656.9 3665.9 3675.6 5109.3 5126.7 5132.5 5144.8 5152.5 4697.7 4708.5 4715.6 4741.0 4748.6 5276.9 5494.8 5513.6 5523.6 5530.7 3962.0 3989.0 4000.0 4002.3 4020.3 5697.4 5704.9 5716.9 5728.5 5739.9 4099.8 4103.6 4108.6 4112.7 4119.7 3881.7 4062.6 4071.9 4081.9 4087.6 4478.9 4558.8 4584.8 4730.2 4757.3 5570.0 5580.7 5592.0 5605.6 5613.7 1404.9 1406.4 1414.4 1415.2 1420.0 905.5 938.6 941.5 947.9 952.7 5155.9 5159.3 5162.8 5208.8 5230.6 5468.8 5478.8 5492.9 5503.9 5509.9 5084.4 5166.2 5377.9 5402.4 5421.9 2925.4 2940.1 2946.0 2950.7 2962.0 5610.5 5748.2 5757.0 5771.2 5779.8 5598.7 5635.9 5644.0 5646.4 5648.7 5748.5 5772.8 5773.7 5777.3 5784.3 5437.3 5564.8 5578.3 5606.4 5606.6 5533.3 5544.9 5549.9 5554.2 5561.0 5465.4 5653.6 5785.0 5804.0 5824.7 3897.3 3949.9 3952.9 3956.8 3968.4 5427.9 5501.5 5515.4 5534.4 5589.9 5539.5 5548.6 5550.8 5565.8 5579.9 5526.7 5542.8 5555.3 5566.0 5566.8 5308.7 5496.8 5624.7 5631.0 5631.8 5676.9 5689.9 5698.0 5704.3 5705.2 5448.3 5486.8 5537.9 5568.9 5578.1 20:38:38 INFO - PROCESS | 14380 | 20:38:38 INFO - PROCESS | 14380 | [#5] yui.html Cycles:180 Average:18047.22 Median:48671.38 stddev:24431.59 (50.2%) stddev-sans-first:24465.44 20:38:38 INFO - PROCESS | 14380 | Values: 711.4 718.3 734.6 744.5 761.5 13136.2 14057.6 14446.2 14622.1 14672.6 12843.0 12871.5 13141.3 13923.0 13939.8 780.7 789.9 795.8 799.2 800.7 793.7 793.8 797.2 797.2 798.1 835.7 836.8 837.9 838.0 838.2 834.6 835.0 835.3 836.4 844.9 616.8 616.8 617.6 617.6 618.0 11561.0 11570.3 11604.9 11640.5 11663.3 12452.9 12516.1 12614.5 12657.2 12863.9 11353.2 11422.0 11441.3 11449.7 11892.8 4254.2 4269.3 4296.5 4319.1 4342.5 80224.0 80310.8 80856.6 80992.0 82192.0 10762.3 10771.2 10926.1 11006.0 11086.8 20037.9 20136.6 20158.8 20246.3 20300.8 7555.6 7804.9 8039.3 8097.4 8169.2 4431.0 4453.6 4487.4 4626.3 4757.2 3465.7 3595.7 3598.6 3600.6 3603.6 592.9 593.4 597.7 609.8 617.5 639.9 664.0 668.0 713.3 739.1 9242.9 9484.3 9485.6 9570.2 9760.8 606.4 665.8 666.7 682.6 684.8 4382.3 4633.6 4721.4 4736.9 4740.4 80321.6 82517.2 84232.2 85568.6 87758.0 75285.0 76754.0 77132.0 77396.2 77403.6 64648.0 65545.7 65729.3 67549.7 68188.0 9056.9 9126.6 9238.3 9269.9 9336.7 46300.8 47153.0 47712.5 47913.8 49429.0 62155.0 64365.0 64563.4 65278.7 68264.0 11468.8 11661.4 11854.2 11947.6 12641.1 13274.5 13337.5 13595.3 13825.4 13900.3 12377.9 12573.7 12732.4 13286.0 13294.0 11837.8 11970.6 12397.2 12768.2 12773.6 12551.0 12726.8 12760.4 12912.3 13311.3 9592.3 9642.2 10062.4 10102.3 10343.8 9941.4 10956.0 11000.1 11085.2 11092.2 20:38:38 INFO - PROCESS | 14380 | -------- Summary: end -------- 20:38:38 INFO - PROCESS | 14380 | 20:38:39 INFO - TEST-INFO | 14380: exit 0 20:38:39 INFO - TEST-OK | dromaeo_css | took 1118434ms 20:38:39 INFO - TEST-START | kraken 20:38:39 INFO - operating with platform_type : linux_ 20:38:39 INFO - Initialising browser for kraken test... 20:38:39 INFO - TEST-INFO | started process 14689 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphl2AyL/profile http://localhost:36664/getInfo.html) 20:38:44 INFO - PROCESS | 14689 | __metrics Screen width/height:1600/1200 20:38:44 INFO - PROCESS | 14689 | colorDepth:24 20:38:44 INFO - PROCESS | 14689 | Browser inner width/height: 1024/697 20:38:44 INFO - PROCESS | 14689 | __metrics 20:38:45 INFO - PROCESS | 14689 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:38:45 INFO - PROCESS | 14689 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:38:45 INFO - PROCESS | 14689 | console.error: PushService: 20:38:45 INFO - PROCESS | 14689 | stateChangeProcessEnqueue: Error transitioning state 20:38:45 INFO - PROCESS | 14689 | UnknownError 20:38:46 INFO - TEST-INFO | 14689: exit 0 20:38:46 INFO - Browser initialized. 20:38:46 INFO - Running cycle 1/1 for kraken test... 20:38:46 INFO - TEST-INFO | started process 14773 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphl2AyL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:38:58 INFO - PROCESS | 14773 | RSS: Main: 172199936 20:38:58 INFO - PROCESS | 14773 | 20:39:01 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36664/tests/kraken/driver.html?testName=audio-beat-detection) 20:39:02 INFO - PROCESS | 14773 | RSS: Main: 234754048 20:39:02 INFO - PROCESS | 14773 | 20:39:04 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36664/tests/kraken/driver.html?testName=audio-dft) 20:39:05 INFO - PROCESS | 14773 | RSS: Main: 216805376 20:39:05 INFO - PROCESS | 14773 | 20:39:08 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36664/tests/kraken/driver.html?testName=audio-fft) 20:39:08 INFO - PROCESS | 14773 | RSS: Main: 298483712 20:39:08 INFO - PROCESS | 14773 | 20:39:11 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36664/tests/kraken/driver.html?testName=audio-oscillator) 20:39:11 INFO - PROCESS | 14773 | RSS: Main: 209289216 20:39:11 INFO - PROCESS | 14773 | 20:39:13 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36664/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:39:14 INFO - PROCESS | 14773 | RSS: Main: 213499904 20:39:14 INFO - PROCESS | 14773 | 20:39:17 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36664/tests/kraken/driver.html?testName=imaging-darkroom) 20:39:18 INFO - PROCESS | 14773 | RSS: Main: 252452864 20:39:18 INFO - PROCESS | 14773 | 20:39:21 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36664/tests/kraken/driver.html?testName=imaging-desaturate) 20:39:21 INFO - PROCESS | 14773 | RSS: Main: 252350464 20:39:21 INFO - PROCESS | 14773 | 20:39:24 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36664/tests/kraken/driver.html?testName=json-parse-financial) 20:39:25 INFO - PROCESS | 14773 | RSS: Main: 251711488 20:39:25 INFO - PROCESS | 14773 | 20:39:26 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36664/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:39:27 INFO - PROCESS | 14773 | RSS: Main: 213569536 20:39:27 INFO - PROCESS | 14773 | 20:39:29 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:39:29 INFO - PROCESS | 14773 | RSS: Main: 219709440 20:39:29 INFO - PROCESS | 14773 | 20:39:31 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:39:32 INFO - PROCESS | 14773 | RSS: Main: 229187584 20:39:32 INFO - PROCESS | 14773 | 20:39:35 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:39:35 INFO - PROCESS | 14773 | RSS: Main: 238649344 20:39:35 INFO - PROCESS | 14773 | 20:39:38 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:39:39 INFO - PROCESS | 14773 | RSS: Main: 234721280 20:39:39 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | Cycle 1(1): loaded http://localhost:36664/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36664/tests/kraken/driver.html?testName=ai-astar) 20:39:41 INFO - PROCESS | 14773 | RSS: Main: 286871552 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | __start_tp_report 20:39:41 INFO - PROCESS | 14773 | _x_x_mozilla_page_load 20:39:41 INFO - PROCESS | 14773 | _x_x_mozilla_page_load_details 20:39:41 INFO - PROCESS | 14773 | |i|pagename|runs| 20:39:41 INFO - PROCESS | 14773 | |0;ai-astar;109;111;111;109;113;109;109;109;111;117 20:39:41 INFO - PROCESS | 14773 | |1;audio-beat-detection;162;123;126;173;124;123;127;123;147;125 20:39:41 INFO - PROCESS | 14773 | |2;audio-dft;161;161;161;161;162;161;162;163;166;162 20:39:41 INFO - PROCESS | 14773 | |3;audio-fft;75;77;76;108;78;78;75;76;94;76 20:39:41 INFO - PROCESS | 14773 | |4;audio-oscillator;83;82;99;82;81;81;95;82;82;111 20:39:41 INFO - PROCESS | 14773 | |5;imaging-gaussian-blur;117;118;118;118;118;118;117;118;118;117 20:39:41 INFO - PROCESS | 14773 | |6;imaging-darkroom;146;146;146;146;145;146;146;145;147;146 20:39:41 INFO - PROCESS | 14773 | |7;imaging-desaturate;96;96;96;96;95;96;95;95;97;96 20:39:41 INFO - PROCESS | 14773 | |8;json-parse-financial;54;54;53;53;54;54;53;54;54;55 20:39:41 INFO - PROCESS | 14773 | |9;json-stringify-tinderbox;63;63;63;63;64;64;64;63;64;64 20:39:41 INFO - PROCESS | 14773 | |10;stanford-crypto-aes;81;86;81;87;81;89;84;79;80;88 20:39:41 INFO - PROCESS | 14773 | |11;stanford-crypto-ccm;132;133;132;135;133;136;150;136;172;146 20:39:41 INFO - PROCESS | 14773 | |12;stanford-crypto-pbkdf2;168;166;165;167;167;165;164;172;164;167 20:39:41 INFO - PROCESS | 14773 | |13;stanford-crypto-sha256-iterative;65;68;71;66;65;66;71;66;68;68 20:39:41 INFO - PROCESS | 14773 | __end_tp_report 20:39:41 INFO - PROCESS | 14773 | __start_cc_report 20:39:41 INFO - PROCESS | 14773 | _x_x_mozilla_cycle_collect,1678 20:39:41 INFO - PROCESS | 14773 | __end_cc_report 20:39:41 INFO - PROCESS | 14773 | __startTimestamp1480739981282__endTimestamp 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | ------- Summary: start ------- 20:39:41 INFO - PROCESS | 14773 | Number of tests: 14 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:2.57 (2.3%) stddev-sans-first:2.65 20:39:41 INFO - PROCESS | 14773 | Values: 109.0 111.0 111.0 109.0 113.0 109.0 109.0 109.0 111.0 117.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:126.50 stddev:18.60 (14.7%) stddev-sans-first:17.04 20:39:41 INFO - PROCESS | 14773 | Values: 162.0 123.0 126.0 173.0 124.0 123.0 127.0 123.0 147.0 125.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.56 (1.0%) stddev-sans-first:1.62 20:39:41 INFO - PROCESS | 14773 | Values: 161.0 161.0 161.0 161.0 162.0 161.0 162.0 163.0 166.0 162.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.50 stddev:10.94 (14.3%) stddev-sans-first:11.37 20:39:41 INFO - PROCESS | 14773 | Values: 75.0 77.0 76.0 108.0 78.0 78.0 75.0 76.0 94.0 76.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:82.00 stddev:10.36 (12.6%) stddev-sans-first:10.84 20:39:41 INFO - PROCESS | 14773 | Values: 83.0 82.0 99.0 82.0 81.0 81.0 95.0 82.0 82.0 111.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 20:39:41 INFO - PROCESS | 14773 | Values: 117.0 118.0 118.0 118.0 118.0 118.0 117.0 118.0 118.0 117.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 20:39:41 INFO - PROCESS | 14773 | Values: 146.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 147.0 146.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 20:39:41 INFO - PROCESS | 14773 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 97.0 96.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 20:39:41 INFO - PROCESS | 14773 | Values: 54.0 54.0 53.0 53.0 54.0 54.0 53.0 54.0 54.0 55.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#9] json-stringify-tinderbox Cycles:10 Average:63.50 Median:64.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 20:39:41 INFO - PROCESS | 14773 | Values: 63.0 63.0 63.0 63.0 64.0 64.0 64.0 63.0 64.0 64.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:85.00 stddev:3.66 (4.3%) stddev-sans-first:3.76 20:39:41 INFO - PROCESS | 14773 | Values: 81.0 86.0 81.0 87.0 81.0 89.0 84.0 79.0 80.0 88.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:136.00 stddev:12.65 (9.3%) stddev-sans-first:13.04 20:39:41 INFO - PROCESS | 14773 | Values: 132.0 133.0 132.0 135.0 133.0 136.0 150.0 136.0 172.0 146.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:167.00 stddev:2.37 (1.4%) stddev-sans-first:2.45 20:39:41 INFO - PROCESS | 14773 | Values: 168.0 166.0 165.0 167.0 167.0 165.0 164.0 172.0 164.0 167.0 20:39:41 INFO - PROCESS | 14773 | 20:39:41 INFO - PROCESS | 14773 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:2.22 (3.3%) stddev-sans-first:2.18 20:39:41 INFO - PROCESS | 14773 | Values: 65.0 68.0 71.0 66.0 65.0 66.0 71.0 66.0 68.0 68.0 20:39:41 INFO - PROCESS | 14773 | -------- Summary: end -------- 20:39:41 INFO - PROCESS | 14773 | 20:39:42 INFO - TEST-INFO | 14773: exit 0 20:39:42 INFO - TEST-OK | kraken | took 62779ms 20:39:42 INFO - SUITE-END | took 1181s 20:39:42 INFO - Completed test suite (00:19:41) 20:39:42 INFO - javascript benchmark 20:39:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3220.533022711707, 3234.8220847853368, 3267.1178781728936, 3291.358185153893, 3318.236805534727, 5211.140161873296, 5296.073187192245, 5331.22697208905, 5403.864903377417, 5416.51251387375, 5314.92027619586, 5331.520163185312, 5410.864728381793, 5416.864578385543, 5459.617826752129, 4795.160846851804, 4854.732989685566, 4854.732989685584, 4895.069936712013, 4946.480619534933, 4823.42118945727, 4958.429780575226, 4995.475475075101, 4998.475160108191, 5031.32077169581, 4726.858194254178, 4837.782299796518, 4840.225563909793, 4881.438634557036, 4950.975245123769, 4756.809727610891, 4804.1352556539805, 4807.062622788558, 4808.61531077513, 4813.5427134422525, 4774.307725379836, 4780.043991201774, 4783.402074740658, 4784.976075119637, 4785.688930219525, 5216.895662086737, 5280.313559237275, 5281.181416880389, 5282.181261904411, 5283.022640811424, 5651.2935883014625, 5676.631018983794, 5687.317521897357, 5731.856703582402, 5780.913286300709, 5739.426057394269, 5747.798827041033, 5749.367569567345, 5756.65460072397, 5758.078707406795, 5149.845504634867, 5238.423773384925, 5374.7043912584795, 5382.6770393776105, 5388.865278368072, 5743.8564035899435, 5745.827625171168, 5793.159991801165, 5824.650520968756, 5891.675957822361, 5621.297337832771, 5630.239917611152, 5641.605087643826, 5644.57665675076, 5646.858828529237, 5584.804531841366, 5695.345035320991, 5702.857428564319, 5703.0, 5711.314642242957, 5605.0, 5640.887182256414, 5648.8870222595315, 5692.6015178937905, 5772.0, 5701.458361455655, 5708.143778433268, 5717.113847353667, 5717.885642287131, 5754.856128596819, 4283.9504321441445, 4285.0, 4289.935650965175, 4296.76367799774, 4323.373110898902, 3798.2973149967343, 3817.522809648794, 3831.578526362098, 3869.9226015479535, 3894.1432884765304, 5403.13549832025, 5406.783728650888, 5411.972940135274, 5440.972795136078, 5452.345718513804, 5725.885482290414, 5771.797987070432, 5772.5670574707065, 5779.768809247583, 5783.681897495594, 5185.377754669388, 5201.687898726089, 5324.04167249891, 5368.489993450616, 5401.081816091274, 4929.852104436873, 4931.9753401232765, 4933.087378834927, 4957.3555437793575, 4972.453030166679, 5937.70311484424, 5952.0, 5952.672603006789, 5961.672108034099, 5971.0744834550715, 5586.357568879631, 5622.465865742748, 5708.942910570841, 5777.104548794943, 5833.329167145663, 5947.672877991752, 5970.104484327386, 5988.850278743066, 6039.698015099315, 6040.063790112539, 5647.6893770843, 5694.572907031907, 5699.287589051369, 5702.743376547983, 5704.3725190228215, 5619.0, 5697.8860422790485, 5730.369659337386, 5783.479486846204, 5786.247787787561, 5591.27313449249, 5634.85912852182, 5637.746301416364, 5670.631408958405, 5722.914156287576, 5649.745761440828, 5652.039153343859, 5656.349519805275, 5658.5473162147755, 5659.943400565941, 5440.891182176413, 5623.437656234508, 5624.915626265692, 5629.437056294501, 5630.8029218977135, 5533.557315414838, 5543.473370029841, 5597.720113994203, 5601.747921343468, 5609.074502707008, 5446.074167391632, 5478.342598888159, 5495.972520137374, 5496.862578435411, 5503.3395992481155], "value": 5275.349312594473, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.7898719499395, 604.4076804731363, 624.965626890521, 643.6427782580666, 647.6502688548185, 30663.540046899285, 33242.0, 33245.0, 33256.83371583139, 33287.0, 41096.58903410965, 42078.0, 42110.57889421105, 45556.0, 45571.544284557145, 8392.244697977165, 8512.78718032051, 8524.445911015795, 8533.74398768038, 8534.957325213365, 7773.106092799316, 7962.522248665098, 8455.450395724289, 9726.70819875405, 9959.203263738884, 9891.752706182331, 9958.551865166084, 9964.501774911261, 9991.850122248172, 10079.0, 10139.695809125702, 10199.4390308533, 10202.846957295647, 10204.540795664212, 10209.0, 9871.309008369417, 9910.653127140587, 9917.950410247902, 9920.503974801304, 9999.35004224723, 39088.413673794916, 39418.802905985285, 39637.0, 39927.80036099801, 39929.0, 3373.1229880231317, 3622.2574372253625, 3718.3864662330684, 3834.789086600236, 3959.208158368309, 30264.54603180954, 30638.693613063806, 30880.38239235225, 30952.0, 32479.188020299443, 4083.5508094109623, 4211.305134652778, 4246.214450326699, 4297.205017071852, 4346.087321662455, 325026.0, 327796.0, 327856.0, 327956.0, 328732.3563382192, 54168.18747718787, 54689.72655136699, 54701.726491368085, 54716.179257311174, 54721.1791823123, 57339.71330143406, 57441.0, 57501.71249143811, 57546.136807947914, 57550.13674794881, 15022.399104035934, 15438.9228053859, 15455.53633391, 15456.304466299043, 15468.845311546966, 10457.424841633783, 11329.830052549218, 11395.544178232944, 11404.201705880509, 11407.771844563062, 13608.251546164858, 14787.852121478647, 14978.10131392098, 15244.771328430083, 15650.530484085495, 5873.3245676746865, 6025.132209015219, 6030.577859549877, 6137.9079313810325, 6138.5396095292135, 857.7555396712032, 931.8509038553799, 954.3891909178132, 963.3834346018554, 1014.9238807089498, 29663.0, 30504.237394065327, 30619.0, 30877.228069298446, 31246.21884452907, 1043.3114144664485, 1047.1831971062584, 1069.6363236499628, 1091.9672409827717, 1093.3877028863863, 15198.544043678707, 16080.678386432206, 16116.0, 16244.431444899372, 16408.26162822676, 419331.0, 419499.0, 420956.0, 421055.0, 421267.0, 54578.72710636501, 54745.452545474036, 54774.452255476936, 54777.0, 55010.72494637501, 1184.466989854569, 1239.1883316427754, 1281.9038572107129, 1311.3508813137557, 1313.9685347002558, 27197.72802271952, 31109.68890311068, 31838.36323273475, 31956.219441080233, 32010.51984220192, 46953.0, 47351.28973065338, 47399.76300118477, 47453.52546474629, 47488.762556187, 367307.0, 367433.0, 368411.0, 368581.0, 369115.0, 26136.34659133537, 26818.06136785203, 26951.326216844736, 28423.43153136967, 37989.62010379861, 44284.778576108205, 44334.334984975896, 44900.0, 45203.54796452125, 45598.0, 37696.43455348117, 37955.49325738227, 38389.0, 38593.0, 39099.218015640094, 38111.0, 38122.0, 38580.80709596434, 39170.80414597909, 39857.60142398655, 38152.61847381491, 38682.419763703, 38745.0, 39988.40017399683, 40161.0, 32827.835860820545, 35137.824310878284, 35156.472652910736, 35974.100647484025, 36062.278754425286, 26878.32804179911, 27108.72891271141, 27136.864315679086, 27342.45315093727, 27487.312817178932], "value": 19021.90572479306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.8071802662615, 377.8677462887988, 401.45803165726267, 407.792026066706, 417.6596074199527, 13803.861961380384, 15444.0, 17210.311587536515, 18901.810981890176, 19523.609527809436, 17826.64346713068, 17887.28450861964, 18140.36508722195, 18216.089195540237, 18232.635347293046, 5493.505584497394, 5710.343310519281, 5712.800051998181, 5717.571182161334, 5719.0, 5351.678899266057, 5599.468050535193, 5620.325560932693, 5622.409646987069, 5628.605997580171, 5550.195221692852, 5570.582206334541, 5591.245181900453, 5596.27248457702, 5596.4403559643915, 5360.2763626910255, 5638.718064096779, 5670.291213598301, 5716.971415142898, 5721.599488035839, 5397.298351214317, 5556.9444305556835, 5568.7215639217875, 5574.191742197399, 5576.0, 13165.670858228525, 13296.534621288305, 13337.53318633843, 13349.532766353228, 16063.839361606468, 2129.9787002130092, 2184.6286131357706, 2236.915096178352, 2290.6449500327476, 2333.9649905251435, 10283.760615406123, 10678.305910115818, 10721.410322432263, 12793.0, 12995.480180792756, 1884.9246030158777, 1897.231621193405, 1929.9035048247672, 1931.2371613212842, 1936.06101040995, 53690.0, 71880.0, 72853.27146728517, 78736.0, 81321.0, 9836.409815411098, 10073.798524029478, 10094.242931780145, 10271.229657775548, 10425.218108641882, 14000.789988150185, 14004.159750415009, 14012.159270443806, 14026.50907218263, 14035.368408421646, 7281.745138920112, 7456.664450099758, 7582.54504729718, 7586.62066896653, 7717.112532058773, 7333.926660733431, 7353.742619008309, 7536.924630753732, 7541.849163016709, 7543.924560754323, 4217.831286748557, 4286.464191976003, 4410.735355878657, 4436.134953684001, 4671.205894997858, 1661.7341225404118, 1726.4043904852806, 1781.4744650328184, 1878.6054928464903, 1915.7317975483443, 843.4770442325674, 904.8235594059096, 931.7763736703142, 955.073578628729, 1014.8532158660813, 17796.199171037333, 18950.431487055135, 19216.327428539935, 19355.0, 19486.0, 920.636348642296, 927.2720914082413, 929.8512238041881, 933.1461712533091, 976.799756050008, 10143.594256229668, 10791.730206744736, 10820.513076911559, 10829.67510974672, 10831.0, 380704.0, 384562.0, 388226.0, 414130.0, 416889.0, 87843.56078219568, 88073.55963220143, 91054.54472727593, 91202.31086767568, 91837.0, 914.4376208631777, 935.0836180543106, 965.7199412170391, 965.8020105878278, 972.7957129002847, 12148.392580371123, 15887.761683574525, 16836.74744878803, 17087.0, 17176.0, 38043.0, 38879.0, 39468.0, 42372.55088215816, 44178.779106104266, 52802.73598631982, 53122.4687753133, 53889.73055134699, 55803.72098139646, 56125.15812262901, 7464.92535074664, 7882.369410447036, 7988.281054705104, 8089.555074470961, 8228.794280143044, 9690.95154524223, 11538.423078845855, 11555.595554155565, 11728.354940478355, 12042.879571204176, 10146.543405546916, 10259.333143345659, 10379.636712715019, 10610.363378197333, 10706.89293107059, 9687.854682179632, 10286.485675716334, 10499.737506562396, 10600.575976960836, 10694.786104278026, 10351.896481035094, 10609.098226650822, 10625.787484250117, 10701.250912436055, 10701.464926753786, 7754.7673569793, 7966.123726389977, 7983.722604383271, 8108.2297181767635, 8415.789605259917, 8245.83508329818, 10181.134603558781, 10212.285140040125, 10561.049505544537, 10777.946110269399], "value": 8549.775188383644, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.3169662713585, 1431.8568143185682, 1529.4341093795297, 1710.5210541048511, 1744.7470116833051, 6890.2420733719255, 7143.071400717899, 7389.778306650803, 7827.843443131141, 7846.646900889466, 7388.298111679382, 7608.69565217392, 7617.5810330431805, 7767.339776119023, 7770.33952114071, 2177.5536015116904, 2207.514346843692, 2211.3918672365066, 2212.8451008429415, 2212.889355532225, 1942.8639995200342, 2005.0876851032817, 2191.6822060801173, 2247.8876056197205, 2273.2952784636814, 1988.4531753767758, 2013.4664313956814, 2151.3653472225733, 2225.8330625203175, 2229.888505574723, 2152.3435352217643, 2155.461134716321, 2155.698202251686, 2158.9028493717665, 2159.222679835258, 2129.233475948657, 2170.522485053286, 2188.3544354415485, 2190.605690975624, 2190.69330293759, 7848.254415830487, 7907.367410607138, 7908.090569584514, 7912.643931023118, 7919.208079192093, 4752.215884379073, 4829.9034019319415, 4872.707637541759, 4876.463589005207, 4888.608911287124, 2971.6731159572428, 3147.6222853257755, 3205.695458931421, 3209.7753157279, 3226.080567038406, 959.8032403357365, 1034.5603118674608, 1038.190211634926, 1038.4703801061437, 1072.1101485766794, 10451.320664156881, 10452.0, 10466.476676166161, 10478.161747060292, 10504.684859454228, 4331.45856767904, 4332.9133417332105, 4353.451936671666, 4415.492218394926, 4485.910281794411, 8866.202041816268, 8882.777930551658, 8891.510966896753, 8902.554872256362, 8943.642254309754, 4950.18321976877, 4962.826301079445, 4969.428515720739, 4977.651564390458, 4977.726225057657, 4489.685721999429, 4498.032922921588, 4512.706674066241, 4546.909061818745, 4584.701994370355, 3619.0, 3674.2835147146584, 3691.243295124547, 3728.7203459740626, 3746.38184699527, 1229.489761748864, 1229.9053842080377, 1238.73986462842, 1250.0499620288465, 1252.3049707412292, 751.8458715963214, 826.7974346285199, 841.5077179849734, 866.6836604639387, 896.3232759266712, 5102.8518583318455, 5217.556507696889, 5309.65487243328, 5366.731663416814, 5395.001924643953, 885.0839381240386, 897.8787863638456, 932.934694571387, 941.712777602824, 959.6497278493439, 3704.6480584344445, 3739.4390841373465, 3796.715246356479, 3842.0586956195352, 3893.3575959966856, 9217.769555761026, 9432.811343773223, 9508.429494230368, 9518.666846660333, 9531.285153613368, 9339.20616747584, 9386.06139386056, 9489.0, 9489.193418559362, 9501.287403444769, 7089.680964356513, 7357.5217610855125, 7361.043064401701, 7363.447741419415, 7363.48455608113, 5140.794368225379, 5190.636655434235, 5222.190560463135, 5254.843934334441, 5493.23094766741, 8479.78800529967, 8547.700830470903, 8632.30941524664, 8652.913470865211, 8752.824943501222, 8686.82626347482, 8816.294696424142, 8897.91102088971, 8901.599428025625, 8906.73279801607, 6444.935550644621, 6699.062131301719, 6747.831304217434, 6753.763618273337, 6760.121184246016, 7346.265373462611, 7423.517471364344, 7434.405247580289, 7439.55362678241, 7440.330370266702, 6572.572782769104, 6690.264070952288, 6851.246362900177, 6882.69027893756, 6938.618375989369, 6522.34776522344, 6691.832704182434, 6792.388685018372, 6812.046313516211, 6812.114425124702, 6208.951046154668, 6320.367963203643, 6341.587796793194, 6382.0, 6385.29761726219, 4643.210654188863, 4650.372199753126, 4717.292406139107, 4734.24252119673, 4809.158397280392, 4398.824047038211, 4410.140022695544, 4413.426254586883, 4430.955690443183, 4498.926449293428], "value": 4194.722238587308, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.0515581612153, 328.93092450585374, 346.01055725393434, 347.4371518140612, 356.39590893435604, 5137.614678899084, 5290.487018666132, 5352.545033672132, 5390.299261096062, 5417.783288668458, 5213.244079608464, 5253.028189784893, 5267.659989257797, 5323.8287576733055, 5351.518363347298, 4172.77049762263, 4216.177845320164, 4227.534971153171, 4228.541153302113, 4251.319788833787, 4007.3588225883864, 4066.7153299268916, 4108.301588729908, 4220.57794220577, 4242.15156968606, 4118.505779306487, 4120.001799127421, 4129.504459464868, 4130.607592278729, 4138.627523522882, 4084.775337356444, 4114.177164567099, 4118.711690181688, 4131.710780245384, 4134.312696747108, 3578.3916734155255, 3647.3981793004123, 3656.9085772855624, 3665.9266814663556, 3675.5773086095173, 5109.284700141983, 5126.743947732792, 5132.53553851149, 5144.819931302424, 5152.536271735562, 4697.741624210667, 4708.529147085298, 4715.599174070208, 4741.004389078298, 4748.646635708807, 5276.947230527684, 5494.80768173112, 5513.61404701671, 5523.6409633373705, 5530.695811730353, 3961.950083227947, 3988.960110398888, 3999.960000400017, 4002.3196056670436, 4020.2763502569524, 5697.430256974311, 5704.8859022819315, 5716.857078573027, 5728.455796699307, 5739.885202295931, 4099.795010249476, 4103.630673239423, 4108.609682080198, 4112.650424713873, 4119.691023173274, 3881.7282790204413, 4062.6343629073526, 4071.900586841536, 4081.89795255121, 4087.591240875948, 4478.910421791546, 4558.791920141165, 4584.839530616479, 4730.195866702668, 4757.26262429324, 5570.025245582007, 5580.693061881554, 5592.0, 5605.607607467438, 5613.663180209201, 1404.9462902822884, 1406.381192275416, 1414.3847426369562, 1415.1933397963062, 1420.0, 905.4612505559181, 938.648006997376, 941.4727752458645, 947.876766032254, 952.6665667016489, 5155.948440515622, 5159.259536835764, 5162.819301324435, 5208.765605547759, 5230.626246071262, 5468.808591699351, 5478.780848766084, 5492.945070549244, 5503.862403439866, 5509.925564514881, 5084.44071152173, 5166.225066240019, 5377.9294971436275, 5402.432744561765, 5421.945780542223, 2925.3564215872475, 2940.0738767288235, 2946.0, 2950.668971512524, 2961.9633128404885, 5610.541259272619, 5748.19525266463, 5757.0, 5771.249737534094, 5779.815137896721, 5598.720063996784, 5635.943640563624, 5643.971780141155, 5646.350669672958, 5648.684039543849, 5748.540116790647, 5772.826815195467, 5773.6824474654295, 5777.306723193244, 5784.334801497714, 5437.293151890229, 5564.8330550083565, 5578.274824272981, 5606.411326810789, 5606.635568688022, 5533.280673512418, 5544.861378465571, 5549.889002220014, 5554.166874968625, 5561.0, 5465.426130256425, 5653.632513886584, 5784.971075144597, 5803.970980145073, 5824.679642619697, 3897.279003384383, 3949.861754838567, 3952.8616498422416, 3956.8219430125137, 3968.444417781571, 5427.945720542744, 5501.477359650827, 5515.393306736176, 5534.418886016911, 5589.944100558942, 5539.473749993744, 5548.639338442988, 5550.7779688812, 5565.777368905198, 5579.8884022320135, 5526.696031718133, 5542.8337149885565, 5555.30558680165, 5565.99624135319, 5566.832995010156, 5308.7172515818565, 5496.780128794803, 5624.74688639004, 5631.0, 5631.802886898939, 5676.914846277226, 5689.886202276014, 5698.0, 5704.260200638679, 5705.172749951317, 5448.346198456052, 5486.807961721321, 5537.861553461195, 5568.888622227451, 5578.107502799533], "value": 4211.658627053931, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.4450728431825, 718.3104219948849, 734.6106563521332, 744.4825846037003, 761.4707778094223, 13136.211827290357, 14057.578272651825, 14446.20545869978, 14622.122672639622, 14672.559823205322, 12843.0, 12871.549495767627, 13141.342932853344, 13923.0, 13939.790903136462, 780.7423550228411, 789.8657228271207, 795.8010497375656, 799.2487062161579, 800.6997375984006, 793.6626933553246, 793.817421992942, 797.2187256488645, 797.2266901105918, 798.0582912163644, 835.6615570693879, 836.7698882807216, 837.937154713399, 838.0195171649185, 838.170211490626, 834.64110432514, 835.0021724039775, 835.2649668291925, 836.3768992100863, 844.932405407566, 616.7810427298334, 616.8427051101941, 617.6109051297699, 617.6386813713938, 617.9567430279882, 11561.0, 11570.305781653127, 11604.941975290154, 11640.4761785719, 11663.259134103479, 12452.875471245263, 12516.123871329011, 12614.495420183182, 12657.177283476636, 12863.877460219053, 11353.20527563071, 11422.0, 11441.313521188673, 11449.656510304705, 11892.762144757144, 4254.191703576311, 4269.338252570826, 4296.548862369437, 4319.092990472004, 4342.457192850894, 80224.0, 80310.79533807056, 80856.59571702163, 80992.0, 82192.0, 10762.300450470695, 10771.246012779031, 10926.125909927187, 11006.0, 11086.833697494543, 20037.899810501145, 20136.59726805485, 20158.798412015985, 20246.291379801638, 20300.79699202989, 7555.584442855586, 7804.882926756103, 8039.316658084128, 8097.35221182304, 8169.183081691902, 4430.955690443118, 4453.621442177451, 4487.439070116236, 4626.329182268552, 4757.191277482836, 3465.7054150396993, 3595.676389124991, 3598.604153543108, 3600.6039335673054, 3603.6036036036016, 592.8962431574457, 593.4391999560461, 597.6533610505983, 609.8140067279434, 617.4967401567708, 639.9121493461134, 664.007309072939, 667.9833004174934, 713.2688993781312, 739.1204466684628, 9242.86135707965, 9484.288678349152, 9485.573149208301, 9570.234381249484, 9760.804783904425, 606.4148097086332, 665.7836203233871, 666.7133132752963, 682.5631595778706, 684.750066225824, 4382.255016647177, 4633.559811817872, 4721.422117501429, 4736.8578942631775, 4740.3837501124635, 80321.59839200767, 82517.17482825214, 84232.15767842365, 85568.57215713881, 87758.0, 75285.0, 76754.0, 77132.0, 77396.2260377389, 77403.61298193473, 64648.0, 65545.67227163834, 65729.34270657328, 67549.66225168842, 68188.0, 9056.909430905607, 9126.589303481493, 9238.260939124719, 9269.907300926905, 9336.673216437392, 46300.7684961573, 47153.0, 47712.52287477081, 47913.76043119762, 49429.0, 62155.0, 64365.0, 64563.354366457614, 65278.673606631666, 68264.0, 11468.770624587294, 11661.416929153338, 11854.209139277149, 11947.641570752892, 12641.051921105769, 13274.469021239429, 13337.466501339837, 13595.252261125734, 13825.446982120602, 13900.304984750519, 12377.876221237673, 12573.685657858627, 12732.427040783372, 13286.0, 13294.0, 11837.763244734884, 11970.58102966392, 12397.194182378116, 12768.170068945488, 12773.61679149627, 12551.0, 12726.809097863725, 12760.4257808397, 12912.289824059391, 13311.26788026629, 9592.328537002342, 9642.246188174042, 10062.39625622465, 10102.292839501164, 10343.82068071264, 9941.380547912557, 10956.0, 11000.119990400908, 11085.22403431752, 11092.223544351818], "value": 7044.7723291666225, "unit": "score"}], "name": "dromaeo_css", "value": 6887.921687108927, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 111.0, 109.0, 113.0, 109.0, 109.0, 109.0, 111.0, 117.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 123.0, 126.0, 173.0, 124.0, 123.0, 127.0, 123.0, 147.0, 125.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0, 163.0, 166.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 108.0, 78.0, 78.0, 75.0, 76.0, 94.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 99.0, 82.0, 81.0, 81.0, 95.0, 82.0, 82.0, 111.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 147.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 97.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 53.0, 54.0, 54.0, 53.0, 54.0, 54.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 64.0, 64.0, 63.0, 64.0, 64.0], "value": 63.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 81.0, 87.0, 81.0, 89.0, 84.0, 79.0, 80.0, 88.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 133.0, 132.0, 135.0, 133.0, 136.0, 150.0, 136.0, 172.0, 146.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 165.0, 167.0, 167.0, 165.0, 164.0, 172.0, 164.0, 167.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 71.0, 66.0, 65.0, 66.0, 71.0, 66.0, 68.0, 68.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1511.9, "alertThreshold": 2.0}]} 20:39:43 INFO - Return code: 0 20:39:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:39:43 INFO - # TBPL SUCCESS # 20:39:43 INFO - Running post-action listener: _resource_record_post_action 20:39:43 INFO - [mozharness: 2016-12-03 04:39:43.081044Z] Finished run-tests step (success) 20:39:43 INFO - Running post-run listener: _resource_record_post_run 20:39:43 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 410873856; Read time: 0; Write time: 483336 20:39:43 INFO - TinderboxPrint: CPU usage
13.1% 20:39:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:39:43 INFO - TinderboxPrint: I/O write bytes / time
410,873,856 / 483,336 20:39:43 INFO - TinderboxPrint: CPU idle
8,307.6 (86.8%) 20:39:43 INFO - TinderboxPrint: CPU user
1,204.5 (12.6%) 20:39:43 INFO - TinderboxPrint: Swap in / out
0 / 0 20:39:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 104116224; Read time: 0; Write time: 154652 20:39:43 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 289636352; Read time: 0; Write time: 321648 20:39:43 INFO - Running post-run listener: _upload_blobber_files 20:39:43 INFO - Blob upload gear active. 20:39:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:39: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 20:39: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'] 20:39: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 20:39:44 INFO - (blobuploader) - INFO - Open directory for files ... 20:39:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:39:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:39:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:47 INFO - (blobuploader) - INFO - Done attempting. 20:39:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:39:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:39:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:48 INFO - (blobuploader) - INFO - Done attempting. 20:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:49 INFO - (blobuploader) - INFO - Done attempting. 20:39:49 INFO - (blobuploader) - INFO - Iteration through files over. 20:39:49 INFO - Return code: 0 20:39:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:39:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:39:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5acb5c107dc6acd472ba56d8e17eaaa4dc3809f95d53617cf7fd32f08b384aef57a484313b64437a57084edbfca4082a32485e15f02e63bfa04725e90deacf7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f0516da34009d00be23061a87f8a4a8508d22d0dea4d87f0e2e94f92aae534273396579b0a4a91d20ed880ad02725fb18f7226d4d58c34859af359dcca3a5c78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25d82e5e0a75c1f472dae9305d43491050af67595c077e495100d711c73228ef98134edc5059a6a2b3b33a3b8ee54196cdc5fba67785742e77cd18cbfd6652dc"} 20:39:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:39:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:39:49 INFO - Contents: 20:39:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5acb5c107dc6acd472ba56d8e17eaaa4dc3809f95d53617cf7fd32f08b384aef57a484313b64437a57084edbfca4082a32485e15f02e63bfa04725e90deacf7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f0516da34009d00be23061a87f8a4a8508d22d0dea4d87f0e2e94f92aae534273396579b0a4a91d20ed880ad02725fb18f7226d4d58c34859af359dcca3a5c78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25d82e5e0a75c1f472dae9305d43491050af67595c077e495100d711c73228ef98134edc5059a6a2b3b33a3b8ee54196cdc5fba67785742e77cd18cbfd6652dc"} 20:39:49 INFO - Running post-run listener: copy_logs_to_upload_dir 20:39:49 INFO - Copying logs to upload dir... 20:39:49 INFO - mkdir: /builds/slave/test/build/upload/logs 20:39:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.503146 ========= master_lag: 0.84 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-02 20:39:49.688702) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-02 20:39:49.696508) ========= 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=745a4373a2a16df560f216e000000264-1480729352.164523-1073842972 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/5acb5c107dc6acd472ba56d8e17eaaa4dc3809f95d53617cf7fd32f08b384aef57a484313b64437a57084edbfca4082a32485e15f02e63bfa04725e90deacf7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f0516da34009d00be23061a87f8a4a8508d22d0dea4d87f0e2e94f92aae534273396579b0a4a91d20ed880ad02725fb18f7226d4d58c34859af359dcca3a5c78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25d82e5e0a75c1f472dae9305d43491050af67595c077e495100d711c73228ef98134edc5059a6a2b3b33a3b8ee54196cdc5fba67785742e77cd18cbfd6652dc"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1480732623/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5acb5c107dc6acd472ba56d8e17eaaa4dc3809f95d53617cf7fd32f08b384aef57a484313b64437a57084edbfca4082a32485e15f02e63bfa04725e90deacf7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f0516da34009d00be23061a87f8a4a8508d22d0dea4d87f0e2e94f92aae534273396579b0a4a91d20ed880ad02725fb18f7226d4d58c34859af359dcca3a5c78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25d82e5e0a75c1f472dae9305d43491050af67595c077e495100d711c73228ef98134edc5059a6a2b3b33a3b8ee54196cdc5fba67785742e77cd18cbfd6652dc"}' ========= master_lag: 1.88 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-12-02 20:39:51.585249) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 20:39:51.585647) ========= 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=745a4373a2a16df560f216e000000264-1480729352.164523-1073842972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 20:39:51.605940) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-02 20:39:51.609382) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-02 20:39:51.609711) ========= ========= Total master_lag: 2.85 =========