builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1483118143.67 results: success (0) buildid: 20161230073208 builduid: 60b8413d39e94d92b0c04d4ebc136282 revision: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.670380) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.670895) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.671225) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.685855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.686160) ========= 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=7de08804244eb36560b44971000001c3-1483118143.403690-120424550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-30 09:15:44-- 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% 32.2M=0s 2016-12-30 09:15:44 (32.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113980 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.814158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.814543) ========= 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=7de08804244eb36560b44971000001c3-1483118143.403690-120424550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014466 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.864486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:43.864882) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9104708cc3ac0ccfe4cf5d518e13736773c565d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 9104708cc3ac0ccfe4cf5d518e13736773c565d7 --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=7de08804244eb36560b44971000001c3-1483118143.403690-120424550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-30 09:15:44,358 truncating revision to first 12 chars 2016-12-30 09:15:44,358 Setting DEBUG logging. 2016-12-30 09:15:44,359 attempt 1/10 2016-12-30 09:15:44,359 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9104708cc3ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 09:15:44,671 unpacking tar archive at: mozilla-central-9104708cc3ac/testing/mozharness/ program finished with exit code 0 elapsedTime=0.624217 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:44.503808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:44.504212) ========= script_repo_revision: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:44.504804) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:44.505129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 09:15:44.515352) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-30 09:15:44.515863) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=7de08804244eb36560b44971000001c3-1483118143.403690-120424550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:45 INFO - MultiFileLogger online at 20161230 09:15:45 in /builds/slave/test 09:15:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:15:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:45 INFO - {'append_to_log': False, 09:15:45 INFO - 'base_work_dir': '/builds/slave/test', 09:15:45 INFO - 'blob_upload_branch': 'Firefox', 09:15:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:45 INFO - 'branch': 'Firefox', 09:15:45 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:45 INFO - 'config_files': ('talos/linux_config.py',), 09:15:45 INFO - 'default_actions': ('clobber', 09:15:45 INFO - 'read-buildbot-config', 09:15:45 INFO - 'download-and-extract', 09:15:45 INFO - 'populate-webroot', 09:15:45 INFO - 'create-virtualenv', 09:15:45 INFO - 'install', 09:15:45 INFO - 'run-tests'), 09:15:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:45 INFO - 'download_minidump_stackwalk': True, 09:15:45 INFO - 'download_symbols': 'ondemand', 09:15:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:45 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:45 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:45 INFO - 'installer_path': 'installer.exe', 09:15:45 INFO - 'log_level': 'info', 09:15:45 INFO - 'log_name': 'talos', 09:15:45 INFO - 'log_to_console': True, 09:15:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:45 INFO - 'opt_config_files': (), 09:15:45 INFO - 'pip_index': False, 09:15:45 INFO - 'sps_profile': False, 09:15:45 INFO - 'sps_profile_interval': 0, 09:15:45 INFO - 'suite': 'dromaeojs', 09:15:45 INFO - 'system_bits': '32', 09:15:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:45 INFO - 'title': 'talos-linux64-ix-003', 09:15:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:45 INFO - 'use_talos_json': True, 09:15:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:45 INFO - 'work_dir': 'build'} 09:15:45 INFO - [mozharness: 2016-12-30 17:15:45.111573Z] Running clobber step. 09:15:45 INFO - Running pre-action listener: _resource_record_pre_action 09:15:45 INFO - Running main action method: clobber 09:15:45 INFO - rmtree: /builds/slave/test/build 09:15:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:46 INFO - Running post-action listener: _resource_record_post_action 09:15:46 INFO - [mozharness: 2016-12-30 17:15:46.093383Z] Finished clobber step (success) 09:15:46 INFO - [mozharness: 2016-12-30 17:15:46.093484Z] Running read-buildbot-config step. 09:15:46 INFO - Running pre-action listener: _resource_record_pre_action 09:15:46 INFO - Running main action method: read_buildbot_config 09:15:46 INFO - Using buildbot properties: 09:15:46 INFO - { 09:15:46 INFO - "project": "", 09:15:46 INFO - "product": "firefox", 09:15:46 INFO - "script_repo_revision": "production", 09:15:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:15:46 INFO - "repository": "", 09:15:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:15:46 INFO - "buildid": "20161230073208", 09:15:46 INFO - "pgo_build": "True", 09:15:46 INFO - "basedir": "/builds/slave/test", 09:15:46 INFO - "buildnumber": 147, 09:15:46 INFO - "slavename": "talos-linux64-ix-003", 09:15:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:15:46 INFO - "platform": "ubuntu64_hw", 09:15:46 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:15:46 INFO - "branch": "mozilla-central", 09:15:46 INFO - "repo_path": "mozilla-central", 09:15:46 INFO - "revision": "9104708cc3ac0ccfe4cf5d518e13736773c565d7", 09:15:46 INFO - "stage_platform": "linux64-pgo", 09:15:46 INFO - "builduid": "60b8413d39e94d92b0c04d4ebc136282", 09:15:46 INFO - "slavebuilddir": "test" 09:15:46 INFO - } 09:15:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:15:46 INFO - Running post-action listener: _resource_record_post_action 09:15:46 INFO - [mozharness: 2016-12-30 17:15:46.095946Z] Finished read-buildbot-config step (success) 09:15:46 INFO - [mozharness: 2016-12-30 17:15:46.096038Z] Running download-and-extract step. 09:15:46 INFO - Running pre-action listener: _resource_record_pre_action 09:15:46 INFO - Running main action method: download_and_extract 09:15:46 INFO - mkdir: /builds/slave/test/build/tests 09:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:46 INFO - trying https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:15:46 INFO - Downloading https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/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 09:15:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/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 09:15:46 INFO - Downloaded 1361 bytes. 09:15:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:15:46 INFO - Using the following test package requirements: 09:15:46 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:46 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:15:46 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:15:46 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'jsshell-linux-x86_64.zip'], 09:15:46 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:15:46 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:46 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:15:46 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:15:46 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:15:46 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:46 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:15:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:15:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:15:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:46 INFO - Fetch https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:15:47 INFO - Expected file size: 21834924 09:15:47 INFO - Obtained file size: 21834924 09:15:51 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 09:15:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:15:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:51 INFO - Fetch https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:15:52 INFO - Expected file size: 21834924 09:15:52 INFO - Obtained file size: 21834924 09:15:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:15:57 INFO - Fetch https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:15:58 INFO - Expected file size: 14409776 09:15:58 INFO - Obtained file size: 14409776 09:15:58 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')]} 09:15:58 INFO - trying https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:15:58 INFO - Downloading https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/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 09:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/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 09:15:59 INFO - Downloaded 61360321 bytes. 09:15:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:15:59 INFO - mkdir: /builds/slave/test/properties 09:15:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:59 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:59 INFO - Contents: 09:15:59 INFO - build_url:https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:16:00 INFO - Running post-action listener: _resource_record_post_action 09:16:00 INFO - Running post-action listener: set_extra_try_arguments 09:16:00 INFO - [mozharness: 2016-12-30 17:16:00.205477Z] Finished download-and-extract step (success) 09:16:00 INFO - [mozharness: 2016-12-30 17:16:00.205708Z] Running populate-webroot step. 09:16:00 INFO - Running pre-action listener: _resource_record_pre_action 09:16:00 INFO - Running main action method: populate_webroot 09:16:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:00 INFO - '--tppagecycles', 09:16:00 INFO - '7'], 09:16:00 INFO - 'tests': ['tsvgm']}}, 09:16:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:00 INFO - 'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:00 INFO - 'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['damp', 'tps']}, 09:16:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:00 INFO - 'tests': ['damp', 'tps']}, 09:16:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['dromaeo_dom']}, 09:16:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['basic_compositor_video']}, 09:16:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['a11yr', 09:16:00 INFO - 'ts_paint', 09:16:00 INFO - 'tpaint', 09:16:00 INFO - 'sessionrestore', 09:16:00 INFO - 'sessionrestore_no_auto_restore', 09:16:00 INFO - 'tabpaint']}, 09:16:00 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:00 INFO - 'ts_paint', 09:16:00 INFO - 'tpaint', 09:16:00 INFO - 'sessionrestore', 09:16:00 INFO - 'sessionrestore_no_auto_restore', 09:16:00 INFO - 'tabpaint']}, 09:16:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:00 INFO - 'ts_paint', 09:16:00 INFO - 'tpaint', 09:16:00 INFO - 'sessionrestore', 09:16:00 INFO - 'sessionrestore_no_auto_restore', 09:16:00 INFO - 'tabpaint']}, 09:16:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:00 INFO - 'ts_paint', 09:16:00 INFO - 'tpaint', 09:16:00 INFO - 'sessionrestore', 09:16:00 INFO - 'sessionrestore_no_auto_restore', 09:16:00 INFO - 'tabpaint']}, 09:16:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['a11yr', 09:16:00 INFO - 'ts_paint', 09:16:00 INFO - 'tpaint', 09:16:00 INFO - 'sessionrestore', 09:16:00 INFO - 'sessionrestore_no_auto_restore', 09:16:00 INFO - 'tabpaint']}, 09:16:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['a11yr', 09:16:00 INFO - 'ts_paint', 09:16:00 INFO - 'tpaint', 09:16:00 INFO - 'sessionrestore', 09:16:00 INFO - 'sessionrestore_no_auto_restore', 09:16:00 INFO - 'tabpaint']}, 09:16:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['tsvgx', 09:16:00 INFO - 'tsvgr_opacity', 09:16:00 INFO - 'tart', 09:16:00 INFO - 'tscrollx', 09:16:00 INFO - 'cart', 09:16:00 INFO - 'tsvg_static']}, 09:16:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:00 INFO - 'tsvgr_opacity', 09:16:00 INFO - 'tart', 09:16:00 INFO - 'tscrollx', 09:16:00 INFO - 'cart', 09:16:00 INFO - 'tsvg_static']}, 09:16:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:00 INFO - 'talos_options': ['--disable-e10s'], 09:16:00 INFO - 'tests': ['tp5o']}, 09:16:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:00 INFO - 'talos_options': ['--disable-e10s', 09:16:00 INFO - '--xperf_path', 09:16:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:00 INFO - 'tests': ['tp5n']}, 09:16:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:00 INFO - 'talos_options': ['--xperf_path', 09:16:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:00 INFO - 'tests': ['tp5n']}}, 09:16:00 INFO - 'talos.zip': {'path': '', 09:16:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:00 INFO - Running post-action listener: _resource_record_post_action 09:16:00 INFO - [mozharness: 2016-12-30 17:16:00.225066Z] Finished populate-webroot step (success) 09:16:00 INFO - [mozharness: 2016-12-30 17:16:00.225156Z] Running create-virtualenv step. 09:16:00 INFO - Running pre-action listener: _resource_record_pre_action 09:16:00 INFO - Running main action method: create_virtualenv 09:16:00 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:00 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 09:16:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:00 INFO - Using real prefix '/usr' 09:16:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:00 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:02 INFO - Installing pip.................done. 09:16:02 INFO - Return code: 0 09:16:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:03 INFO - Reading from file tmpfile_stdout 09:16:03 INFO - Output received: 09:16:03 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) 09:16:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:03 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 09:16:03 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 09:16:03 INFO - Using env: {'DISPLAY': ':0', 09:16:03 INFO - 'HOME': '/home/cltbld', 09:16:03 INFO - 'LANG': 'en_US.UTF-8', 09:16:03 INFO - 'LANGUAGE': 'en_US:en', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MAIL': '/var/mail/cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SHLVL': '1', 09:16:03 INFO - 'TERM': 'linux', 09:16:03 INFO - 'TMOUT': '86400', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - '_': '/tools/buildbot/bin/python'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 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 09:16:03 INFO - Cleaning up... 09:16:03 INFO - Return code: 0 09:16:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:03 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 09:16:03 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 09:16:03 INFO - Using env: {'DISPLAY': ':0', 09:16:03 INFO - 'HOME': '/home/cltbld', 09:16:03 INFO - 'LANG': 'en_US.UTF-8', 09:16:03 INFO - 'LANGUAGE': 'en_US:en', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MAIL': '/var/mail/cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SHLVL': '1', 09:16:03 INFO - 'TERM': 'linux', 09:16:03 INFO - 'TMOUT': '86400', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - '_': '/tools/buildbot/bin/python'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 INFO - Downloading/unpacking psutil>=3.1.1 09:16:03 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 09:16:03 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 09:16:03 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 09:16:03 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 09:16:03 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 09:16:03 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 09:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:05 INFO - Installing collected packages: psutil 09:16:05 INFO - Running setup.py install for psutil 09:16:05 INFO - building 'psutil._psutil_linux' extension 09:16:05 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 09:16:05 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 09:16:05 INFO - building 'psutil._psutil_posix' extension 09:16:05 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 09:16:05 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 09:16:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:06 INFO - Successfully installed psutil 09:16:06 INFO - Cleaning up... 09:16:06 INFO - Return code: 0 09:16:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:16:06 INFO - Using env: {'DISPLAY': ':0', 09:16:06 INFO - 'HOME': '/home/cltbld', 09:16:06 INFO - 'LANG': 'en_US.UTF-8', 09:16:06 INFO - 'LANGUAGE': 'en_US:en', 09:16:06 INFO - 'LOGNAME': 'cltbld', 09:16:06 INFO - 'MAIL': '/var/mail/cltbld', 09:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:06 INFO - 'MOZ_NO_REMOTE': '1', 09:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:06 INFO - 'NO_EM_RESTART': '1', 09:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:06 INFO - 'PWD': '/builds/slave/test', 09:16:06 INFO - 'SHELL': '/bin/bash', 09:16:06 INFO - 'SHLVL': '1', 09:16:06 INFO - 'TERM': 'linux', 09:16:06 INFO - 'TMOUT': '86400', 09:16:06 INFO - 'USER': 'cltbld', 09:16:06 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:06 INFO - '_': '/tools/buildbot/bin/python'} 09:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:08 INFO - Installing collected packages: mozsystemmonitor 09:16:08 INFO - Running setup.py install for mozsystemmonitor 09:16:08 INFO - Successfully installed mozsystemmonitor 09:16:08 INFO - Cleaning up... 09:16:08 INFO - Return code: 0 09:16:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16:08 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')]} 09:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 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')]} 09:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:08 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 09:16:08 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 09:16:08 INFO - Using env: {'DISPLAY': ':0', 09:16:08 INFO - 'HOME': '/home/cltbld', 09:16:08 INFO - 'LANG': 'en_US.UTF-8', 09:16:08 INFO - 'LANGUAGE': 'en_US:en', 09:16:08 INFO - 'LOGNAME': 'cltbld', 09:16:08 INFO - 'MAIL': '/var/mail/cltbld', 09:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:08 INFO - 'MOZ_NO_REMOTE': '1', 09:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:08 INFO - 'NO_EM_RESTART': '1', 09:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:08 INFO - 'PWD': '/builds/slave/test', 09:16:08 INFO - 'SHELL': '/bin/bash', 09:16:08 INFO - 'SHLVL': '1', 09:16:08 INFO - 'TERM': 'linux', 09:16:08 INFO - 'TMOUT': '86400', 09:16:08 INFO - 'USER': 'cltbld', 09:16:08 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:08 INFO - '_': '/tools/buildbot/bin/python'} 09:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:08 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:08 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 09:16:08 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 09:16:08 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 09:16:08 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 09:16:08 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 09:16:08 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 09:16:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:10 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 09:16:10 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 09:16:10 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 09:16:10 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 09:16:10 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 09:16:10 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 09:16:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:11 INFO - warning: no files found matching '*.txt' 09:16:11 INFO - no previously-included directories found matching 'build' 09:16:11 INFO - no previously-included directories found matching 'dist' 09:16:11 INFO - no previously-included directories found matching '.git*' 09:16:11 INFO - Installing collected packages: jsonschema, functools32 09:16:11 INFO - Running setup.py install for functools32 09:16:11 INFO - warning: no files found matching '*.txt' 09:16:11 INFO - no previously-included directories found matching 'build' 09:16:11 INFO - no previously-included directories found matching 'dist' 09:16:11 INFO - no previously-included directories found matching '.git*' 09:16:11 INFO - Successfully installed jsonschema functools32 09:16:11 INFO - Cleaning up... 09:16:11 INFO - Return code: 0 09:16:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:16:11 INFO - Using env: {'DISPLAY': ':0', 09:16:11 INFO - 'HOME': '/home/cltbld', 09:16:11 INFO - 'LANG': 'en_US.UTF-8', 09:16:11 INFO - 'LANGUAGE': 'en_US:en', 09:16:11 INFO - 'LOGNAME': 'cltbld', 09:16:11 INFO - 'MAIL': '/var/mail/cltbld', 09:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:11 INFO - 'MOZ_NO_REMOTE': '1', 09:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:11 INFO - 'NO_EM_RESTART': '1', 09:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:11 INFO - 'PWD': '/builds/slave/test', 09:16:11 INFO - 'SHELL': '/bin/bash', 09:16:11 INFO - 'SHLVL': '1', 09:16:11 INFO - 'TERM': 'linux', 09:16:11 INFO - 'TMOUT': '86400', 09:16:11 INFO - 'USER': 'cltbld', 09:16:11 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:11 INFO - '_': '/tools/buildbot/bin/python'} 09:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:11 INFO - Cleaning up... 09:16:11 INFO - Return code: 0 09:16:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:11 INFO - Using env: {'DISPLAY': ':0', 09:16:11 INFO - 'HOME': '/home/cltbld', 09:16:11 INFO - 'LANG': 'en_US.UTF-8', 09:16:11 INFO - 'LANGUAGE': 'en_US:en', 09:16:11 INFO - 'LOGNAME': 'cltbld', 09:16:11 INFO - 'MAIL': '/var/mail/cltbld', 09:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:11 INFO - 'MOZ_NO_REMOTE': '1', 09:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:11 INFO - 'NO_EM_RESTART': '1', 09:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:11 INFO - 'PWD': '/builds/slave/test', 09:16:11 INFO - 'SHELL': '/bin/bash', 09:16:11 INFO - 'SHLVL': '1', 09:16:11 INFO - 'TERM': 'linux', 09:16:11 INFO - 'TMOUT': '86400', 09:16:11 INFO - 'USER': 'cltbld', 09:16:11 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:11 INFO - '_': '/tools/buildbot/bin/python'} 09:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:11 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:13 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:13 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 09:16:13 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 09:16:13 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 09:16:13 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 09:16:13 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 09:16:13 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 09:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:14 INFO - Downloading docopt-0.6.1.tar.gz 09:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:14 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:14 INFO - Running setup.py install for blobuploader 09:16:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:14 INFO - Running setup.py install for requests 09:16:14 INFO - Running setup.py install for docopt 09:16:15 INFO - Successfully installed blobuploader requests docopt 09:16:15 INFO - Cleaning up... 09:16:15 INFO - Return code: 0 09:16:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:15 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')]} 09:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:15 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')]} 09:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:15 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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:15 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 09:16:15 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 09:16:15 INFO - Using env: {'DISPLAY': ':0', 09:16:15 INFO - 'HOME': '/home/cltbld', 09:16:15 INFO - 'LANG': 'en_US.UTF-8', 09:16:15 INFO - 'LANGUAGE': 'en_US:en', 09:16:15 INFO - 'LOGNAME': 'cltbld', 09:16:15 INFO - 'MAIL': '/var/mail/cltbld', 09:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:15 INFO - 'MOZ_NO_REMOTE': '1', 09:16:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:15 INFO - 'NO_EM_RESTART': '1', 09:16:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:15 INFO - 'PWD': '/builds/slave/test', 09:16:15 INFO - 'SHELL': '/bin/bash', 09:16:15 INFO - 'SHLVL': '1', 09:16:15 INFO - 'TERM': 'linux', 09:16:15 INFO - 'TMOUT': '86400', 09:16:15 INFO - 'USER': 'cltbld', 09:16:15 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:15 INFO - '_': '/tools/buildbot/bin/python'} 09:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:15 INFO - Running setup.py (path:/tmp/pip-v4gKmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:15 INFO - Running setup.py (path:/tmp/pip-sH8kb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:15 INFO - Running setup.py (path:/tmp/pip-hmfhcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:15 INFO - Running setup.py (path:/tmp/pip-qljZc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:15 INFO - Running setup.py (path:/tmp/pip-gkb04W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:15 INFO - Running setup.py (path:/tmp/pip-HxAM5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:15 INFO - Running setup.py (path:/tmp/pip-PuH1Y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:15 INFO - Running setup.py (path:/tmp/pip-Klpra7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:15 INFO - Running setup.py (path:/tmp/pip-z8bwAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:15 INFO - Running setup.py (path:/tmp/pip-F14SKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:16 INFO - Running setup.py (path:/tmp/pip-t4pv0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:16 INFO - Running setup.py (path:/tmp/pip-D3CdvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:16 INFO - Running setup.py (path:/tmp/pip-MHGEO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:16 INFO - Running setup.py (path:/tmp/pip-pyHgAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:16 INFO - Running setup.py (path:/tmp/pip-0P5uNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:16 INFO - Running setup.py (path:/tmp/pip-g1JsuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:16 INFO - Running setup.py (path:/tmp/pip-RSXHUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:16 INFO - Running setup.py install for manifestparser 09:16:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Running setup.py install for mozcrash 09:16:16 INFO - Running setup.py install for mozdebug 09:16:16 INFO - Running setup.py install for mozdevice 09:16:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Running setup.py install for mozfile 09:16:17 INFO - Running setup.py install for mozhttpd 09:16:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozinfo 09:16:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozInstall 09:16:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozleak 09:16:17 INFO - Running setup.py install for mozlog 09:16:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for moznetwork 09:16:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozprocess 09:16:17 INFO - Running setup.py install for mozprofile 09:16:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozrunner 09:16:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:18 INFO - Running setup.py install for mozscreenshot 09:16:18 INFO - Running setup.py install for moztest 09:16:18 INFO - Running setup.py install for mozversion 09:16:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:18 INFO - Cleaning up... 09:16:18 INFO - Return code: 0 09:16:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:18 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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:18 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 09:16:18 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 09:16:18 INFO - Using env: {'DISPLAY': ':0', 09:16:18 INFO - 'HOME': '/home/cltbld', 09:16:18 INFO - 'LANG': 'en_US.UTF-8', 09:16:18 INFO - 'LANGUAGE': 'en_US:en', 09:16:18 INFO - 'LOGNAME': 'cltbld', 09:16:18 INFO - 'MAIL': '/var/mail/cltbld', 09:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:18 INFO - 'MOZ_NO_REMOTE': '1', 09:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:18 INFO - 'NO_EM_RESTART': '1', 09:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:18 INFO - 'PWD': '/builds/slave/test', 09:16:18 INFO - 'SHELL': '/bin/bash', 09:16:18 INFO - 'SHLVL': '1', 09:16:18 INFO - 'TERM': 'linux', 09:16:18 INFO - 'TMOUT': '86400', 09:16:18 INFO - 'USER': 'cltbld', 09:16:18 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:18 INFO - '_': '/tools/buildbot/bin/python'} 09:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:18 INFO - Running setup.py (path:/tmp/pip-aGHMH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:18 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)) 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:18 INFO - Running setup.py (path:/tmp/pip-_QTSTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:18 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)) 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:18 INFO - Running setup.py (path:/tmp/pip-dFrX5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:18 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)) 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:18 INFO - Running setup.py (path:/tmp/pip-LJiK9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:18 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)) 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:18 INFO - Running setup.py (path:/tmp/pip-lp2_Au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:18 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)) 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:18 INFO - Running setup.py (path:/tmp/pip-hhyR5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:19 INFO - Running setup.py (path:/tmp/pip-i_Qbbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:19 INFO - Running setup.py (path:/tmp/pip-gWVLpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:19 INFO - Running setup.py (path:/tmp/pip-Qrld63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:19 INFO - Running setup.py (path:/tmp/pip-IC602E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:19 INFO - Running setup.py (path:/tmp/pip-SHURT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:19 INFO - Running setup.py (path:/tmp/pip-FhHXGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:19 INFO - Running setup.py (path:/tmp/pip-WoAEkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:19 INFO - Running setup.py (path:/tmp/pip-3wCcFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:19 INFO - Running setup.py (path:/tmp/pip-BP5s9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:19 INFO - Running setup.py (path:/tmp/pip-vNdOsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:19 INFO - Running setup.py (path:/tmp/pip-s3Klhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:19 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)) 09:16:19 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)) 09:16:19 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)) 09:16:19 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)) 09:16:19 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)) 09:16:19 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)) 09:16:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:21 INFO - Downloading blessings-1.6.tar.gz 09:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:21 INFO - Installing collected packages: blessings 09:16:21 INFO - Running setup.py install for blessings 09:16:22 INFO - Successfully installed blessings 09:16:22 INFO - Cleaning up... 09:16:22 INFO - Return code: 0 09:16:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:22 INFO - Reading from file tmpfile_stdout 09:16:22 INFO - Current package versions: 09:16:22 INFO - argparse == 1.2.1 09:16:22 INFO - blessings == 1.6 09:16:22 INFO - blobuploader == 1.2.4 09:16:22 INFO - docopt == 0.6.1 09:16:22 INFO - functools32 == 3.2.3-2 09:16:22 INFO - jsonschema == 2.5.1 09:16:22 INFO - manifestparser == 1.1 09:16:22 INFO - mozInstall == 1.12 09:16:22 INFO - mozcrash == 1.0 09:16:22 INFO - mozdebug == 0.1 09:16:22 INFO - mozdevice == 0.49 09:16:22 INFO - mozfile == 1.2 09:16:22 INFO - mozhttpd == 0.7 09:16:22 INFO - mozinfo == 0.9 09:16:22 INFO - mozleak == 0.1 09:16:22 INFO - mozlog == 3.4 09:16:22 INFO - moznetwork == 0.27 09:16:22 INFO - mozprocess == 0.23 09:16:22 INFO - mozprofile == 0.28 09:16:22 INFO - mozrunner == 6.13 09:16:22 INFO - mozscreenshot == 0.1 09:16:22 INFO - mozsystemmonitor == 0.3 09:16:22 INFO - moztest == 0.8 09:16:22 INFO - mozversion == 1.4 09:16:22 INFO - psutil == 3.1.1 09:16:22 INFO - requests == 1.2.3 09:16:22 INFO - wsgiref == 0.1.2 09:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:22 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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:22 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 09:16:22 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 09:16:22 INFO - Using env: {'DISPLAY': ':0', 09:16:22 INFO - 'HOME': '/home/cltbld', 09:16:22 INFO - 'LANG': 'en_US.UTF-8', 09:16:22 INFO - 'LANGUAGE': 'en_US:en', 09:16:22 INFO - 'LOGNAME': 'cltbld', 09:16:22 INFO - 'MAIL': '/var/mail/cltbld', 09:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:22 INFO - 'MOZ_NO_REMOTE': '1', 09:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:22 INFO - 'NO_EM_RESTART': '1', 09:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:22 INFO - 'PWD': '/builds/slave/test', 09:16:22 INFO - 'SHELL': '/bin/bash', 09:16:22 INFO - 'SHLVL': '1', 09:16:22 INFO - 'TERM': 'linux', 09:16:22 INFO - 'TMOUT': '86400', 09:16:22 INFO - 'USER': 'cltbld', 09:16:22 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:22 INFO - '_': '/tools/buildbot/bin/python'} 09:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 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)) 09:16:22 INFO - Cleaning up... 09:16:22 INFO - Return code: 0 09:16:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:22 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 0x157a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1746a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ebc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, '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': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', '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 09:16:22 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 09:16:22 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 09:16:22 INFO - Using env: {'DISPLAY': ':0', 09:16:22 INFO - 'HOME': '/home/cltbld', 09:16:22 INFO - 'LANG': 'en_US.UTF-8', 09:16:22 INFO - 'LANGUAGE': 'en_US:en', 09:16:22 INFO - 'LOGNAME': 'cltbld', 09:16:22 INFO - 'MAIL': '/var/mail/cltbld', 09:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:22 INFO - 'MOZ_NO_REMOTE': '1', 09:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:22 INFO - 'NO_EM_RESTART': '1', 09:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:22 INFO - 'PWD': '/builds/slave/test', 09:16:22 INFO - 'SHELL': '/bin/bash', 09:16:22 INFO - 'SHLVL': '1', 09:16:22 INFO - 'TERM': 'linux', 09:16:22 INFO - 'TMOUT': '86400', 09:16:22 INFO - 'USER': 'cltbld', 09:16:22 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:22 INFO - '_': '/tools/buildbot/bin/python'} 09:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:22 INFO - Cleaning up... 09:16:22 INFO - Return code: 0 09:16:22 INFO - Running post-action listener: _resource_record_post_action 09:16:22 INFO - Running post-action listener: _start_resource_monitoring 09:16:22 INFO - Starting resource monitoring. 09:16:22 INFO - [mozharness: 2016-12-30 17:16:22.815055Z] Finished create-virtualenv step (success) 09:16:22 INFO - [mozharness: 2016-12-30 17:16:22.815725Z] Running install step. 09:16:22 INFO - Running pre-action listener: _resource_record_pre_action 09:16:22 INFO - Running main action method: install 09:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:23 INFO - Reading from file tmpfile_stdout 09:16:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:23 INFO - Reading from file tmpfile_stdout 09:16:23 INFO - Output received: 09:16:23 INFO - Usage: mozinstall [options] installer 09:16:23 INFO - Options: 09:16:23 INFO - -h, --help show this help message and exit 09:16:23 INFO - -d DEST, --destination=DEST 09:16:23 INFO - Directory to install application into. [default: 09:16:23 INFO - "/builds/slave/test"] 09:16:23 INFO - --app=APP Application being installed. [default: firefox] 09:16:23 INFO - mkdir: /builds/slave/test/build/application 09:16:23 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'] 09:16:23 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 09:16:40 INFO - Reading from file tmpfile_stdout 09:16:40 INFO - Output received: 09:16:40 INFO - /builds/slave/test/build/application/firefox/firefox 09:16:40 INFO - Running post-action listener: _resource_record_post_action 09:16:40 INFO - [mozharness: 2016-12-30 17:16:40.924495Z] Finished install step (success) 09:16:40 INFO - [mozharness: 2016-12-30 17:16:40.924744Z] Running run-tests step. 09:16:40 INFO - Running pre-action listener: _resource_record_pre_action 09:16:40 INFO - Running main action method: run_tests 09:16:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:40 INFO - Python 2.7.3 09:16:40 INFO - Return code: 0 09:16:40 INFO - grabbing minidump binary from tooltool 09:16:40 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')]} 09:16:40 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 0x18e93f0>, '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 0x18e9cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ea1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:40 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 09:16:40 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 09:16:40 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 09:16:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:41 INFO - Return code: 0 09:16:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:16:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:16:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 09:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 09:16:41 INFO - Using env: {'DISPLAY': ':0', 09:16:41 INFO - 'HOME': '/home/cltbld', 09:16:41 INFO - 'LANG': 'en_US.UTF-8', 09:16:41 INFO - 'LANGUAGE': 'en_US:en', 09:16:41 INFO - 'LOGNAME': 'cltbld', 09:16:41 INFO - 'MAIL': '/var/mail/cltbld', 09:16:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:41 INFO - 'MOZ_NO_REMOTE': '1', 09:16:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:41 INFO - 'NO_EM_RESTART': '1', 09:16:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:41 INFO - 'PWD': '/builds/slave/test', 09:16:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:41 INFO - 'SHELL': '/bin/bash', 09:16:41 INFO - 'SHLVL': '1', 09:16:41 INFO - 'TERM': 'linux', 09:16:41 INFO - 'TMOUT': '86400', 09:16:41 INFO - 'USER': 'cltbld', 09:16:41 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1483118143.403690-120424550', 09:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:41 INFO - '_': '/tools/buildbot/bin/python'} 09:16:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 09:16:41 INFO - mozversion application_buildid: 20161230073208 09:16:41 INFO - mozversion application_changeset: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 09:16:41 INFO - mozversion application_display_name: Nightly 09:16:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:41 INFO - mozversion application_name: Firefox 09:16:41 INFO - mozversion application_remotingname: firefox 09:16:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:16:41 INFO - mozversion application_vendor: Mozilla 09:16:41 INFO - mozversion application_version: 53.0a1 09:16:41 INFO - mozversion platform_buildid: 20161230073208 09:16:41 INFO - mozversion platform_changeset: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 09:16:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:16:41 INFO - mozversion platform_version: 53.0a1 09:16:41 INFO - using testdate: 1483118201 09:16:41 INFO - actual date: 1483118201 09:16:41 INFO - starting webserver on 'localhost:42534' 09:16:41 INFO - SUITE-START | Running 2 tests 09:16:41 INFO - TEST-START | dromaeo_css 09:16:41 INFO - operating with platform_type : linux_ 09:16:41 INFO - Initialising browser for dromaeo_css test... 09:16:41 INFO - TEST-INFO | started process 31112 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM7T_9o/profile http://localhost:42534/getInfo.html) 09:16:41 INFO - PROCESS | 31112 | 1483118201677 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:16:45 INFO - PROCESS | 31112 | __metrics Screen width/height:1600/1200 09:16:45 INFO - PROCESS | 31112 | colorDepth:24 09:16:45 INFO - PROCESS | 31112 | Browser inner width/height: 1024/697 09:16:45 INFO - PROCESS | 31112 | __metrics 09:16:45 INFO - PROCESS | 31112 | 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" 09:16:45 INFO - PROCESS | 31112 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:16:46 INFO - PROCESS | 31112 | console.error: PushService: 09:16:46 INFO - PROCESS | 31112 | stateChangeProcessEnqueue: Error transitioning state 09:16:46 INFO - PROCESS | 31112 | UnknownError 09:16:47 INFO - TEST-INFO | 31112: exit 0 09:16:47 INFO - Browser initialized. 09:16:47 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:47 INFO - TEST-INFO | started process 31197 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM7T_9o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:58 INFO - PROCESS | 31197 | RSS: Main: 146735104 09:16:58 INFO - PROCESS | 31197 | 09:17:50 INFO - PROCESS | 31197 | 1483118270336 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] 09:20:00 INFO - PROCESS | 31197 | Cycle 1(1): loaded http://localhost:42534/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42534/tests/dromaeo/cssquery-ext.html) 09:20:02 INFO - PROCESS | 31197 | RSS: Main: 259465216 09:20:02 INFO - PROCESS | 31197 | 09:21:47 INFO - PROCESS | 31197 | ************************* 09:21:47 INFO - PROCESS | 31197 | A coding exception was thrown and uncaught in a Task. 09:21:47 INFO - PROCESS | 31197 | 09:21:47 INFO - PROCESS | 31197 | Full message: ReferenceError: fetch is not defined 09:21:47 INFO - PROCESS | 31197 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:21:47 INFO - PROCESS | 31197 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:21:47 INFO - PROCESS | 31197 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:21:47 INFO - PROCESS | 31197 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:21:47 INFO - PROCESS | 31197 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:21:47 INFO - PROCESS | 31197 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:21:47 INFO - PROCESS | 31197 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:21:47 INFO - PROCESS | 31197 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:21:47 INFO - PROCESS | 31197 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:21:47 INFO - PROCESS | 31197 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:21:47 INFO - PROCESS | 31197 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:21:47 INFO - PROCESS | 31197 | 09:21:47 INFO - PROCESS | 31197 | ************************* 09:23:04 INFO - PROCESS | 31197 | Cycle 1(1): loaded http://localhost:42534/tests/dromaeo/cssquery-ext.html (next: http://localhost:42534/tests/dromaeo/cssquery-jquery.html) 09:23:05 INFO - PROCESS | 31197 | RSS: Main: 233066496 09:23:05 INFO - PROCESS | 31197 | 09:26:06 INFO - PROCESS | 31197 | Cycle 1(1): loaded http://localhost:42534/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42534/tests/dromaeo/cssquery-mootools.html) 09:26:07 INFO - PROCESS | 31197 | RSS: Main: 221077504 09:26:07 INFO - PROCESS | 31197 | 09:29:09 INFO - PROCESS | 31197 | Cycle 1(1): loaded http://localhost:42534/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42534/tests/dromaeo/cssquery-prototype.html) 09:29:10 INFO - PROCESS | 31197 | RSS: Main: 226930688 09:29:10 INFO - PROCESS | 31197 | 09:32:12 INFO - PROCESS | 31197 | Cycle 1(1): loaded http://localhost:42534/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42534/tests/dromaeo/cssquery-yui.html) 09:32:13 INFO - PROCESS | 31197 | RSS: Main: 183623680 09:32:13 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | Cycle 1(1): loaded http://localhost:42534/tests/dromaeo/cssquery-yui.html (next: http://localhost:42534/tests/dromaeo/cssquery-dojo.html) 09:35:16 INFO - PROCESS | 31197 | RSS: Main: 265908224 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | __start_tp_report 09:35:16 INFO - PROCESS | 31197 | _x_x_mozilla_page_load 09:35:16 INFO - PROCESS | 31197 | _x_x_mozilla_page_load_details 09:35:16 INFO - PROCESS | 31197 | |i|pagename|runs| 09:35:16 INFO - PROCESS | 31197 | |0;dojo.html;3142.497200447928;3162.1620270628273;3187.537807017982;3189.744820414367;3195.536647186158;5207.140821764414;5281.603879709018;5290.523852853243;5347.251384806125;5356.866078348044;5322.095243808552;5339.092354299778;5347.144456886899;5465.480779325958;5523.834284971447;4938.308636790844;4971.304017437562;4990.30135780991;4998.775055122528;5006.499350065001;4861.416630004404;4938.777755001033;4971.875703107433;5001.274815151801;5007.499250074981;4911.165101932662;4984.202527595586;5020.121478741224;5037.1940489521685;5049.949500505003;4916.901661966776;4943.555080042814;4962.131626965267;4985.875353116165;4989.501049895018;4761.785719642625;4781.450133234653;4818.855434336975;4842.782074806642;4855.174620314555;5348.171033489815;5365.382980957162;5368.731563421813;5402.054640437907;5438.619296649236;5795.797147099874;5828.97085514574;5829.387914269026;5829.533637309005;5831.008728516163;5601.215829783833;5635.774569017234;5772.134179873011;5845.210896528956;5847.766089356421;5467.398586155599;5476.2607048048;5481.890362192734;5512.862178445571;5529.695866727328;6021.57848950578;6025.9096113558335;6030.33666296707;6032.215811944507;6032.668203248863;5605.495505404533;5657.32112146545;5665.745041473061;5758.136279558016;5762.538996880239;5778.075507918713;5781.653100813965;5824.563157763185;5881.206037185011;5887.028640274307;5755.85610359736;5800.274965629296;5806.883862322815;5814.215080964101;5815;5548.5838562108;5584.050711379075;5586.748596313176;5675.3757086720425;5819.82540523785;4377.299632058855;4381.18947994622;4409.250427427345;4447.2439685253585;4453.532379100213;3925.666318362914;3927.4697915781576;3930.292547341449;3958.129211573449;3970.0273433009097;5574.609777315549;5575.470330318613;5581;5581.776728930798;5626.803061892814;5731.570132240098;5780.439281394;5841.269841269841;5847.327557330961;5883.588148829541;5575.69333686643;5613.747381367848;5624.971875140598;5652.576056795757;5667.036603777367;4919.163742163841;4923.163062279421;4926.113299606033;4928.063667903087;4928.950710492921;6037.577369584174;6071.635701857726;6094.786682466092;6147.0779383092895;6181.072839074175;5838.503727183237;5882.5293976482635;5886.52907767369;5928.584999050025;5942.1383899335215;6143.754249830135;6146.446819786241;6149.508039356751;6150.815475535741;6151.138840562415;5655.151727240782;5686.34607020198;5791.218185544897;5792.710364481674;5794.449527294901;5768.192453056659;5799.101139323411;5806.2451881255165;5812.041013232769;5913.5564832636865;5691.829245122487;5694.174344720075;5701.800436984686;5711.057675483498;5724.914126288026;5576.553875689854;5722.771089156554;5726.112452569859;5727.1695604138;5728;5561.944380556142;5654.406287339934;5660.745266462936;5670.234518340137;5788.363280039276;5785.161151633074;5800.4489573490455;5803.564732644984;5805.506531944832;5809.302883653989;5451.318585176853;5454.372747134131;5472.644278121909;5647.0964645656495;5653.491185793298 09:35:16 INFO - PROCESS | 31197 | |1;ext.html;556.1657513729406;584.7193347193346;588.6732863260892;591.0867709388993;608.902575587906;32055.839720801392;41533.584664153386;43158.152578721325;43181.568184318145;47376.52623473764;44560.55439445605;44993.325100123526;45047.0990580189;45065.549344506544;45135;8424.157584241557;8777.166169213915;8953.910460895406;8955.910440895574;8958.596863141174;8502.06477287498;10362.222833287497;10585.364878107299;10657.946710266438;10941.398223097727;10332.483375831216;10381;10388.792224155512;10466.895331046706;10473.581056757721;10779.838302425469;10828.945855270753;10843.457827108614;10847.132229421666;10849.837252441219;10549.630762923338;10625.149988000941;10630.681079567625;10633.680989570325;10636.734081647943;42583.14833703308;42588.148237035086;42598.57401426008;42681;42886.14227715459;4142.668586513072;4204.978975105105;4216.2199993001395;4234.237837189305;4269.509006464265;31566.684333156605;31720.36559268825;31802.840985794923;32036;33009.83495082533;4316.115196384733;4375.868723938287;4394.645945557525;4402.3836662867225;4483.977580112079;313537;314238.4288078568;314854;315043;315095;58804.41520526261;59065;59149.70425147847;59168.70415647894;59179.40820591825;62573.68713156405;62684;62688.68655656784;62749;62798.68600657059;13611.047226694234;14005.5098071567;14050.789238161437;14080.366383512766;14217.715645687029;9719.271054670928;9978.451185184884;10013.148882345082;10555.577776888991;10627.202959778047;17065.829341706423;17483.737743933852;17594.648107038043;17676.734848977274;17726.202320895332;6713.731450742013;6777.8644427111185;6784.423324017514;6851;6937.479689023344;899.5457294066455;908.1191244492832;908.8364094462928;911.1435250864166;917.0325306801388;29896.850515747283;31049.37901242008;31068.844655776575;31210.84394578058;31241.375172496424;954.4654993203828;1038.6676263595577;1056.4400867540216;1057.8571892794528;1069.9144068474504;15240.771388429182;16358.591035224215;16365.754513682305;16425.425110121327;16531.256093475822;537398;537720;543429;543447;543766;59353.70323148443;59614.403855961755;59667.70166149228;59730;59812;1290.7612091763053;1320.3728229091105;1329.0829327763813;1341.745068436994;1348.2449828096298;28086.2978425541;29559.85220073886;29803.403931920806;30022.549661754656;30677.23306917345;51583.226251607004;52247.73876130595;52291;52374.73812630912;52536;401984;403810;404134;404461;405397;34946;35710.8214458926;38212.42681359726;40144;40829.38755918723;47566.76216618895;47760.522394777;47771.52228477671;47843.52156478391;48862.77843053952;39029.21941561096;39675.40486892641;40098.59901401065;41450;42100.36849447199;39572.40641390439;39708.60291397165;40154.39768403418;41190.58809411868;41490;34337.31325373529;35475.46786798148;39273;39916;40028;29880;31125.221869453446;32296.35407291794;32332.67667323391;32446.188845279055;24759.009639614214;25217.87391063033;25614.23157305284;25708.228753137435;25943.74056259489 09:35:16 INFO - PROCESS | 31197 | |2;jquery.html;399.6977410117721;407.36451136227487;410.7576529847393;414.8236999275307;427.13931428172236;17322.826771732278;18419.50029494395;18427.631447371044;18820.435386938396;18857.434276971697;18887.905560472213;19392.22431102754;19433.611327773437;19573.804261957375;19581.21675132993;5655.066913959192;5657.603967722261;5743.827685169452;5780.0751879699255;5780.306363236417;5622.381538030813;5705.800296989606;5706.828795136153;5708.229389032469;5716.371199168109;5528.198411230369;5648.181013753004;5657.9434205658035;5675.921574900776;5686.175504551838;5460.781568737246;5610.018246806813;5639.661620302795;5643.887122257553;5651.095824668054;5047.47001564834;5089.6691715038405;5092.8472145835685;5095.592352611782;5101.4133374662015;21560.676589851166;21614.675779863315;21636.567268654697;21657.891710541346;21842.017109230124;2626.5797472404324;2796.8881244750187;2828.4343131373807;2841.2186648671573;2850.372917958046;10299.124574411184;10328.173746100294;10564.471776411148;10579.153667706641;10769.4615269237;1917.5973045660428;1947.3573720672143;1980.8019198080221;1987.4335814293001;1988.7613486381726;76005.61997190033;76029;76537.37133372987;76568;77441.61279193625;11092.445377731081;11205.439728013567;11279.154063445274;11288.717782055513;11289.37908415045;15402.691946161014;15446.691066178613;15475.613109672124;15476.690466190614;15484.5354639361;7272.600006999666;7422.146453157848;7454.180040195574;7617.5048621839405;7643.044619422572;6831.385175334244;6858.142732158481;7016.684249208798;7027.8594428111155;7028.297170282931;4769.809207631725;4837.346958160603;4852.684575502652;4898.2652602109965;4985.4017517897355;2000.9090500426798;2051.6819892916624;2082.3648787120073;2115.323096609101;2125.5005073807642;818.2186012358184;835.7952301686004;848.9533075680897;855.149126619018;871.8953725552974;16609.501714948572;17112.57218569546;17376.739348909778;17911.044677628044;18278.26886924535;856.8200677857595;867.0765634599131;872.2150064941603;875.5490922175085;877.434055034512;9520.767410986136;10440.321379110334;10453.529591168415;10460.215483838743;10552.525136368882;385137;389559;391133;392826;397300;84578.57710711408;85607.57196214104;85629;87954.56022719973;88387;1044.8693913260843;1057.386715704874;1079.7246702090918;1100.3287994323457;1100.339796122336;16561.751573726644;16835.831641683428;16966.560549096423;17280.567985800455;17313.220905059556;39014.6098539011;39567.40648890327;39728.60271397365;39780;39810.800945995084;54149.729251355064;54161.72919135379;54705.72647136739;55281.447185529236;55636;8458.365622578209;8499.57502124879;8656.965613081047;8672.696455624024;8878.73363799087;11591.420428978348;12838.29389383593;12964.546240881524;13021.739565208833;13040.673983150118;10364.689059328231;10980.725481863017;11369.602063927723;11561.710957226136;11644.825327619923;11240.55037798479;11455.823393555198;11510.42447877586;11646.76706465849;11840.28958262507;11323.207375483636;11431.142664300045;11457.312561246019;11682.532698691957;11688.123390745557;8388.832223355621;8544.300644608571;8629.827403452022;8682.175193356621;8737.169968852948;8599.484030958163;10501.894981050398;11200.607978720705;11231.831522527333;11429.371384573928 09:35:16 INFO - PROCESS | 31197 | |3;mootools.html;1309.3976770685483;1342.187976274354;1360.272254343926;1363.0526783885198;1365.0853927868327;7526.849463010736;8123.593820308983;8397;8400.537970411626;8658.437201581908;8432.114627964052;8513.148685131484;8518.36112291579;8519;8523;2071.461420030795;2137.487003119256;2309.168699268266;2367.621180611103;2369.9407514812096;2342.9882850585723;2411.3489357873364;2427.1141033522727;2432.20953190213;2441.4872876695913;2326.7905888470036;2340.7659234076627;2345.906163753448;2350.5063936573342;2356.575816353056;2313.548857972696;2313.8727369994645;2333.4166458385403;2333.871637059961;2334.3813889319345;2258.4128126687015;2331.4521087544413;2350.435895385095;2355.422921384272;2355.9764402355927;8533.487990720576;8593.871091933563;8609.26821220197;8610.741677749678;8621.482711037295;5150.459201783834;5334.626576139672;5337.06601344767;5339.119045357511;5339.6262261641705;3314.5359649649067;3326.916827079318;3575.928481430383;3577.5349204603235;3578.320119177374;1413.4487549855462;1425.2232533269394;1438.5684294711546;1448.4423496953643;1556.571942715751;11617.593384231595;11628.709282267926;11650.766984660344;11652.650420487398;11655.30068195911;4659.627229821606;4690.788914498856;4714.905701886011;4734.621230301567;4763.356946812205;9873.950630246803;9912.950435247778;9972.700818975442;9989.700308990741;9996.900030999743;5293.391260005072;5293.788248470095;5299.33758280215;5300.1519756838725;5303.575713942875;4790.329353890458;4827.710337379768;4845.200541910545;4876.902461950812;4877.853664390074;3809.980950095232;3936.566977632458;3990.8004599769897;4088.407180958804;4192;1321.9206847589176;1325.1717676452217;1329.6609364612154;1340.477213886585;1350.9797353039712;870.8998465176586;891.0530490877325;891.8573028315438;893.4594570285014;914.5564401265381;5437.782488700408;5600.299962504687;5677.120046392815;5696.316442026983;5699.5155411790465;562.3532937122342;567.4580775359522;572.1103683771692;574.4399210769467;578.9247397838254;3792.5069740934055;3857.4213867920034;3862.3820188769664;3899.200663863901;3904.9847039769297;9878.604855805686;10337.638182663572;10380.896191038144;10418.583256669799;10447.895521044844;10274.589016439408;10293.794124117476;10300.484975751333;10312.587496500206;10348.172146228284;7512.887306690404;7839.725609603636;7860.842783144305;7961.72133975308;7987.880181797278;5801.6519008859605;5805.477507024388;5898.1152827076285;5899.351071382059;6013.428724271187;9214.170724634814;9305.627774888928;9319.720408387759;9405.717828465156;9438.764030899009;9687.370320929118;9698;9731.172850307803;9785.21718262523;9843.212542996685;7397.149327827154;7483.625818708934;7516.549007059594;7528.849423011399;7573.583452910143;8064.516129032277;8212.712555060543;8213.425060245725;8216;8236.135205803304;7342.853142937218;7445.10658720957;7464.290892365217;7523.473356864967;7595.658195381111;6997.440204783503;7045.682944267418;7119.786406407816;7281.854362912818;7325.560466372035;6483.837904052436;6650.933490665031;6652.035454859115;6656.001599759881;6674.299198584079;4921.311016457627;4932.988737308771;5028.069807085701;5081.0854046272025;5151.484851514819;4960.30555722192;5029.723365214948;5120.078385890575;5177.585793136615;5190.870228244324 09:35:16 INFO - PROCESS | 31197 | |4;prototype.html;378.52684144818977;389.604551380349;399.39292275740877;400.4453831443451;403.7000857239689;5058.4435712071645;5162.406323272825;5195.272661827347;5197.922031169535;5311.442298558653;4989.850304490871;5168.2764412982215;5174.309359133248;5223;5228.529432351088;4061.532923713766;4076.4700588923547;4077.4903137107863;4080.1431699343175;4093.2836753568154;3907.570167281611;3952.2688302663955;4011;4050.2709512287774;4088.0597462583623;4016.4577781999496;4027.97986010071;4032.5564187939303;4038.8586399476026;4045.7167998240134;4030.3954406838916;4046.150308435232;4052.7365721228025;4055.655269302113;4062.9796851015853;3421.81180035098;3603.837827297778;3605.927881442383;3608.332458495187;3618.3306088373733;5035.59715222785;5065.721385323805;5068.239764035387;5075.365579302587;5128.2564028216075;4674.579287864109;4676.649251306166;4683.227267500858;4685.906281874378;4694.554017368345;5204.557612602934;5449.809256676037;5461.836144915659;5462.317210348707;5489.862753431156;3919.588947978754;3962.643362097396;3966.107625784204;3971.4837071180564;3972.682185425159;5653.434656534355;5659.87754592028;5665.716714164317;5705.800296989585;5711.40030296817;4036.798160091984;4064.3903414488063;4067;4069.819752271808;4079.633322836883;3924.7056470764805;3937.7046721496004;4101.712880098365;4107.137501124737;4147.54835807471;4462.48681401643;4595.241785105487;4598.195315819718;4604.516525764747;4626.352310676508;5456.48163424474;5462.808801692001;5476.26070480488;5486.3690675572025;5488.490665067138;1352.4657760184673;1363.8772510474105;1369.6233535777542;1369.8561651026698;1378.710470801123;959.9808003839884;965.7344230336713;967.709687093869;975.0785507695301;981.6269817469316;5003.949960500348;5043.798248070036;5067.417247016567;5103.948960510422;5149.948500514947;5395.595330350239;5404.216388623706;5422.102264207496;5458.208559758892;5479.178123281539;4986.7257300848805;5083.542481176712;5117.590592752571;5119.718415487109;5130.871728206825;2876.453473839964;2877.2806798300426;2904.941901161965;2905.3149173479333;2907.8400687961944;5480.561555075585;5612.410696876851;5634.3802181759975;5648.322201335866;5651.208830763697;5444.782208711607;5444.972775136099;5465.4261302563455;5478.205660179252;5536.972315138479;5532.5297349724915;5577.470140336662;5645.153227015899;5660.801871934463;5679.261695979496;5262.921056184084;5302.946970530245;5319.547838433777;5342.919856202082;5422.8850748209015;5284.867878303073;5290.841274761763;5292.9735351323;5301.973490132525;5309.415964243853;5294.417614062527;5565.608597850537;5586.916196256978;5634.774609015593;5654.264945557051;3914.5106861642294;3958.2083583284034;3966.821493032878;3979.582143874851;3983.9044262827956;5236.2720302299385;5367.785288588413;5419.566434685295;5467.58993075513;5536.169574563848;5434.184872269191;5439.292891924025;5458.044842152528;5482.834280459117;5495.368032676293;5439;5446.809361672323;5478.671279723229;5482.725863706878;5491.7254137292175;5235.712035837913;5399.244105825109;5408.54027407659;5417.864553386197;5434.64674796137;5478.479544443271;5482.533984611353;5506.476884695948;5508.724563771715;5518.696471693935;5224.660397074178;5373.9731301343245;5374.404883824274;5384.946150538444;5390.595705322038 09:35:16 INFO - PROCESS | 31197 | |5;yui.html;745.4074011161126;761.7105499910036;779.8791187365956;802.1176705623815;851.7061613743258;13638.181709097462;13932;14085.718285634293;16169.272382742776;16199.433019844279;15277.6944461111;15367.15480648564;15398;15475.84524154758;15946.681066378695;811.8457493076297;834.9415540912139;835.1189495082685;835.8829763833069;836.1178956201205;710.594960872303;731.9158296795883;804.5655346113092;840.0633293877313;841.3017195727532;873.6680061576592;884.4074470105013;884.8230353929208;885.0662551008684;885.2873436883307;875.3128793896792;875.9693410730625;876.6537217798998;876.8640860666607;880.3353468131584;542.1542393865583;556.9637973531708;614.858582526017;632.7627139822566;645.5610185074148;16152.919235403866;16224.432144874872;16277.837221627751;16287.755683664755;16299.429519966745;13001.284929328884;13018.283994380305;13189.670258243525;13285.202887826665;13338.160609427028;12683.619491415273;12731.93634031824;12798;13059.542915997894;13227.470901163942;4438.889027774299;4468.285074388083;4478.865634030984;4595.954040459619;4609.884752881171;77515.61242193753;77942.61028694877;77992.61003695002;78722.60638696827;81521.59239203767;11120.165987550967;11160.218784685156;11179;11198.776024479464;11415.657530274106;20114.39656810298;20168.78987260769;20550.17799288012;20812;20817.271395501084;7664.34853037498;7682.231776822385;7736.032995875516;7755.457118001686;7890.842183156305;4366.104948485553;4493.910121797546;4507.909841803145;4527.320901864681;4631.282151266559;3431.1422144463886;3522.964770352315;3527.2592755521628;3530.029241958482;3544.7341449390883;647.6179054357951;657.2572992518519;663.4128796015465;670.276101810044;672.8553361027402;730.791724358566;733.4352548537703;747.4415842967474;752.3717695724132;755.9130699969464;9114.36199466031;9475.804451147498;9570;9765.560549775277;9996.700098997042;691.8288863339028;697.3584302441766;709.9680514376865;715.2561336210387;723.020307483363;4287.378330142112;4449.488308844459;4502.3246513023305;4555.794989225493;4563.269876819693;62198.378016220166;64423.29706646515;65021.34978650153;73379.26620733725;75912.24087759163;64774.352256477774;65465;65534.67232663902;66981;69644.6517767408;58441.707791461624;58517.122243166385;59325;60510.39489605048;60562.39437605567;8914;9350.438973661601;9451.621935122517;9468.905310946802;9481.857772133286;39787;39867.60132398639;40586.376544939056;41496.58503414927;41735.79132104442;50061;50120.74939625279;50893;50919.236211457595;51063;12889.871101288867;13088.869111308764;13176.868231317565;13271.469141234242;13434.261115638734;13858.584242472742;13901.510255619847;13919.930400347934;14052;14145.787813183017;13621.931890340486;13768.007277889299;13813.240271784747;14010.159390436607;14061.296935152997;13631.659208519866;13803.792943106062;13820.447182113005;13844.30778461093;14806.768413412128;13774.86225137776;14172.787408188678;14258.429662813785;15036.398544058115;15260.923695381896;11116.888831111586;11370.772584548096;11456.427178641201;12092.153549251469;12302.077344199042;11812.881871181522;11866.703332416413;12053.337066461429;12175.269483830998;12468 09:35:16 INFO - PROCESS | 31197 | __end_tp_report 09:35:16 INFO - PROCESS | 31197 | __start_cc_report 09:35:16 INFO - PROCESS | 31197 | _x_x_mozilla_cycle_collect,1033 09:35:16 INFO - PROCESS | 31197 | __end_cc_report 09:35:16 INFO - PROCESS | 31197 | __startTimestamp1483119316582__endTimestamp 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | ------- Summary: start ------- 09:35:16 INFO - PROCESS | 31197 | Number of tests: 6 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | [#0] dojo.html Cycles:165 Average:5407.41 Median:5626.80 stddev:627.34 (11.1%) stddev-sans-first:603.58 09:35:16 INFO - PROCESS | 31197 | Values: 3142.5 3162.2 3187.5 3189.7 3195.5 5207.1 5281.6 5290.5 5347.3 5356.9 5322.1 5339.1 5347.1 5465.5 5523.8 4938.3 4971.3 4990.3 4998.8 5006.5 4861.4 4938.8 4971.9 5001.3 5007.5 4911.2 4984.2 5020.1 5037.2 5049.9 4916.9 4943.6 4962.1 4985.9 4989.5 4761.8 4781.5 4818.9 4842.8 4855.2 5348.2 5365.4 5368.7 5402.1 5438.6 5795.8 5829.0 5829.4 5829.5 5831.0 5601.2 5635.8 5772.1 5845.2 5847.8 5467.4 5476.3 5481.9 5512.9 5529.7 6021.6 6025.9 6030.3 6032.2 6032.7 5605.5 5657.3 5665.7 5758.1 5762.5 5778.1 5781.7 5824.6 5881.2 5887.0 5755.9 5800.3 5806.9 5814.2 5815.0 5548.6 5584.1 5586.7 5675.4 5819.8 4377.3 4381.2 4409.3 4447.2 4453.5 3925.7 3927.5 3930.3 3958.1 3970.0 5574.6 5575.5 5581.0 5581.8 5626.8 5731.6 5780.4 5841.3 5847.3 5883.6 5575.7 5613.7 5625.0 5652.6 5667.0 4919.2 4923.2 4926.1 4928.1 4929.0 6037.6 6071.6 6094.8 6147.1 6181.1 5838.5 5882.5 5886.5 5928.6 5942.1 6143.8 6146.4 6149.5 6150.8 6151.1 5655.2 5686.3 5791.2 5792.7 5794.4 5768.2 5799.1 5806.2 5812.0 5913.6 5691.8 5694.2 5701.8 5711.1 5724.9 5576.6 5722.8 5726.1 5727.2 5728.0 5561.9 5654.4 5660.7 5670.2 5788.4 5785.2 5800.4 5803.6 5805.5 5809.3 5451.3 5454.4 5472.6 5647.1 5653.5 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | [#1] ext.html Cycles:180 Average:58461.97 Median:40149.20 stddev:114050.73 (284.1%) stddev-sans-first:114287.81 09:35:16 INFO - PROCESS | 31197 | Values: 556.2 584.7 588.7 591.1 608.9 32055.8 41533.6 43158.2 43181.6 47376.5 44560.6 44993.3 45047.1 45065.5 45135.0 8424.2 8777.2 8953.9 8955.9 8958.6 8502.1 10362.2 10585.4 10657.9 10941.4 10332.5 10381.0 10388.8 10466.9 10473.6 10779.8 10828.9 10843.5 10847.1 10849.8 10549.6 10625.1 10630.7 10633.7 10636.7 42583.1 42588.1 42598.6 42681.0 42886.1 4142.7 4205.0 4216.2 4234.2 4269.5 31566.7 31720.4 31802.8 32036.0 33009.8 4316.1 4375.9 4394.6 4402.4 4484.0 313537.0 314238.4 314854.0 315043.0 315095.0 58804.4 59065.0 59149.7 59168.7 59179.4 62573.7 62684.0 62688.7 62749.0 62798.7 13611.0 14005.5 14050.8 14080.4 14217.7 9719.3 9978.5 10013.1 10555.6 10627.2 17065.8 17483.7 17594.6 17676.7 17726.2 6713.7 6777.9 6784.4 6851.0 6937.5 899.5 908.1 908.8 911.1 917.0 29896.9 31049.4 31068.8 31210.8 31241.4 954.5 1038.7 1056.4 1057.9 1069.9 15240.8 16358.6 16365.8 16425.4 16531.3 537398.0 537720.0 543429.0 543447.0 543766.0 59353.7 59614.4 59667.7 59730.0 59812.0 1290.8 1320.4 1329.1 1341.7 1348.2 28086.3 29559.9 29803.4 30022.5 30677.2 51583.2 52247.7 52291.0 52374.7 52536.0 401984.0 403810.0 404134.0 404461.0 405397.0 34946.0 35710.8 38212.4 40144.0 40829.4 47566.8 47760.5 47771.5 47843.5 48862.8 39029.2 39675.4 40098.6 41450.0 42100.4 39572.4 39708.6 40154.4 41190.6 41490.0 34337.3 35475.5 39273.0 39916.0 40028.0 29880.0 31125.2 32296.4 32332.7 32446.2 24759.0 25217.9 25614.2 25708.2 25943.7 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | [#2] jquery.html Cycles:180 Average:25602.01 Median:215073.80 stddev:64906.23 (30.2%) stddev-sans-first:65060.72 09:35:16 INFO - PROCESS | 31197 | Values: 399.7 407.4 410.8 414.8 427.1 17322.8 18419.5 18427.6 18820.4 18857.4 18887.9 19392.2 19433.6 19573.8 19581.2 5655.1 5657.6 5743.8 5780.1 5780.3 5622.4 5705.8 5706.8 5708.2 5716.4 5528.2 5648.2 5657.9 5675.9 5686.2 5460.8 5610.0 5639.7 5643.9 5651.1 5047.5 5089.7 5092.8 5095.6 5101.4 21560.7 21614.7 21636.6 21657.9 21842.0 2626.6 2796.9 2828.4 2841.2 2850.4 10299.1 10328.2 10564.5 10579.2 10769.5 1917.6 1947.4 1980.8 1987.4 1988.8 76005.6 76029.0 76537.4 76568.0 77441.6 11092.4 11205.4 11279.2 11288.7 11289.4 15402.7 15446.7 15475.6 15476.7 15484.5 7272.6 7422.1 7454.2 7617.5 7643.0 6831.4 6858.1 7016.7 7027.9 7028.3 4769.8 4837.3 4852.7 4898.3 4985.4 2000.9 2051.7 2082.4 2115.3 2125.5 818.2 835.8 849.0 855.1 871.9 16609.5 17112.6 17376.7 17911.0 18278.3 856.8 867.1 872.2 875.5 877.4 9520.8 10440.3 10453.5 10460.2 10552.5 385137.0 389559.0 391133.0 392826.0 397300.0 84578.6 85607.6 85629.0 87954.6 88387.0 1044.9 1057.4 1079.7 1100.3 1100.3 16561.8 16835.8 16966.6 17280.6 17313.2 39014.6 39567.4 39728.6 39780.0 39810.8 54149.7 54161.7 54705.7 55281.4 55636.0 8458.4 8499.6 8657.0 8672.7 8878.7 11591.4 12838.3 12964.5 13021.7 13040.7 10364.7 10980.7 11369.6 11561.7 11644.8 11240.6 11455.8 11510.4 11646.8 11840.3 11323.2 11431.1 11457.3 11682.5 11688.1 8388.8 8544.3 8629.8 8682.2 8737.2 8599.5 10501.9 11200.6 11231.8 11429.4 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | [#3] mootools.html Cycles:180 Average:5604.12 Median:5293.59 stddev:3100.88 (58.6%) stddev-sans-first:3092.77 09:35:16 INFO - PROCESS | 31197 | Values: 1309.4 1342.2 1360.3 1363.1 1365.1 7526.8 8123.6 8397.0 8400.5 8658.4 8432.1 8513.1 8518.4 8519.0 8523.0 2071.5 2137.5 2309.2 2367.6 2369.9 2343.0 2411.3 2427.1 2432.2 2441.5 2326.8 2340.8 2345.9 2350.5 2356.6 2313.5 2313.9 2333.4 2333.9 2334.4 2258.4 2331.5 2350.4 2355.4 2356.0 8533.5 8593.9 8609.3 8610.7 8621.5 5150.5 5334.6 5337.1 5339.1 5339.6 3314.5 3326.9 3575.9 3577.5 3578.3 1413.4 1425.2 1438.6 1448.4 1556.6 11617.6 11628.7 11650.8 11652.7 11655.3 4659.6 4690.8 4714.9 4734.6 4763.4 9874.0 9913.0 9972.7 9989.7 9996.9 5293.4 5293.8 5299.3 5300.2 5303.6 4790.3 4827.7 4845.2 4876.9 4877.9 3810.0 3936.6 3990.8 4088.4 4192.0 1321.9 1325.2 1329.7 1340.5 1351.0 870.9 891.1 891.9 893.5 914.6 5437.8 5600.3 5677.1 5696.3 5699.5 562.4 567.5 572.1 574.4 578.9 3792.5 3857.4 3862.4 3899.2 3905.0 9878.6 10337.6 10380.9 10418.6 10447.9 10274.6 10293.8 10300.5 10312.6 10348.2 7512.9 7839.7 7860.8 7961.7 7987.9 5801.7 5805.5 5898.1 5899.4 6013.4 9214.2 9305.6 9319.7 9405.7 9438.8 9687.4 9698.0 9731.2 9785.2 9843.2 7397.1 7483.6 7516.5 7528.8 7573.6 8064.5 8212.7 8213.4 8216.0 8236.1 7342.9 7445.1 7464.3 7523.5 7595.7 6997.4 7045.7 7119.8 7281.9 7325.6 6483.8 6650.9 6652.0 6656.0 6674.3 4921.3 4933.0 5028.1 5081.1 5151.5 4960.3 5029.7 5120.1 5177.6 5190.9 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | [#4] prototype.html Cycles:180 Average:4546.04 Median:5196.60 stddev:1310.03 (25.2%) stddev-sans-first:1275.81 09:35:16 INFO - PROCESS | 31197 | Values: 378.5 389.6 399.4 400.4 403.7 5058.4 5162.4 5195.3 5197.9 5311.4 4989.9 5168.3 5174.3 5223.0 5228.5 4061.5 4076.5 4077.5 4080.1 4093.3 3907.6 3952.3 4011.0 4050.3 4088.1 4016.5 4028.0 4032.6 4038.9 4045.7 4030.4 4046.2 4052.7 4055.7 4063.0 3421.8 3603.8 3605.9 3608.3 3618.3 5035.6 5065.7 5068.2 5075.4 5128.3 4674.6 4676.6 4683.2 4685.9 4694.6 5204.6 5449.8 5461.8 5462.3 5489.9 3919.6 3962.6 3966.1 3971.5 3972.7 5653.4 5659.9 5665.7 5705.8 5711.4 4036.8 4064.4 4067.0 4069.8 4079.6 3924.7 3937.7 4101.7 4107.1 4147.5 4462.5 4595.2 4598.2 4604.5 4626.4 5456.5 5462.8 5476.3 5486.4 5488.5 1352.5 1363.9 1369.6 1369.9 1378.7 960.0 965.7 967.7 975.1 981.6 5003.9 5043.8 5067.4 5103.9 5149.9 5395.6 5404.2 5422.1 5458.2 5479.2 4986.7 5083.5 5117.6 5119.7 5130.9 2876.5 2877.3 2904.9 2905.3 2907.8 5480.6 5612.4 5634.4 5648.3 5651.2 5444.8 5445.0 5465.4 5478.2 5537.0 5532.5 5577.5 5645.2 5660.8 5679.3 5262.9 5302.9 5319.5 5342.9 5422.9 5284.9 5290.8 5293.0 5302.0 5309.4 5294.4 5565.6 5586.9 5634.8 5654.3 3914.5 3958.2 3966.8 3979.6 3983.9 5236.3 5367.8 5419.6 5467.6 5536.2 5434.2 5439.3 5458.0 5482.8 5495.4 5439.0 5446.8 5478.7 5482.7 5491.7 5235.7 5399.2 5408.5 5417.9 5434.6 5478.5 5482.5 5506.5 5508.7 5518.7 5224.7 5374.0 5374.4 5384.9 5390.6 09:35:16 INFO - PROCESS | 31197 | 09:35:16 INFO - PROCESS | 31197 | [#5] yui.html Cycles:180 Average:17007.72 Median:4559.53 stddev:21023.39 (461.1%) stddev-sans-first:21046.89 09:35:16 INFO - PROCESS | 31197 | Values: 745.4 761.7 779.9 802.1 851.7 13638.2 13932.0 14085.7 16169.3 16199.4 15277.7 15367.2 15398.0 15475.8 15946.7 811.8 834.9 835.1 835.9 836.1 710.6 731.9 804.6 840.1 841.3 873.7 884.4 884.8 885.1 885.3 875.3 876.0 876.7 876.9 880.3 542.2 557.0 614.9 632.8 645.6 16152.9 16224.4 16277.8 16287.8 16299.4 13001.3 13018.3 13189.7 13285.2 13338.2 12683.6 12731.9 12798.0 13059.5 13227.5 4438.9 4468.3 4478.9 4596.0 4609.9 77515.6 77942.6 77992.6 78722.6 81521.6 11120.2 11160.2 11179.0 11198.8 11415.7 20114.4 20168.8 20550.2 20812.0 20817.3 7664.3 7682.2 7736.0 7755.5 7890.8 4366.1 4493.9 4507.9 4527.3 4631.3 3431.1 3523.0 3527.3 3530.0 3544.7 647.6 657.3 663.4 670.3 672.9 730.8 733.4 747.4 752.4 755.9 9114.4 9475.8 9570.0 9765.6 9996.7 691.8 697.4 710.0 715.3 723.0 4287.4 4449.5 4502.3 4555.8 4563.3 62198.4 64423.3 65021.3 73379.3 75912.2 64774.4 65465.0 65534.7 66981.0 69644.7 58441.7 58517.1 59325.0 60510.4 60562.4 8914.0 9350.4 9451.6 9468.9 9481.9 39787.0 39867.6 40586.4 41496.6 41735.8 50061.0 50120.7 50893.0 50919.2 51063.0 12889.9 13088.9 13176.9 13271.5 13434.3 13858.6 13901.5 13919.9 14052.0 14145.8 13621.9 13768.0 13813.2 14010.2 14061.3 13631.7 13803.8 13820.4 13844.3 14806.8 13774.9 14172.8 14258.4 15036.4 15260.9 11116.9 11370.8 11456.4 12092.2 12302.1 11812.9 11866.7 12053.3 12175.3 12468.0 09:35:16 INFO - PROCESS | 31197 | -------- Summary: end -------- 09:35:16 INFO - PROCESS | 31197 | 09:35:17 INFO - TEST-INFO | 31197: exit 0 09:35:17 INFO - TEST-OK | dromaeo_css | took 1116136ms 09:35:17 INFO - TEST-START | kraken 09:35:17 INFO - operating with platform_type : linux_ 09:35:17 INFO - Initialising browser for kraken test... 09:35:17 INFO - TEST-INFO | started process 31503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNccjdh/profile http://localhost:42534/getInfo.html) 09:35:17 INFO - PROCESS | 31503 | 1483119317808 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:35:21 INFO - PROCESS | 31503 | __metrics Screen width/height:1600/1200 09:35:21 INFO - PROCESS | 31503 | colorDepth:24 09:35:21 INFO - PROCESS | 31503 | Browser inner width/height: 1024/697 09:35:21 INFO - PROCESS | 31503 | __metrics 09:35:21 INFO - PROCESS | 31503 | 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" 09:35:21 INFO - PROCESS | 31503 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:22 INFO - PROCESS | 31503 | console.error: PushService: 09:35:22 INFO - PROCESS | 31503 | stateChangeProcessEnqueue: Error transitioning state 09:35:22 INFO - PROCESS | 31503 | UnknownError 09:35:22 INFO - TEST-INFO | 31503: exit 0 09:35:22 INFO - Browser initialized. 09:35:22 INFO - Running cycle 1/1 for kraken test... 09:35:22 INFO - TEST-INFO | started process 31586 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNccjdh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:34 INFO - PROCESS | 31586 | RSS: Main: 147095552 09:35:34 INFO - PROCESS | 31586 | 09:35:37 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42534/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:38 INFO - PROCESS | 31586 | RSS: Main: 202317824 09:35:38 INFO - PROCESS | 31586 | 09:35:40 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42534/tests/kraken/driver.html?testName=audio-dft) 09:35:41 INFO - PROCESS | 31586 | RSS: Main: 181362688 09:35:41 INFO - PROCESS | 31586 | 09:35:44 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42534/tests/kraken/driver.html?testName=audio-fft) 09:35:44 INFO - PROCESS | 31586 | RSS: Main: 262488064 09:35:44 INFO - PROCESS | 31586 | 09:35:46 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42534/tests/kraken/driver.html?testName=audio-oscillator) 09:35:47 INFO - PROCESS | 31586 | RSS: Main: 173993984 09:35:47 INFO - PROCESS | 31586 | 09:35:49 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42534/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:50 INFO - PROCESS | 31586 | RSS: Main: 178339840 09:35:50 INFO - PROCESS | 31586 | 09:35:53 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42534/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:53 INFO - PROCESS | 31586 | RSS: Main: 217092096 09:35:53 INFO - PROCESS | 31586 | 09:35:57 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42534/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:57 INFO - PROCESS | 31586 | RSS: Main: 216850432 09:35:57 INFO - PROCESS | 31586 | 09:36:00 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42534/tests/kraken/driver.html?testName=json-parse-financial) 09:36:01 INFO - PROCESS | 31586 | RSS: Main: 215457792 09:36:01 INFO - PROCESS | 31586 | 09:36:02 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42534/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:03 INFO - PROCESS | 31586 | RSS: Main: 178024448 09:36:03 INFO - PROCESS | 31586 | 09:36:04 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:05 INFO - PROCESS | 31586 | RSS: Main: 184872960 09:36:05 INFO - PROCESS | 31586 | 09:36:07 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:08 INFO - PROCESS | 31586 | RSS: Main: 193916928 09:36:08 INFO - PROCESS | 31586 | 09:36:10 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:11 INFO - PROCESS | 31586 | RSS: Main: 202907648 09:36:11 INFO - PROCESS | 31586 | 09:36:14 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:14 INFO - PROCESS | 31586 | RSS: Main: 199725056 09:36:14 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | Cycle 1(1): loaded http://localhost:42534/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42534/tests/kraken/driver.html?testName=ai-astar) 09:36:16 INFO - PROCESS | 31586 | RSS: Main: 250445824 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | __start_tp_report 09:36:16 INFO - PROCESS | 31586 | _x_x_mozilla_page_load 09:36:16 INFO - PROCESS | 31586 | _x_x_mozilla_page_load_details 09:36:16 INFO - PROCESS | 31586 | |i|pagename|runs| 09:36:16 INFO - PROCESS | 31586 | |0;ai-astar;108;108;110;110;110;109;108;111;109;109 09:36:16 INFO - PROCESS | 31586 | |1;audio-beat-detection;143;119;121;159;122;119;123;117;146;120 09:36:16 INFO - PROCESS | 31586 | |2;audio-dft;161;160;161;160;161;160;163;161;161;161 09:36:16 INFO - PROCESS | 31586 | |3;audio-fft;74;75;76;100;76;77;77;76;92;76 09:36:16 INFO - PROCESS | 31586 | |4;audio-oscillator;83;83;119;83;84;83;95;83;84;110 09:36:16 INFO - PROCESS | 31586 | |5;imaging-gaussian-blur;116;117;117;117;116;117;117;117;117;117 09:36:16 INFO - PROCESS | 31586 | |6;imaging-darkroom;146;147;147;146;147;146;147;147;147;146 09:36:16 INFO - PROCESS | 31586 | |7;imaging-desaturate;96;96;96;96;96;96;95;95;96;96 09:36:16 INFO - PROCESS | 31586 | |8;json-parse-financial;51;50;53;49;51;50;50;52;51;52 09:36:16 INFO - PROCESS | 31586 | |9;json-stringify-tinderbox;66;66;69;66;66;67;66;67;65;66 09:36:16 INFO - PROCESS | 31586 | |10;stanford-crypto-aes;81;88;81;91;81;81;82;83;90;81 09:36:16 INFO - PROCESS | 31586 | |11;stanford-crypto-ccm;127;128;125;133;133;129;127;127;164;135 09:36:16 INFO - PROCESS | 31586 | |12;stanford-crypto-pbkdf2;168;168;165;163;166;164;163;164;166;159 09:36:16 INFO - PROCESS | 31586 | |13;stanford-crypto-sha256-iterative;62;65;61;63;61;61;63;63;61;65 09:36:16 INFO - PROCESS | 31586 | __end_tp_report 09:36:16 INFO - PROCESS | 31586 | __start_cc_report 09:36:16 INFO - PROCESS | 31586 | _x_x_mozilla_cycle_collect,1579 09:36:16 INFO - PROCESS | 31586 | __end_cc_report 09:36:16 INFO - PROCESS | 31586 | __startTimestamp1483119376888__endTimestamp 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | ------- Summary: start ------- 09:36:16 INFO - PROCESS | 31586 | Number of tests: 14 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#0] ai-astar Cycles:10 Average:109.20 Median:109.50 stddev:1.03 (0.9%) stddev-sans-first:1.00 09:36:16 INFO - PROCESS | 31586 | Values: 108.0 108.0 110.0 110.0 110.0 109.0 108.0 111.0 109.0 109.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:122.50 stddev:14.75 (12.0%) stddev-sans-first:14.74 09:36:16 INFO - PROCESS | 31586 | Values: 143.0 119.0 121.0 159.0 122.0 119.0 123.0 117.0 146.0 120.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 09:36:16 INFO - PROCESS | 31586 | Values: 161.0 160.0 161.0 160.0 161.0 160.0 163.0 161.0 161.0 161.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#3] audio-fft Cycles:10 Average:79.90 Median:76.00 stddev:8.74 (11.5%) stddev-sans-first:9.00 09:36:16 INFO - PROCESS | 31586 | Values: 74.0 75.0 76.0 100.0 76.0 77.0 77.0 76.0 92.0 76.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:83.00 stddev:13.24 (16.0%) stddev-sans-first:13.75 09:36:16 INFO - PROCESS | 31586 | Values: 83.0 83.0 119.0 83.0 84.0 83.0 95.0 83.0 84.0 110.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:36:16 INFO - PROCESS | 31586 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:36:16 INFO - PROCESS | 31586 | Values: 146.0 147.0 147.0 146.0 147.0 146.0 147.0 147.0 147.0 146.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:36:16 INFO - PROCESS | 31586 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.20 (2.3%) stddev-sans-first:1.27 09:36:16 INFO - PROCESS | 31586 | Values: 51.0 50.0 53.0 49.0 51.0 50.0 50.0 52.0 51.0 52.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 09:36:16 INFO - PROCESS | 31586 | Values: 66.0 66.0 69.0 66.0 66.0 67.0 66.0 67.0 65.0 66.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:82.50 stddev:4.09 (5.0%) stddev-sans-first:4.21 09:36:16 INFO - PROCESS | 31586 | Values: 81.0 88.0 81.0 91.0 81.0 81.0 82.0 83.0 90.0 81.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#11] stanford-crypto-ccm Cycles:10 Average:132.80 Median:131.00 stddev:11.44 (8.7%) stddev-sans-first:11.94 09:36:16 INFO - PROCESS | 31586 | Values: 127.0 128.0 125.0 133.0 133.0 129.0 127.0 127.0 164.0 135.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:165.50 stddev:2.67 (1.6%) stddev-sans-first:2.54 09:36:16 INFO - PROCESS | 31586 | Values: 168.0 168.0 165.0 163.0 166.0 164.0 163.0 164.0 166.0 159.0 09:36:16 INFO - PROCESS | 31586 | 09:36:16 INFO - PROCESS | 31586 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:63.00 stddev:1.58 (2.5%) stddev-sans-first:1.67 09:36:16 INFO - PROCESS | 31586 | Values: 62.0 65.0 61.0 63.0 61.0 61.0 63.0 63.0 61.0 65.0 09:36:16 INFO - PROCESS | 31586 | -------- Summary: end -------- 09:36:16 INFO - PROCESS | 31586 | 09:36:17 INFO - TEST-INFO | 31586: exit 0 09:36:17 INFO - TEST-OK | kraken | took 60647ms 09:36:17 INFO - SUITE-END | took 1176s 09:36:18 INFO - Completed test suite (00:19:36) 09:36:18 INFO - javascript benchmark 09:36:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3142.497200447928, 3162.1620270628273, 3187.537807017982, 3189.744820414367, 3195.536647186158, 5207.140821764414, 5281.603879709018, 5290.523852853243, 5347.251384806125, 5356.866078348044, 5322.095243808552, 5339.092354299778, 5347.144456886899, 5465.480779325958, 5523.834284971447, 4938.308636790844, 4971.304017437562, 4990.30135780991, 4998.775055122528, 5006.499350065001, 4861.416630004404, 4938.777755001033, 4971.875703107433, 5001.274815151801, 5007.499250074981, 4911.165101932662, 4984.202527595586, 5020.121478741224, 5037.1940489521685, 5049.949500505003, 4916.901661966776, 4943.555080042814, 4962.131626965267, 4985.875353116165, 4989.501049895018, 4761.785719642625, 4781.450133234653, 4818.855434336975, 4842.782074806642, 4855.174620314555, 5348.171033489815, 5365.382980957162, 5368.731563421813, 5402.054640437907, 5438.619296649236, 5795.797147099874, 5828.97085514574, 5829.387914269026, 5829.533637309005, 5831.008728516163, 5601.215829783833, 5635.774569017234, 5772.134179873011, 5845.210896528956, 5847.766089356421, 5467.398586155599, 5476.2607048048, 5481.890362192734, 5512.862178445571, 5529.695866727328, 6021.57848950578, 6025.9096113558335, 6030.33666296707, 6032.215811944507, 6032.668203248863, 5605.495505404533, 5657.32112146545, 5665.745041473061, 5758.136279558016, 5762.538996880239, 5778.075507918713, 5781.653100813965, 5824.563157763185, 5881.206037185011, 5887.028640274307, 5755.85610359736, 5800.274965629296, 5806.883862322815, 5814.215080964101, 5815.0, 5548.5838562108, 5584.050711379075, 5586.748596313176, 5675.3757086720425, 5819.82540523785, 4377.299632058855, 4381.18947994622, 4409.250427427345, 4447.2439685253585, 4453.532379100213, 3925.666318362914, 3927.4697915781576, 3930.292547341449, 3958.129211573449, 3970.0273433009097, 5574.609777315549, 5575.470330318613, 5581.0, 5581.776728930798, 5626.803061892814, 5731.570132240098, 5780.439281394, 5841.269841269841, 5847.327557330961, 5883.588148829541, 5575.69333686643, 5613.747381367848, 5624.971875140598, 5652.576056795757, 5667.036603777367, 4919.163742163841, 4923.163062279421, 4926.113299606033, 4928.063667903087, 4928.950710492921, 6037.577369584174, 6071.635701857726, 6094.786682466092, 6147.0779383092895, 6181.072839074175, 5838.503727183237, 5882.5293976482635, 5886.52907767369, 5928.584999050025, 5942.1383899335215, 6143.754249830135, 6146.446819786241, 6149.508039356751, 6150.815475535741, 6151.138840562415, 5655.151727240782, 5686.34607020198, 5791.218185544897, 5792.710364481674, 5794.449527294901, 5768.192453056659, 5799.101139323411, 5806.2451881255165, 5812.041013232769, 5913.5564832636865, 5691.829245122487, 5694.174344720075, 5701.800436984686, 5711.057675483498, 5724.914126288026, 5576.553875689854, 5722.771089156554, 5726.112452569859, 5727.1695604138, 5728.0, 5561.944380556142, 5654.406287339934, 5660.745266462936, 5670.234518340137, 5788.363280039276, 5785.161151633074, 5800.4489573490455, 5803.564732644984, 5805.506531944832, 5809.302883653989, 5451.318585176853, 5454.372747134131, 5472.644278121909, 5647.0964645656495, 5653.491185793298], "value": 5364.549894311144, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.1657513729406, 584.7193347193346, 588.6732863260892, 591.0867709388993, 608.902575587906, 32055.839720801392, 41533.584664153386, 43158.152578721325, 43181.568184318145, 47376.52623473764, 44560.55439445605, 44993.325100123526, 45047.0990580189, 45065.549344506544, 45135.0, 8424.157584241557, 8777.166169213915, 8953.910460895406, 8955.910440895574, 8958.596863141174, 8502.06477287498, 10362.222833287497, 10585.364878107299, 10657.946710266438, 10941.398223097727, 10332.483375831216, 10381.0, 10388.792224155512, 10466.895331046706, 10473.581056757721, 10779.838302425469, 10828.945855270753, 10843.457827108614, 10847.132229421666, 10849.837252441219, 10549.630762923338, 10625.149988000941, 10630.681079567625, 10633.680989570325, 10636.734081647943, 42583.14833703308, 42588.148237035086, 42598.57401426008, 42681.0, 42886.14227715459, 4142.668586513072, 4204.978975105105, 4216.2199993001395, 4234.237837189305, 4269.509006464265, 31566.684333156605, 31720.36559268825, 31802.840985794923, 32036.0, 33009.83495082533, 4316.115196384733, 4375.868723938287, 4394.645945557525, 4402.3836662867225, 4483.977580112079, 313537.0, 314238.4288078568, 314854.0, 315043.0, 315095.0, 58804.41520526261, 59065.0, 59149.70425147847, 59168.70415647894, 59179.40820591825, 62573.68713156405, 62684.0, 62688.68655656784, 62749.0, 62798.68600657059, 13611.047226694234, 14005.5098071567, 14050.789238161437, 14080.366383512766, 14217.715645687029, 9719.271054670928, 9978.451185184884, 10013.148882345082, 10555.577776888991, 10627.202959778047, 17065.829341706423, 17483.737743933852, 17594.648107038043, 17676.734848977274, 17726.202320895332, 6713.731450742013, 6777.8644427111185, 6784.423324017514, 6851.0, 6937.479689023344, 899.5457294066455, 908.1191244492832, 908.8364094462928, 911.1435250864166, 917.0325306801388, 29896.850515747283, 31049.37901242008, 31068.844655776575, 31210.84394578058, 31241.375172496424, 954.4654993203828, 1038.6676263595577, 1056.4400867540216, 1057.8571892794528, 1069.9144068474504, 15240.771388429182, 16358.591035224215, 16365.754513682305, 16425.425110121327, 16531.256093475822, 537398.0, 537720.0, 543429.0, 543447.0, 543766.0, 59353.70323148443, 59614.403855961755, 59667.70166149228, 59730.0, 59812.0, 1290.7612091763053, 1320.3728229091105, 1329.0829327763813, 1341.745068436994, 1348.2449828096298, 28086.2978425541, 29559.85220073886, 29803.403931920806, 30022.549661754656, 30677.23306917345, 51583.226251607004, 52247.73876130595, 52291.0, 52374.73812630912, 52536.0, 401984.0, 403810.0, 404134.0, 404461.0, 405397.0, 34946.0, 35710.8214458926, 38212.42681359726, 40144.0, 40829.38755918723, 47566.76216618895, 47760.522394777, 47771.52228477671, 47843.52156478391, 48862.77843053952, 39029.21941561096, 39675.40486892641, 40098.59901401065, 41450.0, 42100.36849447199, 39572.40641390439, 39708.60291397165, 40154.39768403418, 41190.58809411868, 41490.0, 34337.31325373529, 35475.46786798148, 39273.0, 39916.0, 40028.0, 29880.0, 31125.221869453446, 32296.35407291794, 32332.67667323391, 32446.188845279055, 24759.009639614214, 25217.87391063033, 25614.23157305284, 25708.228753137435, 25943.74056259489], "value": 19989.874893244236, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.6977410117721, 407.36451136227487, 410.7576529847393, 414.8236999275307, 427.13931428172236, 17322.826771732278, 18419.50029494395, 18427.631447371044, 18820.435386938396, 18857.434276971697, 18887.905560472213, 19392.22431102754, 19433.611327773437, 19573.804261957375, 19581.21675132993, 5655.066913959192, 5657.603967722261, 5743.827685169452, 5780.0751879699255, 5780.306363236417, 5622.381538030813, 5705.800296989606, 5706.828795136153, 5708.229389032469, 5716.371199168109, 5528.198411230369, 5648.181013753004, 5657.9434205658035, 5675.921574900776, 5686.175504551838, 5460.781568737246, 5610.018246806813, 5639.661620302795, 5643.887122257553, 5651.095824668054, 5047.47001564834, 5089.6691715038405, 5092.8472145835685, 5095.592352611782, 5101.4133374662015, 21560.676589851166, 21614.675779863315, 21636.567268654697, 21657.891710541346, 21842.017109230124, 2626.5797472404324, 2796.8881244750187, 2828.4343131373807, 2841.2186648671573, 2850.372917958046, 10299.124574411184, 10328.173746100294, 10564.471776411148, 10579.153667706641, 10769.4615269237, 1917.5973045660428, 1947.3573720672143, 1980.8019198080221, 1987.4335814293001, 1988.7613486381726, 76005.61997190033, 76029.0, 76537.37133372987, 76568.0, 77441.61279193625, 11092.445377731081, 11205.439728013567, 11279.154063445274, 11288.717782055513, 11289.37908415045, 15402.691946161014, 15446.691066178613, 15475.613109672124, 15476.690466190614, 15484.5354639361, 7272.600006999666, 7422.146453157848, 7454.180040195574, 7617.5048621839405, 7643.044619422572, 6831.385175334244, 6858.142732158481, 7016.684249208798, 7027.8594428111155, 7028.297170282931, 4769.809207631725, 4837.346958160603, 4852.684575502652, 4898.2652602109965, 4985.4017517897355, 2000.9090500426798, 2051.6819892916624, 2082.3648787120073, 2115.323096609101, 2125.5005073807642, 818.2186012358184, 835.7952301686004, 848.9533075680897, 855.149126619018, 871.8953725552974, 16609.501714948572, 17112.57218569546, 17376.739348909778, 17911.044677628044, 18278.26886924535, 856.8200677857595, 867.0765634599131, 872.2150064941603, 875.5490922175085, 877.434055034512, 9520.767410986136, 10440.321379110334, 10453.529591168415, 10460.215483838743, 10552.525136368882, 385137.0, 389559.0, 391133.0, 392826.0, 397300.0, 84578.57710711408, 85607.57196214104, 85629.0, 87954.56022719973, 88387.0, 1044.8693913260843, 1057.386715704874, 1079.7246702090918, 1100.3287994323457, 1100.339796122336, 16561.751573726644, 16835.831641683428, 16966.560549096423, 17280.567985800455, 17313.220905059556, 39014.6098539011, 39567.40648890327, 39728.60271397365, 39780.0, 39810.800945995084, 54149.729251355064, 54161.72919135379, 54705.72647136739, 55281.447185529236, 55636.0, 8458.365622578209, 8499.57502124879, 8656.965613081047, 8672.696455624024, 8878.73363799087, 11591.420428978348, 12838.29389383593, 12964.546240881524, 13021.739565208833, 13040.673983150118, 10364.689059328231, 10980.725481863017, 11369.602063927723, 11561.710957226136, 11644.825327619923, 11240.55037798479, 11455.823393555198, 11510.42447877586, 11646.76706465849, 11840.28958262507, 11323.207375483636, 11431.142664300045, 11457.312561246019, 11682.532698691957, 11688.123390745557, 8388.832223355621, 8544.300644608571, 8629.827403452022, 8682.175193356621, 8737.169968852948, 8599.484030958163, 10501.894981050398, 11200.607978720705, 11231.831522527333, 11429.371384573928], "value": 8939.199477691529, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.3976770685483, 1342.187976274354, 1360.272254343926, 1363.0526783885198, 1365.0853927868327, 7526.849463010736, 8123.593820308983, 8397.0, 8400.537970411626, 8658.437201581908, 8432.114627964052, 8513.148685131484, 8518.36112291579, 8519.0, 8523.0, 2071.461420030795, 2137.487003119256, 2309.168699268266, 2367.621180611103, 2369.9407514812096, 2342.9882850585723, 2411.3489357873364, 2427.1141033522727, 2432.20953190213, 2441.4872876695913, 2326.7905888470036, 2340.7659234076627, 2345.906163753448, 2350.5063936573342, 2356.575816353056, 2313.548857972696, 2313.8727369994645, 2333.4166458385403, 2333.871637059961, 2334.3813889319345, 2258.4128126687015, 2331.4521087544413, 2350.435895385095, 2355.422921384272, 2355.9764402355927, 8533.487990720576, 8593.871091933563, 8609.26821220197, 8610.741677749678, 8621.482711037295, 5150.459201783834, 5334.626576139672, 5337.06601344767, 5339.119045357511, 5339.6262261641705, 3314.5359649649067, 3326.916827079318, 3575.928481430383, 3577.5349204603235, 3578.320119177374, 1413.4487549855462, 1425.2232533269394, 1438.5684294711546, 1448.4423496953643, 1556.571942715751, 11617.593384231595, 11628.709282267926, 11650.766984660344, 11652.650420487398, 11655.30068195911, 4659.627229821606, 4690.788914498856, 4714.905701886011, 4734.621230301567, 4763.356946812205, 9873.950630246803, 9912.950435247778, 9972.700818975442, 9989.700308990741, 9996.900030999743, 5293.391260005072, 5293.788248470095, 5299.33758280215, 5300.1519756838725, 5303.575713942875, 4790.329353890458, 4827.710337379768, 4845.200541910545, 4876.902461950812, 4877.853664390074, 3809.980950095232, 3936.566977632458, 3990.8004599769897, 4088.407180958804, 4192.0, 1321.9206847589176, 1325.1717676452217, 1329.6609364612154, 1340.477213886585, 1350.9797353039712, 870.8998465176586, 891.0530490877325, 891.8573028315438, 893.4594570285014, 914.5564401265381, 5437.782488700408, 5600.299962504687, 5677.120046392815, 5696.316442026983, 5699.5155411790465, 562.3532937122342, 567.4580775359522, 572.1103683771692, 574.4399210769467, 578.9247397838254, 3792.5069740934055, 3857.4213867920034, 3862.3820188769664, 3899.200663863901, 3904.9847039769297, 9878.604855805686, 10337.638182663572, 10380.896191038144, 10418.583256669799, 10447.895521044844, 10274.589016439408, 10293.794124117476, 10300.484975751333, 10312.587496500206, 10348.172146228284, 7512.887306690404, 7839.725609603636, 7860.842783144305, 7961.72133975308, 7987.880181797278, 5801.6519008859605, 5805.477507024388, 5898.1152827076285, 5899.351071382059, 6013.428724271187, 9214.170724634814, 9305.627774888928, 9319.720408387759, 9405.717828465156, 9438.764030899009, 9687.370320929118, 9698.0, 9731.172850307803, 9785.21718262523, 9843.212542996685, 7397.149327827154, 7483.625818708934, 7516.549007059594, 7528.849423011399, 7573.583452910143, 8064.516129032277, 8212.712555060543, 8213.425060245725, 8216.0, 8236.135205803304, 7342.853142937218, 7445.10658720957, 7464.290892365217, 7523.473356864967, 7595.658195381111, 6997.440204783503, 7045.682944267418, 7119.786406407816, 7281.854362912818, 7325.560466372035, 6483.837904052436, 6650.933490665031, 6652.035454859115, 6656.001599759881, 6674.299198584079, 4921.311016457627, 4932.988737308771, 5028.069807085701, 5081.0854046272025, 5151.484851514819, 4960.30555722192, 5029.723365214948, 5120.078385890575, 5177.585793136615, 5190.870228244324], "value": 4492.234777641736, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.52684144818977, 389.604551380349, 399.39292275740877, 400.4453831443451, 403.7000857239689, 5058.4435712071645, 5162.406323272825, 5195.272661827347, 5197.922031169535, 5311.442298558653, 4989.850304490871, 5168.2764412982215, 5174.309359133248, 5223.0, 5228.529432351088, 4061.532923713766, 4076.4700588923547, 4077.4903137107863, 4080.1431699343175, 4093.2836753568154, 3907.570167281611, 3952.2688302663955, 4011.0, 4050.2709512287774, 4088.0597462583623, 4016.4577781999496, 4027.97986010071, 4032.5564187939303, 4038.8586399476026, 4045.7167998240134, 4030.3954406838916, 4046.150308435232, 4052.7365721228025, 4055.655269302113, 4062.9796851015853, 3421.81180035098, 3603.837827297778, 3605.927881442383, 3608.332458495187, 3618.3306088373733, 5035.59715222785, 5065.721385323805, 5068.239764035387, 5075.365579302587, 5128.2564028216075, 4674.579287864109, 4676.649251306166, 4683.227267500858, 4685.906281874378, 4694.554017368345, 5204.557612602934, 5449.809256676037, 5461.836144915659, 5462.317210348707, 5489.862753431156, 3919.588947978754, 3962.643362097396, 3966.107625784204, 3971.4837071180564, 3972.682185425159, 5653.434656534355, 5659.87754592028, 5665.716714164317, 5705.800296989585, 5711.40030296817, 4036.798160091984, 4064.3903414488063, 4067.0, 4069.819752271808, 4079.633322836883, 3924.7056470764805, 3937.7046721496004, 4101.712880098365, 4107.137501124737, 4147.54835807471, 4462.48681401643, 4595.241785105487, 4598.195315819718, 4604.516525764747, 4626.352310676508, 5456.48163424474, 5462.808801692001, 5476.26070480488, 5486.3690675572025, 5488.490665067138, 1352.4657760184673, 1363.8772510474105, 1369.6233535777542, 1369.8561651026698, 1378.710470801123, 959.9808003839884, 965.7344230336713, 967.709687093869, 975.0785507695301, 981.6269817469316, 5003.949960500348, 5043.798248070036, 5067.417247016567, 5103.948960510422, 5149.948500514947, 5395.595330350239, 5404.216388623706, 5422.102264207496, 5458.208559758892, 5479.178123281539, 4986.7257300848805, 5083.542481176712, 5117.590592752571, 5119.718415487109, 5130.871728206825, 2876.453473839964, 2877.2806798300426, 2904.941901161965, 2905.3149173479333, 2907.8400687961944, 5480.561555075585, 5612.410696876851, 5634.3802181759975, 5648.322201335866, 5651.208830763697, 5444.782208711607, 5444.972775136099, 5465.4261302563455, 5478.205660179252, 5536.972315138479, 5532.5297349724915, 5577.470140336662, 5645.153227015899, 5660.801871934463, 5679.261695979496, 5262.921056184084, 5302.946970530245, 5319.547838433777, 5342.919856202082, 5422.8850748209015, 5284.867878303073, 5290.841274761763, 5292.9735351323, 5301.973490132525, 5309.415964243853, 5294.417614062527, 5565.608597850537, 5586.916196256978, 5634.774609015593, 5654.264945557051, 3914.5106861642294, 3958.2083583284034, 3966.821493032878, 3979.582143874851, 3983.9044262827956, 5236.2720302299385, 5367.785288588413, 5419.566434685295, 5467.58993075513, 5536.169574563848, 5434.184872269191, 5439.292891924025, 5458.044842152528, 5482.834280459117, 5495.368032676293, 5439.0, 5446.809361672323, 5478.671279723229, 5482.725863706878, 5491.7254137292175, 5235.712035837913, 5399.244105825109, 5408.54027407659, 5417.864553386197, 5434.64674796137, 5478.479544443271, 5482.533984611353, 5506.476884695948, 5508.724563771715, 5518.696471693935, 5224.660397074178, 5373.9731301343245, 5374.404883824274, 5384.946150538444, 5390.595705322038], "value": 4148.957155801054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.4074011161126, 761.7105499910036, 779.8791187365956, 802.1176705623815, 851.7061613743258, 13638.181709097462, 13932.0, 14085.718285634293, 16169.272382742776, 16199.433019844279, 15277.6944461111, 15367.15480648564, 15398.0, 15475.84524154758, 15946.681066378695, 811.8457493076297, 834.9415540912139, 835.1189495082685, 835.8829763833069, 836.1178956201205, 710.594960872303, 731.9158296795883, 804.5655346113092, 840.0633293877313, 841.3017195727532, 873.6680061576592, 884.4074470105013, 884.8230353929208, 885.0662551008684, 885.2873436883307, 875.3128793896792, 875.9693410730625, 876.6537217798998, 876.8640860666607, 880.3353468131584, 542.1542393865583, 556.9637973531708, 614.858582526017, 632.7627139822566, 645.5610185074148, 16152.919235403866, 16224.432144874872, 16277.837221627751, 16287.755683664755, 16299.429519966745, 13001.284929328884, 13018.283994380305, 13189.670258243525, 13285.202887826665, 13338.160609427028, 12683.619491415273, 12731.93634031824, 12798.0, 13059.542915997894, 13227.470901163942, 4438.889027774299, 4468.285074388083, 4478.865634030984, 4595.954040459619, 4609.884752881171, 77515.61242193753, 77942.61028694877, 77992.61003695002, 78722.60638696827, 81521.59239203767, 11120.165987550967, 11160.218784685156, 11179.0, 11198.776024479464, 11415.657530274106, 20114.39656810298, 20168.78987260769, 20550.17799288012, 20812.0, 20817.271395501084, 7664.34853037498, 7682.231776822385, 7736.032995875516, 7755.457118001686, 7890.842183156305, 4366.104948485553, 4493.910121797546, 4507.909841803145, 4527.320901864681, 4631.282151266559, 3431.1422144463886, 3522.964770352315, 3527.2592755521628, 3530.029241958482, 3544.7341449390883, 647.6179054357951, 657.2572992518519, 663.4128796015465, 670.276101810044, 672.8553361027402, 730.791724358566, 733.4352548537703, 747.4415842967474, 752.3717695724132, 755.9130699969464, 9114.36199466031, 9475.804451147498, 9570.0, 9765.560549775277, 9996.700098997042, 691.8288863339028, 697.3584302441766, 709.9680514376865, 715.2561336210387, 723.020307483363, 4287.378330142112, 4449.488308844459, 4502.3246513023305, 4555.794989225493, 4563.269876819693, 62198.378016220166, 64423.29706646515, 65021.34978650153, 73379.26620733725, 75912.24087759163, 64774.352256477774, 65465.0, 65534.67232663902, 66981.0, 69644.6517767408, 58441.707791461624, 58517.122243166385, 59325.0, 60510.39489605048, 60562.39437605567, 8914.0, 9350.438973661601, 9451.621935122517, 9468.905310946802, 9481.857772133286, 39787.0, 39867.60132398639, 40586.376544939056, 41496.58503414927, 41735.79132104442, 50061.0, 50120.74939625279, 50893.0, 50919.236211457595, 51063.0, 12889.871101288867, 13088.869111308764, 13176.868231317565, 13271.469141234242, 13434.261115638734, 13858.584242472742, 13901.510255619847, 13919.930400347934, 14052.0, 14145.787813183017, 13621.931890340486, 13768.007277889299, 13813.240271784747, 14010.159390436607, 14061.296935152997, 13631.659208519866, 13803.792943106062, 13820.447182113005, 13844.30778461093, 14806.768413412128, 13774.86225137776, 14172.787408188678, 14258.429662813785, 15036.398544058115, 15260.923695381896, 11116.888831111586, 11370.772584548096, 11456.427178641201, 12092.153549251469, 12302.077344199042, 11812.881871181522, 11866.703332416413, 12053.337066461429, 12175.269483830998, 12468.0], "value": 7232.708364694348, "unit": "score"}], "name": "dromaeo_css", "value": 7110.471841524616, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 110.0, 110.0, 110.0, 109.0, 108.0, 111.0, 109.0, 109.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 119.0, 121.0, 159.0, 122.0, 119.0, 123.0, 117.0, 146.0, 120.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 160.0, 163.0, 161.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 100.0, 76.0, 77.0, 77.0, 76.0, 92.0, 76.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 119.0, 83.0, 84.0, 83.0, 95.0, 83.0, 84.0, 110.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 53.0, 49.0, 51.0, 50.0, 50.0, 52.0, 51.0, 52.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 69.0, 66.0, 66.0, 67.0, 66.0, 67.0, 65.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 88.0, 81.0, 91.0, 81.0, 81.0, 82.0, 83.0, 90.0, 81.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 125.0, 133.0, 133.0, 129.0, 127.0, 127.0, 164.0, 135.0], "value": 132.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 165.0, 163.0, 166.0, 164.0, 163.0, 164.0, 166.0, 159.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 61.0, 63.0, 61.0, 61.0, 63.0, 63.0, 61.0, 65.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1489.8999999999999, "alertThreshold": 2.0}]} 09:36:18 INFO - Return code: 0 09:36:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:18 INFO - # TBPL SUCCESS # 09:36:18 INFO - Running post-action listener: _resource_record_post_action 09:36:18 INFO - [mozharness: 2016-12-30 17:36:18.207499Z] Finished run-tests step (success) 09:36:18 INFO - Running post-run listener: _resource_record_post_run 09:36:18 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406241280; Read time: 8; Write time: 357120 09:36:18 INFO - TinderboxPrint: CPU usage
13.1% 09:36:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:36:18 INFO - TinderboxPrint: I/O write bytes / time
406,241,280 / 357,120 09:36:18 INFO - TinderboxPrint: CPU idle
8,288.3 (86.9%) 09:36:18 INFO - TinderboxPrint: CPU user
1,200.8 (12.6%) 09:36:18 INFO - TinderboxPrint: Swap in / out
0 / 0 09:36:18 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 111788032; Read time: 0; Write time: 115436 09:36:18 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 294027264; Read time: 0; Write time: 241604 09:36:18 INFO - Running post-run listener: _upload_blobber_files 09:36:18 INFO - Blob upload gear active. 09:36:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:18 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 09:36:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:36:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:36:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:20 INFO - (blobuploader) - INFO - Done attempting. 09:36:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:36:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:36:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:21 INFO - (blobuploader) - INFO - Done attempting. 09:36:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:21 INFO - (blobuploader) - INFO - Done attempting. 09:36:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:24 INFO - (blobuploader) - INFO - Done attempting. 09:36:24 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:24 INFO - Return code: 0 09:36:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c1875bcdd3875283583b84a0350f6b65c6b4f6241dd4e4946449e11785cb668c16adce51c67bee30f28fa2f2c94f305519a93c333d2b98ec58d61b3cf72abad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15ab589b4dd4785b8e7de9737459d28eaa355675612bc632687e09433c17ad0cae6d8dc09d7007672949d52a7cf22da04d1e6f31cabf7c134e81061bce4c9731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23a2eb8ab8183af320e94c4db6ab2f31f294ccfb09402e4a34f93908f094cd9641a4a483ffe61103c18aec3d07cb00b73f68de2950610171a19f916ea524abe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61c2b7d59296457af10da0fc54fe2d0c019c19cf8560a24b34f5ad2df1e3a2222611ffddcf70cdd94916bde966c6d18edd94497f6e34c52b455ea25e41200c7a"} 09:36:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:24 INFO - Contents: 09:36:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c1875bcdd3875283583b84a0350f6b65c6b4f6241dd4e4946449e11785cb668c16adce51c67bee30f28fa2f2c94f305519a93c333d2b98ec58d61b3cf72abad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15ab589b4dd4785b8e7de9737459d28eaa355675612bc632687e09433c17ad0cae6d8dc09d7007672949d52a7cf22da04d1e6f31cabf7c134e81061bce4c9731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23a2eb8ab8183af320e94c4db6ab2f31f294ccfb09402e4a34f93908f094cd9641a4a483ffe61103c18aec3d07cb00b73f68de2950610171a19f916ea524abe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61c2b7d59296457af10da0fc54fe2d0c019c19cf8560a24b34f5ad2df1e3a2222611ffddcf70cdd94916bde966c6d18edd94497f6e34c52b455ea25e41200c7a"} 09:36:24 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:24 INFO - Copying logs to upload dir... 09:36:24 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.018051 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-30 09:36:24.552009) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 09:36:24.555797) ========= 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=7de08804244eb36560b44971000001c3-1483118143.403690-120424550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c1875bcdd3875283583b84a0350f6b65c6b4f6241dd4e4946449e11785cb668c16adce51c67bee30f28fa2f2c94f305519a93c333d2b98ec58d61b3cf72abad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15ab589b4dd4785b8e7de9737459d28eaa355675612bc632687e09433c17ad0cae6d8dc09d7007672949d52a7cf22da04d1e6f31cabf7c134e81061bce4c9731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23a2eb8ab8183af320e94c4db6ab2f31f294ccfb09402e4a34f93908f094cd9641a4a483ffe61103c18aec3d07cb00b73f68de2950610171a19f916ea524abe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61c2b7d59296457af10da0fc54fe2d0c019c19cf8560a24b34f5ad2df1e3a2222611ffddcf70cdd94916bde966c6d18edd94497f6e34c52b455ea25e41200c7a"} build_url:https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011369 build_url: 'https://queue.taskcluster.net/v1/task/IwtxFyvETtqqa9CCxGjHDw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c1875bcdd3875283583b84a0350f6b65c6b4f6241dd4e4946449e11785cb668c16adce51c67bee30f28fa2f2c94f305519a93c333d2b98ec58d61b3cf72abad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15ab589b4dd4785b8e7de9737459d28eaa355675612bc632687e09433c17ad0cae6d8dc09d7007672949d52a7cf22da04d1e6f31cabf7c134e81061bce4c9731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23a2eb8ab8183af320e94c4db6ab2f31f294ccfb09402e4a34f93908f094cd9641a4a483ffe61103c18aec3d07cb00b73f68de2950610171a19f916ea524abe2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61c2b7d59296457af10da0fc54fe2d0c019c19cf8560a24b34f5ad2df1e3a2222611ffddcf70cdd94916bde966c6d18edd94497f6e34c52b455ea25e41200c7a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 09:36:24.581668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:36:24.581980) ========= 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=7de08804244eb36560b44971000001c3-1483118143.403690-120424550 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:36:24.664207) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 09:36:24.664532) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 09:36:24.664812) ========= ========= Total master_lag: 0.17 =========