builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1483754226.5 results: success (0) buildid: 20170106160555 builduid: 687de184391647a0a6544e3dab2bcbd6 revision: 71dcbe4ec7a105ac93e288d68e48c2f0824751c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.501944) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.502310) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.502627) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.518104) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.518372) ========= 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=920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-06 17:57:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-01-06 17:57:07 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114309 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.645648) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.645923) ========= 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=920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021791 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:06.694845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-06 17:57:06.695144) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 71dcbe4ec7a105ac93e288d68e48c2f0824751c7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 71dcbe4ec7a105ac93e288d68e48c2f0824751c7 --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=920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-06 17:57:07,437 truncating revision to first 12 chars 2017-01-06 17:57:07,437 Setting DEBUG logging. 2017-01-06 17:57:07,437 attempt 1/10 2017-01-06 17:57:07,437 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/71dcbe4ec7a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 17:57:08,107 unpacking tar archive at: mozilla-beta-71dcbe4ec7a1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.033717 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-06 17:57:07.741844) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:07.742148) ========= script_repo_revision: 71dcbe4ec7a105ac93e288d68e48c2f0824751c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:07.742553) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:07.742832) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 17:57:07.751042) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-01-06 17:57:07.751323) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:57:08 INFO - MultiFileLogger online at 20170106 17:57:08 in /builds/slave/test 17:57:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:57:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:57:08 INFO - {'append_to_log': False, 17:57:08 INFO - 'base_work_dir': '/builds/slave/test', 17:57:08 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:57:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:57:08 INFO - 'branch': 'Mozilla-Beta', 17:57:08 INFO - 'buildbot_json_path': 'buildprops.json', 17:57:08 INFO - 'config_files': ('talos/linux_config.py',), 17:57:08 INFO - 'default_actions': ('clobber', 17:57:08 INFO - 'read-buildbot-config', 17:57:08 INFO - 'download-and-extract', 17:57:08 INFO - 'populate-webroot', 17:57:08 INFO - 'create-virtualenv', 17:57:08 INFO - 'install', 17:57:08 INFO - 'run-tests'), 17:57:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:57:08 INFO - 'download_minidump_stackwalk': True, 17:57:08 INFO - 'download_symbols': 'ondemand', 17:57:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:57:08 INFO - 'tooltool.py': '/tools/tooltool.py', 17:57:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:57:08 INFO - '/tools/misc-python/virtualenv.py')}, 17:57:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:57:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:57:08 INFO - 'installer_path': 'installer.exe', 17:57:08 INFO - 'log_level': 'info', 17:57:08 INFO - 'log_name': 'talos', 17:57:08 INFO - 'log_to_console': True, 17:57:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:57:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:57:08 INFO - 'opt_config_files': (), 17:57:08 INFO - 'pip_index': False, 17:57:08 INFO - 'sps_profile': False, 17:57:08 INFO - 'sps_profile_interval': 0, 17:57:08 INFO - 'suite': 'dromaeojs', 17:57:08 INFO - 'system_bits': '32', 17:57:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:57:08 INFO - 'title': 'talos-linux64-ix-053', 17:57:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:57:08 INFO - 'use_talos_json': True, 17:57:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:57:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:57:08 INFO - 'work_dir': 'build'} 17:57:08 INFO - [mozharness: 2017-01-07 01:57:08.583810Z] Running clobber step. 17:57:08 INFO - Running pre-action listener: _resource_record_pre_action 17:57:08 INFO - Running main action method: clobber 17:57:08 INFO - rmtree: /builds/slave/test/build 17:57:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:57:09 INFO - Running post-action listener: _resource_record_post_action 17:57:09 INFO - [mozharness: 2017-01-07 01:57:09.570533Z] Finished clobber step (success) 17:57:09 INFO - [mozharness: 2017-01-07 01:57:09.570632Z] Running read-buildbot-config step. 17:57:09 INFO - Running pre-action listener: _resource_record_pre_action 17:57:09 INFO - Running main action method: read_buildbot_config 17:57:09 INFO - Using buildbot properties: 17:57:09 INFO - { 17:57:09 INFO - "project": "", 17:57:09 INFO - "product": "firefox", 17:57:09 INFO - "script_repo_revision": "production", 17:57:09 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:57:09 INFO - "repository": "", 17:57:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:57:09 INFO - "buildid": "20170106160555", 17:57:09 INFO - "pgo_build": "True", 17:57:09 INFO - "basedir": "/builds/slave/test", 17:57:09 INFO - "buildnumber": 508, 17:57:09 INFO - "slavename": "talos-linux64-ix-053", 17:57:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:57:09 INFO - "platform": "ubuntu64_hw", 17:57:09 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:57:09 INFO - "branch": "mozilla-beta", 17:57:09 INFO - "repo_path": "releases/mozilla-beta", 17:57:09 INFO - "revision": "71dcbe4ec7a105ac93e288d68e48c2f0824751c7", 17:57:09 INFO - "stage_platform": "linux64-pgo", 17:57:09 INFO - "builduid": "687de184391647a0a6544e3dab2bcbd6", 17:57:09 INFO - "slavebuilddir": "test" 17:57:09 INFO - } 17:57:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 17:57:09 INFO - Running post-action listener: _resource_record_post_action 17:57:09 INFO - [mozharness: 2017-01-07 01:57:09.573067Z] Finished read-buildbot-config step (success) 17:57:09 INFO - [mozharness: 2017-01-07 01:57:09.573157Z] Running download-and-extract step. 17:57:09 INFO - Running pre-action listener: _resource_record_pre_action 17:57:09 INFO - Running main action method: download_and_extract 17:57:09 INFO - mkdir: /builds/slave/test/build/tests 17:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:09 INFO - trying https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 17:57:09 INFO - Downloading https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 17:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:57:10 INFO - Downloaded 1327 bytes. 17:57:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 17:57:10 INFO - Using the following test package requirements: 17:57:10 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 17:57:10 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:57:10 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 17:57:10 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'jsshell-linux-x86_64.zip'], 17:57:10 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:57:10 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 17:57:10 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 17:57:10 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 17:57:10 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:57:10 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 17:57:10 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:57:10 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:57:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 17:57:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:57:10 INFO - Fetch https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 17:57:10 INFO - Expected file size: 21969736 17:57:15 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:57:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 17:57:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:57:15 INFO - Fetch https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 17:57:15 INFO - Expected file size: 21969736 17:57:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 17:57:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:57:20 INFO - Fetch https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 17:57:20 INFO - Expected file size: 14414089 17:57:21 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')]} 17:57:21 INFO - trying https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 17:57:21 INFO - Downloading https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 17:57:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:57:22 INFO - Downloaded 56994056 bytes. 17:57:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 17:57:22 INFO - mkdir: /builds/slave/test/properties 17:57:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:57:22 INFO - Writing to file /builds/slave/test/properties/build_url 17:57:22 INFO - Contents: 17:57:22 INFO - build_url:https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 17:57:23 INFO - Running post-action listener: _resource_record_post_action 17:57:23 INFO - Running post-action listener: set_extra_try_arguments 17:57:23 INFO - [mozharness: 2017-01-07 01:57:23.368075Z] Finished download-and-extract step (success) 17:57:23 INFO - [mozharness: 2017-01-07 01:57:23.368301Z] Running populate-webroot step. 17:57:23 INFO - Running pre-action listener: _resource_record_pre_action 17:57:23 INFO - Running main action method: populate_webroot 17:57:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:57:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:57:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:57:23 INFO - '--tppagecycles', 17:57:23 INFO - '7'], 17:57:23 INFO - 'tests': ['tsvgm']}}, 17:57:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:57:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:57:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:57:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:57:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['damp', 'tps']}, 17:57:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'tests': ['damp', 'tps']}, 17:57:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['dromaeo_dom']}, 17:57:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:57:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['basic_compositor_video']}, 17:57:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:57:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other-e10s': {'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tsvgx', 17:57:23 INFO - 'tsvgr_opacity', 17:57:23 INFO - 'tart', 17:57:23 INFO - 'tscrollx', 17:57:23 INFO - 'cart']}, 17:57:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:57:23 INFO - 'tsvgr_opacity', 17:57:23 INFO - 'tart', 17:57:23 INFO - 'tscrollx', 17:57:23 INFO - 'cart']}, 17:57:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tp5o']}, 17:57:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'tests': ['tp5o']}, 17:57:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s', 17:57:23 INFO - '--xperf_path', 17:57:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:23 INFO - 'tests': ['tp5n']}, 17:57:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:23 INFO - 'talos_options': ['--xperf_path', 17:57:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:23 INFO - 'tests': ['tp5n']}}, 17:57:23 INFO - 'talos.zip': {'path': '', 17:57:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:57:23 INFO - Running post-action listener: _resource_record_post_action 17:57:23 INFO - [mozharness: 2017-01-07 01:57:23.386966Z] Finished populate-webroot step (success) 17:57:23 INFO - [mozharness: 2017-01-07 01:57:23.387062Z] Running create-virtualenv step. 17:57:23 INFO - Running pre-action listener: _resource_record_pre_action 17:57:23 INFO - Running main action method: create_virtualenv 17:57:23 INFO - Creating virtualenv /builds/slave/test/build/venv 17:57:23 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 17:57:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:57:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:57:23 INFO - Using real prefix '/usr' 17:57:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:57:23 INFO - Installing distribute.............................................................................................................................................................................................done. 17:57:25 INFO - Installing pip.................done. 17:57:25 INFO - Return code: 0 17:57:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:57:25 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')]} 17:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:25 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')]} 17:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:25 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 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:25 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 17:57:25 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 17:57:25 INFO - Using env: {'DISPLAY': ':0', 17:57:25 INFO - 'HOME': '/home/cltbld', 17:57:25 INFO - 'LANG': 'en_US.UTF-8', 17:57:25 INFO - 'LANGUAGE': 'en_US:en', 17:57:25 INFO - 'LOGNAME': 'cltbld', 17:57:25 INFO - 'MAIL': '/var/mail/cltbld', 17:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:25 INFO - 'MOZ_NO_REMOTE': '1', 17:57:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:25 INFO - 'NO_EM_RESTART': '1', 17:57:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:25 INFO - 'PWD': '/builds/slave/test', 17:57:25 INFO - 'SHELL': '/bin/bash', 17:57:25 INFO - 'SHLVL': '1', 17:57:25 INFO - 'TERM': 'linux', 17:57:25 INFO - 'TMOUT': '86400', 17:57:25 INFO - 'USER': 'cltbld', 17:57:25 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:25 INFO - '_': '/tools/buildbot/bin/python'} 17:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:57:26 INFO - Cleaning up... 17:57:26 INFO - Return code: 0 17:57:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:57:26 INFO - Using env: {'DISPLAY': ':0', 17:57:26 INFO - 'HOME': '/home/cltbld', 17:57:26 INFO - 'LANG': 'en_US.UTF-8', 17:57:26 INFO - 'LANGUAGE': 'en_US:en', 17:57:26 INFO - 'LOGNAME': 'cltbld', 17:57:26 INFO - 'MAIL': '/var/mail/cltbld', 17:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:26 INFO - 'MOZ_NO_REMOTE': '1', 17:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:26 INFO - 'NO_EM_RESTART': '1', 17:57:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:26 INFO - 'PWD': '/builds/slave/test', 17:57:26 INFO - 'SHELL': '/bin/bash', 17:57:26 INFO - 'SHLVL': '1', 17:57:26 INFO - 'TERM': 'linux', 17:57:26 INFO - 'TMOUT': '86400', 17:57:26 INFO - 'USER': 'cltbld', 17:57:26 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:26 INFO - '_': '/tools/buildbot/bin/python'} 17:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:26 INFO - Downloading/unpacking psutil>=3.1.1 17:57:26 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 17:57:26 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 17:57:26 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 17:57:26 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 17:57:26 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 17:57:26 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 17:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:57:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:28 INFO - Installing collected packages: psutil 17:57:28 INFO - Running setup.py install for psutil 17:57:28 INFO - building 'psutil._psutil_linux' extension 17:57:28 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 17:57:28 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 17:57:28 INFO - building 'psutil._psutil_posix' extension 17:57:28 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 17:57:28 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 17:57:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:28 INFO - Successfully installed psutil 17:57:28 INFO - Cleaning up... 17:57:29 INFO - Return code: 0 17:57:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:57:29 INFO - Using env: {'DISPLAY': ':0', 17:57:29 INFO - 'HOME': '/home/cltbld', 17:57:29 INFO - 'LANG': 'en_US.UTF-8', 17:57:29 INFO - 'LANGUAGE': 'en_US:en', 17:57:29 INFO - 'LOGNAME': 'cltbld', 17:57:29 INFO - 'MAIL': '/var/mail/cltbld', 17:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:29 INFO - 'MOZ_NO_REMOTE': '1', 17:57:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:29 INFO - 'NO_EM_RESTART': '1', 17:57:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:29 INFO - 'PWD': '/builds/slave/test', 17:57:29 INFO - 'SHELL': '/bin/bash', 17:57:29 INFO - 'SHLVL': '1', 17:57:29 INFO - 'TERM': 'linux', 17:57:29 INFO - 'TMOUT': '86400', 17:57:29 INFO - 'USER': 'cltbld', 17:57:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:29 INFO - '_': '/tools/buildbot/bin/python'} 17:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:57:31 INFO - Installing collected packages: mozsystemmonitor 17:57:31 INFO - Running setup.py install for mozsystemmonitor 17:57:31 INFO - Successfully installed mozsystemmonitor 17:57:31 INFO - Cleaning up... 17:57:31 INFO - Return code: 0 17:57:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:57:31 INFO - Using env: {'DISPLAY': ':0', 17:57:31 INFO - 'HOME': '/home/cltbld', 17:57:31 INFO - 'LANG': 'en_US.UTF-8', 17:57:31 INFO - 'LANGUAGE': 'en_US:en', 17:57:31 INFO - 'LOGNAME': 'cltbld', 17:57:31 INFO - 'MAIL': '/var/mail/cltbld', 17:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:31 INFO - 'MOZ_NO_REMOTE': '1', 17:57:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:31 INFO - 'NO_EM_RESTART': '1', 17:57:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:31 INFO - 'PWD': '/builds/slave/test', 17:57:31 INFO - 'SHELL': '/bin/bash', 17:57:31 INFO - 'SHLVL': '1', 17:57:31 INFO - 'TERM': 'linux', 17:57:31 INFO - 'TMOUT': '86400', 17:57:31 INFO - 'USER': 'cltbld', 17:57:31 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:31 INFO - '_': '/tools/buildbot/bin/python'} 17:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:31 INFO - Downloading/unpacking jsonschema==2.5.1 17:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:57:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:33 INFO - Downloading functools32-3.2.3-2.tar.gz 17:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:57:33 INFO - warning: no files found matching '*.txt' 17:57:33 INFO - no previously-included directories found matching 'build' 17:57:33 INFO - no previously-included directories found matching 'dist' 17:57:33 INFO - no previously-included directories found matching '.git*' 17:57:33 INFO - Installing collected packages: jsonschema, functools32 17:57:34 INFO - Running setup.py install for functools32 17:57:34 INFO - warning: no files found matching '*.txt' 17:57:34 INFO - no previously-included directories found matching 'build' 17:57:34 INFO - no previously-included directories found matching 'dist' 17:57:34 INFO - no previously-included directories found matching '.git*' 17:57:34 INFO - Successfully installed jsonschema functools32 17:57:34 INFO - Cleaning up... 17:57:34 INFO - Return code: 0 17:57:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:57:34 INFO - Using env: {'DISPLAY': ':0', 17:57:34 INFO - 'HOME': '/home/cltbld', 17:57:34 INFO - 'LANG': 'en_US.UTF-8', 17:57:34 INFO - 'LANGUAGE': 'en_US:en', 17:57:34 INFO - 'LOGNAME': 'cltbld', 17:57:34 INFO - 'MAIL': '/var/mail/cltbld', 17:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:34 INFO - 'MOZ_NO_REMOTE': '1', 17:57:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:34 INFO - 'NO_EM_RESTART': '1', 17:57:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:34 INFO - 'PWD': '/builds/slave/test', 17:57:34 INFO - 'SHELL': '/bin/bash', 17:57:34 INFO - 'SHLVL': '1', 17:57:34 INFO - 'TERM': 'linux', 17:57:34 INFO - 'TMOUT': '86400', 17:57:34 INFO - 'USER': 'cltbld', 17:57:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:34 INFO - '_': '/tools/buildbot/bin/python'} 17:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:57:34 INFO - Cleaning up... 17:57:34 INFO - Return code: 0 17:57:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:57:34 INFO - Using env: {'DISPLAY': ':0', 17:57:34 INFO - 'HOME': '/home/cltbld', 17:57:34 INFO - 'LANG': 'en_US.UTF-8', 17:57:34 INFO - 'LANGUAGE': 'en_US:en', 17:57:34 INFO - 'LOGNAME': 'cltbld', 17:57:34 INFO - 'MAIL': '/var/mail/cltbld', 17:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:34 INFO - 'MOZ_NO_REMOTE': '1', 17:57:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:34 INFO - 'NO_EM_RESTART': '1', 17:57:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:34 INFO - 'PWD': '/builds/slave/test', 17:57:34 INFO - 'SHELL': '/bin/bash', 17:57:34 INFO - 'SHLVL': '1', 17:57:34 INFO - 'TERM': 'linux', 17:57:34 INFO - 'TMOUT': '86400', 17:57:34 INFO - 'USER': 'cltbld', 17:57:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:34 INFO - '_': '/tools/buildbot/bin/python'} 17:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:34 INFO - Downloading/unpacking blobuploader==1.2.4 17:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:36 INFO - Downloading blobuploader-1.2.4.tar.gz 17:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:57:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:57:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:37 INFO - Downloading docopt-0.6.1.tar.gz 17:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:57:37 INFO - Installing collected packages: blobuploader, requests, docopt 17:57:37 INFO - Running setup.py install for blobuploader 17:57:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:57:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:57:37 INFO - Running setup.py install for requests 17:57:37 INFO - Running setup.py install for docopt 17:57:37 INFO - Successfully installed blobuploader requests docopt 17:57:37 INFO - Cleaning up... 17:57:38 INFO - Return code: 0 17:57:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:57:38 INFO - Using env: {'DISPLAY': ':0', 17:57:38 INFO - 'HOME': '/home/cltbld', 17:57:38 INFO - 'LANG': 'en_US.UTF-8', 17:57:38 INFO - 'LANGUAGE': 'en_US:en', 17:57:38 INFO - 'LOGNAME': 'cltbld', 17:57:38 INFO - 'MAIL': '/var/mail/cltbld', 17:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:38 INFO - 'MOZ_NO_REMOTE': '1', 17:57:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:38 INFO - 'NO_EM_RESTART': '1', 17:57:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:38 INFO - 'PWD': '/builds/slave/test', 17:57:38 INFO - 'SHELL': '/bin/bash', 17:57:38 INFO - 'SHLVL': '1', 17:57:38 INFO - 'TERM': 'linux', 17:57:38 INFO - 'TMOUT': '86400', 17:57:38 INFO - 'USER': 'cltbld', 17:57:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:38 INFO - '_': '/tools/buildbot/bin/python'} 17:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:38 INFO - Running setup.py (path:/tmp/pip-OW0Fp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:38 INFO - Running setup.py (path:/tmp/pip-4z02bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:38 INFO - Running setup.py (path:/tmp/pip-kRwHpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:38 INFO - Running setup.py (path:/tmp/pip-A5iBub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:38 INFO - Running setup.py (path:/tmp/pip-wRj_RI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:38 INFO - Running setup.py (path:/tmp/pip-UIv3Ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:38 INFO - Running setup.py (path:/tmp/pip-zz1W2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:38 INFO - Running setup.py (path:/tmp/pip-zEqu30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:38 INFO - Running setup.py (path:/tmp/pip-2eKz9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:38 INFO - Running setup.py (path:/tmp/pip-Z6gmwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:38 INFO - Running setup.py (path:/tmp/pip-IQNwJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:39 INFO - Running setup.py (path:/tmp/pip-_QjTj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:39 INFO - Running setup.py (path:/tmp/pip-K5KnYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:39 INFO - Running setup.py (path:/tmp/pip-dfBWaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:39 INFO - Running setup.py (path:/tmp/pip-P8FXGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:39 INFO - Running setup.py (path:/tmp/pip-lz2goY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:39 INFO - Running setup.py (path:/tmp/pip-CTxbJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:57:39 INFO - Running setup.py install for manifestparser 17:57:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:57:39 INFO - Running setup.py install for mozcrash 17:57:39 INFO - Running setup.py install for mozdebug 17:57:39 INFO - Running setup.py install for mozdevice 17:57:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:57:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:57:39 INFO - Running setup.py install for mozfile 17:57:39 INFO - Running setup.py install for mozhttpd 17:57:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Running setup.py install for mozinfo 17:57:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Running setup.py install for mozInstall 17:57:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Running setup.py install for mozleak 17:57:40 INFO - Running setup.py install for mozlog 17:57:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Running setup.py install for moznetwork 17:57:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Running setup.py install for mozprocess 17:57:40 INFO - Running setup.py install for mozprofile 17:57:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Running setup.py install for mozrunner 17:57:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:57:40 INFO - Running setup.py install for mozscreenshot 17:57:41 INFO - Running setup.py install for moztest 17:57:41 INFO - Running setup.py install for mozversion 17:57:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:57:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:57:41 INFO - Cleaning up... 17:57:41 INFO - Return code: 0 17:57:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:57:41 INFO - Using env: {'DISPLAY': ':0', 17:57:41 INFO - 'HOME': '/home/cltbld', 17:57:41 INFO - 'LANG': 'en_US.UTF-8', 17:57:41 INFO - 'LANGUAGE': 'en_US:en', 17:57:41 INFO - 'LOGNAME': 'cltbld', 17:57:41 INFO - 'MAIL': '/var/mail/cltbld', 17:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:41 INFO - 'MOZ_NO_REMOTE': '1', 17:57:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:41 INFO - 'NO_EM_RESTART': '1', 17:57:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:41 INFO - 'PWD': '/builds/slave/test', 17:57:41 INFO - 'SHELL': '/bin/bash', 17:57:41 INFO - 'SHLVL': '1', 17:57:41 INFO - 'TERM': 'linux', 17:57:41 INFO - 'TMOUT': '86400', 17:57:41 INFO - 'USER': 'cltbld', 17:57:41 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:41 INFO - '_': '/tools/buildbot/bin/python'} 17:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:41 INFO - Running setup.py (path:/tmp/pip-WaR87G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:41 INFO - Running setup.py (path:/tmp/pip-X_QTAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:41 INFO - Running setup.py (path:/tmp/pip-KMB2G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:41 INFO - Running setup.py (path:/tmp/pip-FQAB8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:41 INFO - Running setup.py (path:/tmp/pip-dPgI4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:41 INFO - Running setup.py (path:/tmp/pip-gSiNi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:41 INFO - Running setup.py (path:/tmp/pip-LwobsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:41 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)) 17:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:41 INFO - Running setup.py (path:/tmp/pip-5hywES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:42 INFO - Running setup.py (path:/tmp/pip-6gRWgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:42 INFO - Running setup.py (path:/tmp/pip-M2guj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:42 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)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:42 INFO - Running setup.py (path:/tmp/pip-i0duaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:42 INFO - Running setup.py (path:/tmp/pip-onaxgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:42 INFO - Running setup.py (path:/tmp/pip-QXf4ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:42 INFO - Running setup.py (path:/tmp/pip-4kxSv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:42 INFO - Running setup.py (path:/tmp/pip-fPFyCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:42 INFO - Running setup.py (path:/tmp/pip-WwdJHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:42 INFO - Running setup.py (path:/tmp/pip-XibvPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:57:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:44 INFO - Downloading blessings-1.6.tar.gz 17:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:57:44 INFO - Installing collected packages: blessings 17:57:44 INFO - Running setup.py install for blessings 17:57:44 INFO - Successfully installed blessings 17:57:44 INFO - Cleaning up... 17:57:44 INFO - Return code: 0 17:57:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:57:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:45 INFO - Reading from file tmpfile_stdout 17:57:45 INFO - Current package versions: 17:57:45 INFO - argparse == 1.2.1 17:57:45 INFO - blessings == 1.6 17:57:45 INFO - blobuploader == 1.2.4 17:57:45 INFO - docopt == 0.6.1 17:57:45 INFO - functools32 == 3.2.3-2 17:57:45 INFO - jsonschema == 2.5.1 17:57:45 INFO - manifestparser == 1.1 17:57:45 INFO - mozInstall == 1.12 17:57:45 INFO - mozcrash == 0.17 17:57:45 INFO - mozdebug == 0.1 17:57:45 INFO - mozdevice == 0.48 17:57:45 INFO - mozfile == 1.2 17:57:45 INFO - mozhttpd == 0.7 17:57:45 INFO - mozinfo == 0.9 17:57:45 INFO - mozleak == 0.1 17:57:45 INFO - mozlog == 3.4 17:57:45 INFO - moznetwork == 0.27 17:57:45 INFO - mozprocess == 0.23 17:57:45 INFO - mozprofile == 0.28 17:57:45 INFO - mozrunner == 6.12 17:57:45 INFO - mozscreenshot == 0.1 17:57:45 INFO - mozsystemmonitor == 0.3 17:57:45 INFO - moztest == 0.7 17:57:45 INFO - mozversion == 1.4 17:57:45 INFO - psutil == 3.1.1 17:57:45 INFO - requests == 1.2.3 17:57:45 INFO - wsgiref == 0.1.2 17:57:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:57:45 INFO - Using env: {'DISPLAY': ':0', 17:57:45 INFO - 'HOME': '/home/cltbld', 17:57:45 INFO - 'LANG': 'en_US.UTF-8', 17:57:45 INFO - 'LANGUAGE': 'en_US:en', 17:57:45 INFO - 'LOGNAME': 'cltbld', 17:57:45 INFO - 'MAIL': '/var/mail/cltbld', 17:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:45 INFO - 'MOZ_NO_REMOTE': '1', 17:57:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:45 INFO - 'NO_EM_RESTART': '1', 17:57:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:45 INFO - 'PWD': '/builds/slave/test', 17:57:45 INFO - 'SHELL': '/bin/bash', 17:57:45 INFO - 'SHLVL': '1', 17:57:45 INFO - 'TERM': 'linux', 17:57:45 INFO - 'TMOUT': '86400', 17:57:45 INFO - 'USER': 'cltbld', 17:57:45 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:45 INFO - '_': '/tools/buildbot/bin/python'} 17:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:57:45 INFO - Cleaning up... 17:57:45 INFO - Return code: 0 17:57:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f0419f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f74ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, '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': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', '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 17:57:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:57:45 INFO - Using env: {'DISPLAY': ':0', 17:57:45 INFO - 'HOME': '/home/cltbld', 17:57:45 INFO - 'LANG': 'en_US.UTF-8', 17:57:45 INFO - 'LANGUAGE': 'en_US:en', 17:57:45 INFO - 'LOGNAME': 'cltbld', 17:57:45 INFO - 'MAIL': '/var/mail/cltbld', 17:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:45 INFO - 'MOZ_NO_REMOTE': '1', 17:57:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:45 INFO - 'NO_EM_RESTART': '1', 17:57:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:45 INFO - 'PWD': '/builds/slave/test', 17:57:45 INFO - 'SHELL': '/bin/bash', 17:57:45 INFO - 'SHLVL': '1', 17:57:45 INFO - 'TERM': 'linux', 17:57:45 INFO - 'TMOUT': '86400', 17:57:45 INFO - 'USER': 'cltbld', 17:57:45 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:45 INFO - '_': '/tools/buildbot/bin/python'} 17:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:57:45 INFO - Cleaning up... 17:57:45 INFO - Return code: 0 17:57:45 INFO - Running post-action listener: _resource_record_post_action 17:57:45 INFO - Running post-action listener: _start_resource_monitoring 17:57:45 INFO - Starting resource monitoring. 17:57:45 INFO - [mozharness: 2017-01-07 01:57:45.666248Z] Finished create-virtualenv step (success) 17:57:45 INFO - [mozharness: 2017-01-07 01:57:45.666707Z] Running install step. 17:57:45 INFO - Running pre-action listener: _resource_record_pre_action 17:57:45 INFO - Running main action method: install 17:57:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:45 INFO - Reading from file tmpfile_stdout 17:57:45 INFO - Detecting whether we're running mozinstall >=1.0... 17:57:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:57:45 INFO - Reading from file tmpfile_stdout 17:57:45 INFO - Output received: 17:57:45 INFO - Usage: mozinstall [options] installer 17:57:45 INFO - Options: 17:57:45 INFO - -h, --help show this help message and exit 17:57:45 INFO - -d DEST, --destination=DEST 17:57:45 INFO - Directory to install application into. [default: 17:57:45 INFO - "/builds/slave/test"] 17:57:45 INFO - --app=APP Application being installed. [default: firefox] 17:57:45 INFO - mkdir: /builds/slave/test/build/application 17:57:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:57:59 INFO - Reading from file tmpfile_stdout 17:57:59 INFO - Output received: 17:57:59 INFO - /builds/slave/test/build/application/firefox/firefox 17:57:59 INFO - Running post-action listener: _resource_record_post_action 17:57:59 INFO - [mozharness: 2017-01-07 01:57:59.463161Z] Finished install step (success) 17:57:59 INFO - [mozharness: 2017-01-07 01:57:59.463405Z] Running run-tests step. 17:57:59 INFO - Running pre-action listener: _resource_record_pre_action 17:57:59 INFO - Running main action method: run_tests 17:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:57:59 INFO - Python 2.7.3 17:57:59 INFO - Return code: 0 17:57:59 INFO - grabbing minidump binary from tooltool 17:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:59 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 0x1f71f60>, '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 0x1f72d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f731d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:57:59 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 17:57:59 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 17:57:59 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 17:57:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:57:59 INFO - Return code: 0 17:57:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:57:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:57:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:57:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:57:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 17:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 17:57:59 INFO - Using env: {'DISPLAY': ':0', 17:57:59 INFO - 'HOME': '/home/cltbld', 17:57:59 INFO - 'LANG': 'en_US.UTF-8', 17:57:59 INFO - 'LANGUAGE': 'en_US:en', 17:57:59 INFO - 'LOGNAME': 'cltbld', 17:57:59 INFO - 'MAIL': '/var/mail/cltbld', 17:57:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:57:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:59 INFO - 'MOZ_NO_REMOTE': '1', 17:57:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:57:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:59 INFO - 'NO_EM_RESTART': '1', 17:57:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:59 INFO - 'PWD': '/builds/slave/test', 17:57:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:57:59 INFO - 'SHELL': '/bin/bash', 17:57:59 INFO - 'SHLVL': '1', 17:57:59 INFO - 'TERM': 'linux', 17:57:59 INFO - 'TMOUT': '86400', 17:57:59 INFO - 'USER': 'cltbld', 17:57:59 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695', 17:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:59 INFO - '_': '/tools/buildbot/bin/python'} 17:57:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 17:57:59 INFO - mozversion application_buildid: 20170106160555 17:57:59 INFO - mozversion application_changeset: 71dcbe4ec7a105ac93e288d68e48c2f0824751c7 17:57:59 INFO - mozversion application_display_name: Firefox 17:57:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:57:59 INFO - mozversion application_name: Firefox 17:57:59 INFO - mozversion application_remotingname: firefox 17:57:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:57:59 INFO - mozversion application_vendor: Mozilla 17:57:59 INFO - mozversion application_version: 51.0 17:57:59 INFO - mozversion platform_buildid: 20170106160555 17:57:59 INFO - mozversion platform_changeset: 71dcbe4ec7a105ac93e288d68e48c2f0824751c7 17:57:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:57:59 INFO - mozversion platform_version: 51.0 17:57:59 INFO - using testdate: 1483754279 17:57:59 INFO - actual date: 1483754279 17:57:59 INFO - starting webserver on 'localhost:36481' 17:57:59 INFO - SUITE-START | Running 2 tests 17:57:59 INFO - TEST-START | dromaeo_css 17:57:59 INFO - operating with platform_type : linux_ 17:57:59 INFO - Initialising browser for dromaeo_css test... 17:57:59 INFO - TEST-INFO | started process 29497 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNtCTlv/profile http://localhost:36481/getInfo.html) 17:58:03 INFO - PROCESS | 29497 | __metrics Screen width/height:1600/1200 17:58:03 INFO - PROCESS | 29497 | colorDepth:24 17:58:03 INFO - PROCESS | 29497 | Browser inner width/height: 1024/697 17:58:03 INFO - PROCESS | 29497 | __metrics 17:58:04 INFO - PROCESS | 29497 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:58:04 INFO - PROCESS | 29497 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:58:04 INFO - PROCESS | 29497 | console.error: PushService: 17:58:04 INFO - PROCESS | 29497 | stateChangeProcessEnqueue: Error transitioning state 17:58:04 INFO - PROCESS | 29497 | UnknownError 17:58:05 INFO - TEST-INFO | 29497: exit 0 17:58:05 INFO - Browser initialized. 17:58:05 INFO - Running cycle 1/1 for dromaeo_css test... 17:58:05 INFO - TEST-INFO | started process 29586 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNtCTlv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:58:17 INFO - PROCESS | 29586 | RSS: Main: 143794176 17:58:17 INFO - PROCESS | 29586 | 17:59:08 INFO - PROCESS | 29586 | 1483754348284 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] 18:01:17 INFO - PROCESS | 29586 | Cycle 1(1): loaded http://localhost:36481/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36481/tests/dromaeo/cssquery-ext.html) 18:01:18 INFO - PROCESS | 29586 | RSS: Main: 228069376 18:01:18 INFO - PROCESS | 29586 | 18:04:20 INFO - PROCESS | 29586 | Cycle 1(1): loaded http://localhost:36481/tests/dromaeo/cssquery-ext.html (next: http://localhost:36481/tests/dromaeo/cssquery-jquery.html) 18:04:21 INFO - PROCESS | 29586 | RSS: Main: 232636416 18:04:21 INFO - PROCESS | 29586 | 18:07:23 INFO - PROCESS | 29586 | Cycle 1(1): loaded http://localhost:36481/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36481/tests/dromaeo/cssquery-mootools.html) 18:07:23 INFO - PROCESS | 29586 | RSS: Main: 215904256 18:07:23 INFO - PROCESS | 29586 | 18:10:25 INFO - PROCESS | 29586 | Cycle 1(1): loaded http://localhost:36481/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36481/tests/dromaeo/cssquery-prototype.html) 18:10:26 INFO - PROCESS | 29586 | RSS: Main: 211861504 18:10:26 INFO - PROCESS | 29586 | 18:13:28 INFO - PROCESS | 29586 | Cycle 1(1): loaded http://localhost:36481/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36481/tests/dromaeo/cssquery-yui.html) 18:13:29 INFO - PROCESS | 29586 | RSS: Main: 179281920 18:13:29 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | Cycle 1(1): loaded http://localhost:36481/tests/dromaeo/cssquery-yui.html (next: http://localhost:36481/tests/dromaeo/cssquery-dojo.html) 18:16:31 INFO - PROCESS | 29586 | RSS: Main: 265531392 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | __start_tp_report 18:16:31 INFO - PROCESS | 29586 | _x_x_mozilla_page_load 18:16:31 INFO - PROCESS | 29586 | _x_x_mozilla_page_load_details 18:16:31 INFO - PROCESS | 29586 | |i|pagename|runs| 18:16:31 INFO - PROCESS | 29586 | |0;dojo.html;3075.123589776914;3115.0499097775178;3124.7343975762074;3143.874245030199;3156.321390900956;5485.561155107592;5553.555715542757;5582.106862901936;5609.831705048845;5615.803446879355;5550.840264810722;5572.944270557293;5582.246396736439;5588.748506317215;5595.132754423071;5038.974805125969;5060.4686507916695;5069.974650126745;5104.055749686301;5116.513931176551;4922.114019476493;4965.801367945278;5126.974365128169;5141.5886729061585;5146.0479811235045;4970.751462426864;4971.37857767779;4978.303037574742;5005.749712514378;5013.924791128118;5024.321716568253;5036.068327359432;5100.770465329069;5110.821121260775;5137;4835.637327200474;4982.6761260518315;4996.050750357421;5055.292259083732;5080.974595127001;5401.432849550819;5433.402325744165;5447.536959358459;5497.642653227527;5570.053090974504;5967.791127310524;5982.04287314032;5990.460858522754;5991.790287339966;5996.97001514994;5850.795222167247;6022.78920237794;6037.516998640098;6040.395960403968;6050.6369617823075;5473.452654734535;5496.203050557727;5506.119020956668;5524.14375771756;5593.944060559424;6067.908981365283;6070.119832624245;6075.605085669417;6089.086637004485;6125.9693701531205;5812.912806307909;5821.912671309934;5822.650640961471;5824.563157763185;5830.737616807254;5781.566382521328;5819.010768169422;5855.414458554111;5898.675572843449;5926.051831706906;5955.464008239279;5962.940370596239;5988.820335389945;5992.041273396323;5999.190109335271;5901.114832775118;5917.615355001911;5954.940450595439;5967.671778052162;6002.4897883679505;4441.755703436342;4459.843905463294;4586.931196032062;4590.908181836345;4605.6776025678555;4006.1186538961383;4035.1929614077303;4052.0477687743355;4056.1076563156057;4110.650594699424;5659.236003139606;5663.830085097454;5670.404607516234;5672.290963629546;5702.401247868914;5788.18965344852;5993.460588547052;6000.33996260411;6001.909971350433;6007.519398448114;5508.394076651565;5569;5606.383297837235;5624.831255062355;5707.3721890592005;5145;5174.715390653475;5179.067767801756;5189.42916279209;5189.844304670866;6098.5426093042315;6146.231721034871;6147.0779383092895;6158.630482171084;6160.969195153995;5794.971025144847;5841.649501029951;5925.407459254213;5992.700364981646;6001.699915004319;6206.503479721521;6210.4100110489435;6212.099245609271;6218.844528886633;6222.160008398808;5927.2590926134235;5928.79249226275;5934.139549765174;5934.169216309807;5973.223480947449;5853.912191317048;5870.706464676835;5952.077427998667;5954.255718035246;6030.336662966983;5918.171455996078;5965.045592705146;5965.403459654;5984.521238300838;5991.64050156992;5919.260092488439;5931.881362372815;5935.465808077293;5937.198478205387;5938.2280303560265;5639.971800140973;5693.032184528557;5813.82558523245;5875.294964604275;5883.646981181143;5780.710964451845;5792.739326730223;5824.097264923944;5901.29184497863;5929.762809487572;5737;5745.712714364348;5762.538996880324;5766.538676905921;5773.451522105393 18:16:31 INFO - PROCESS | 29586 | |1;ext.html;573.8335882594048;581.2153592649922;601.0143364881592;612.415143537921;634.6097150252594;32587.83706081469;36643.633563664356;43903.34144987828;45024.77487612566;45187.774061129734;47887.76056119723;48311;48421.27368089473;48457.75771121148;48574;9307.34848560601;9446.433214007147;9470.573824177929;9477.526123693822;9483.146516813486;8648.524331161783;8901.777455563599;10956.726081847937;11214;11218.326900385962;11441.59954401596;11551;11553.249038812492;11555.595554155607;11561.42192890352;11316.830247546251;11391.829122563167;11398.25911315766;11402.600908968188;11403.885961140366;11146.164037697205;11149;11170.888291117066;11190.888091119148;11268.830967535412;44806.103877922586;44994.32508512342;45024.3246351305;45162.32256516155;45226.77386613079;3520.9647903520895;3575.6960658344074;3740.3080430120513;3818.7517811342173;4381.671374646914;30120.54819177714;30271.697283027108;30582;30634.69365306341;30818;4468.352088947117;4542.931856022163;4587.220172570637;4610.838620648295;4685.695429797052;351155;351521.24239378894;355337;356250;356430;37565.43651845225;62863.685681571296;63238;63270.36729632645;63271;38592;41136;41310.58689413127;41318;41321.1735765283;15317.387304507918;15469.845301547066;15497.922510387376;15501.689966200613;15503.302351394213;11312.830307545393;11581;12196.14626976121;12308.753824923451;12314.630561083182;9069;9467.100625440573;9478.668246611473;9535.284853636005;9583.808323833484;3558.0430763080203;3560.572731272264;3562.7327950403824;3574.803385813805;3699.963000370016;998.6155330197926;1054.9630762923261;1137.3281276523496;1153.7231064544617;1165.4580620011566;34170.064644455495;34368;34521.654783452344;34660;34798.47802282968;1107.2526044919648;1151.6026970695098;1154.4574050196395;1180.9350485723369;1221.9205751626293;18150.547956163475;18193.7270940936;18203.817961820212;18270.63458730818;18278.17748201334;574261;577366;578089;578919;579744;60531.394686053456;60848.39151608427;61085.38914610797;61111.38888611146;61185;1307.8888294495075;1341.9329033548283;1366.3373263966969;1409.1826740490492;1420.921849298278;34158.65841341555;35737.821310893276;35782.64217357793;35910.64089359073;36067.27865442729;52558.73720631372;53050.4694953061;53069.73465132649;53182;53252.46747532475;402057;405868;406056;406477;406544;30932;36269.81865090763;45884.54115458803;45992.54007460016;46842;48159.295903365855;52956.73521632367;52965.20552191643;53848.46151538435;54076.45923540714;41922.58077419187;43480;43953;45269.32096018497;45934;43046.14359652658;43346.566534334255;43645.345319820866;44841.327380088675;45294.77352613216;45803.54196457993;46827.29759053685;46930;47047.764761175975;47426.76286618545;37790.43314350232;39868.80065599769;40437.59562404456;40565.79717101395;41342.793286033375;30248.243793904447;30315.242118947204;30326.241843953194;30519;30780.384392311582 18:16:31 INFO - PROCESS | 29586 | |2;jquery.html;408.46695062942865;411.22894572676233;419.9832006719731;420.7853994462824;432.5242233543103;18432.44702658919;19372.70940935887;19659.31192408266;19774.505637359074;20081.497962550944;19550.902245488753;19571;20609.27867524637;20722.27472038479;20747;5328.706921119337;5580.107182850745;5605.747741351629;5685.147227915825;5688.4311568842995;5561.138023606327;5585.3297604287545;5599.832005039835;5600.4959553640165;5885.7351419186025;6084.482818960394;6228.03465462854;6234.750609975618;6236.594621349598;6243.344448832875;6038.305594856582;6148.047052706837;6206.224221972254;6228.813135605939;6241.28225254097;6011.008183649693;6041.577089603729;6041.78853740117;6042.274927008743;6045.848853778647;15084.17037062961;15087.4719384821;15383.923080384526;15434.768478472832;15498.922505387514;2365.8225633077586;2380.392999785061;2979.553067039961;3127.937441251185;3193.0740085375214;10665.413402262873;10955.123590112771;10971.396573188471;10972.341659500455;10990.17573681977;1962.6859702447682;1991.661417559004;1999.4201681512488;2013.1544751204383;2054.732884724976;72679;80891.59554202251;85051;86345.99999999937;89869;11498.827517587242;11542.249753765978;11635.138377859503;11823.645290641294;11840.348780817138;16845.41041063557;16865.57836054084;16871.578210544834;16874.071926044184;16896.493105206617;7839.529628222325;7971.282584567417;8005.599720013976;8147.388945829087;8356.749297521084;7628.618569071524;7671.693132274758;7770.261825126717;7780.688772449152;7792.649330780128;4992.076465853828;5094.159463688524;5129.897402052013;5142.125838607446;5241.292425522582;1779.599590092219;2045.954045954046;2108.493961449241;2124.341454149219;2139.283340081059;891.4473026723343;1010.7927874785652;1075.6074032978063;1083.4474418046696;1090.013537748339;20080.799192007893;20098.598028039356;20152.193912243347;20193.39419817408;20242;1020.642775028734;1030.99484502577;1043.139409986764;1086.3916206924148;1098.5880294889416;10693.89306106929;11446.713832154097;11474.311541307548;11479.540818367172;11480.483378247998;460705;460760;460856;475448;512721;94458;95345;97532.51233743786;98734;99013;1002.4536627538009;1035.9430231337349;1069;1095.085603521053;1103.8509801176738;17188.914055429643;17627.383041593483;17755;17966;18315.908420457814;42622.57377426186;43765.34351984659;43890;43892.56107438885;44072.779636101615;58681.70659146677;59985;61346.69326653338;61432;61476.69261653842;8797.428167169113;9206.769830754285;9230.815383692154;9475.857862132212;9677.080677335642;8726.376253237593;12277.140600157903;12851.100422970676;13840.307984600528;14217.146971181348;12245.816312755138;12400.31798251105;12502.364666407855;12549.435275412447;12675.556355527513;11911.523539058688;12159.209651372632;12188.329641869428;12452.564160254347;12588.433520491782;12253.081018923438;12278.631641050782;12610.873891261337;12684.936575317064;12687.429065691882;9062.682806101755;9090.13643703847;9211.263098952202;9311.86032209531;9420;9543.713688589354;12187.939060304641;12450.501979920702;12507.74984500323;12809.487620495076 18:16:31 INFO - PROCESS | 29586 | |3;mootools.html;1495.2972103111538;1533.5322726568397;1669.6076422040826;1745.1361576019872;1801.5496125968507;8860.645574177033;9595.088466595662;9604.42373457593;9607.807843843128;9612.37519561228;8137;8349.791255218608;8821;9059.184673379394;9343.065693430653;2463.519613675334;2490.9252722418264;2496.7378425265356;2498.675172227608;2498.800095992325;2437.902483900642;2506.686664166979;2509.573372526675;2511.8116141289383;2511.8744062796877;2438.1344622658967;2439.560879041772;2514.283429222672;2515.71069327027;2520.6723125993594;2404.855708657486;2417.419819243378;2422.0190822716827;2453.4847681986626;2454.938626534351;2392.97607023931;2398.736139024706;2406.338256979327;2410.09621391978;2445.144199530168;9380.812383752354;9407.20038796703;9505.952470237604;9513.714588562285;9519;5458.399576046631;5588.245586845765;5590.329160500766;5592.161175823659;5595.664260144405;3330.163635643258;3463.0822831949554;3473.4095203815414;3524.9118772030906;3527.6472352764777;1089.7929393415225;1109.6282745280341;1132.47905963257;1146.8394424780538;1159.4289737405863;13001.414936327794;13034.348282585834;13043.08698391113;13082.345882705828;13113.409896554582;4674.6260299176665;4733.644976626698;4751.09729151467;4770.529910817835;4911;10895.782084358269;10917.672469825919;11002.614908478165;11003.33979961205;11005.119590432747;5358.517733404013;5375.274337964403;5390.622656414092;5393.43368946255;5402.351717793811;4405.537418571004;4532.9546704533195;4826.565609095198;4881.194602890554;4977.452480227172;4158.600203939863;4178.289690752579;4230.111676547959;4272.700910936267;4284.057507348441;1314.2246074815714;1320.3165963396746;1340.0887396570427;1344.2337867415479;1361.9931900340632;864.7578677970179;967.883853937532;978.1001478639527;984.6602921991902;984.7592034037165;5975.282966044103;6039.094135879654;6044.576879618385;6060.969695151496;6113.510919126459;438.68853114289215;449.0099330975192;450.88727818045487;452.7713504680115;463.1963543252454;4077.91844163121;4100.7334523255895;4117.300058989979;4136.565660605654;4155.065110350208;11111.777764444665;11596.130290228266;11598.420078996016;11645.825312620318;11655.533778648929;11503.654890353135;11524.88475115255;11530.76938461226;11536.538538458535;11539.711507212387;8019.959900200578;8399.160083991552;8400.579971001425;8614.483131012159;8629.525376104375;6098.420650038239;6215.627062376272;6231.1276421300145;6231.844203894939;6328.557001009885;10244.64143754965;10598.20513461509;10622.628208012682;10625.415602141647;10630.787384252117;10795.082417994558;10889.727756806144;10905.727356816144;10920.945395272973;10927;7264.309890560388;7410.258974102547;7489.625518724151;8118.59407029658;8244.958775206085;8381.203785640355;8930.062343453843;8962.596683149144;9238.399504032217;9260.536973151451;7881.763547093597;7919.485233459806;8140.023197216372;8173.386995975445;8345.624446899783;7835.059792824897;7937.642806073625;7985.8802117969435;8114.350851931949;8172.182781722018;6780.559263647847;7249.383802376857;7305.671244794104;7311.4516411268305;7311.561306321638;5137.974310128426;5178.741062946913;5195.74021298926;5210.869728256824;5240.613510972236;5169.844904652867;5171.3535808024;5185.792568297225;5204.5315921565725;5324.8136315228785 18:16:31 INFO - PROCESS | 29586 | |4;prototype.html;342.0780995217887;346.7728637742279;354.5004630413351;358.8259694048384;363.73265649747447;5525.861853453661;5528.889422211553;5531.695756733378;5536.197251398545;5620.831375058755;5259.658122222053;5338.466153384659;5377.623566350348;5469.4958886306285;5523.613347065707;4319.265724826787;4328.870133895988;4329.592882313245;4353.564643535645;4353.651707863379;4311.5472875348105;4361.389405483219;4374.125174965004;4380.386745855567;4394.626456751164;4103.548609652936;4276.850310239127;4310.439642846425;4322.762248076355;4335.458067741532;4265.168292183025;4301.548337424557;4309.2458819706735;4315.762633055181;4318.287482565389;3814.313423583754;3819.75171613847;3825.5218097737784;3834.4631751554803;3835.17543728097;5188.6367954243215;5412.404635490093;5417.187421886709;5487.64330318532;5507.7246137692955;4892.8532144035735;4895.8776030599165;4922.16323225049;4934.470314202608;4955.479674634147;5709.571782116358;5723.227364305807;5725.914111288334;5755.740991655385;5759.856003599902;4085.3054980653064;4151.232022075896;4174.5616710245295;4198.895027624303;4252.532221455637;5938.317093534256;5988.401159884021;5993.6403815770755;5995.820125396245;5998;4266.360045993126;4274.850380236677;4276.2089013532595;4303;4308.5781692041555;4159.875203743893;4332.913341733148;4340.804663790137;4355.88739461361;4365.388845561624;4909.656324057353;4914.70511769295;4926.802927882916;4926.926096108561;4932.482089380635;5759.251297331404;5774.220480235199;5826.300843898759;5840.941590584125;5849.040571408541;1453.9709205815825;1479.3195130239994;1482.85171482853;1486.4797320937585;1490.8285547161997;938.3290946972947;949.050949050949;952.0859974424492;960.3229723045237;962.6967505235829;4842.878928026757;4880.951190488121;4883;4892.3884514435695;4919.655624106278;5141.177411614199;5184.481551844785;5202.687838729688;5205.583553315726;5211.2443695663915;4694.6713730039255;4925.950740492621;4942.703437793743;4947.282644016669;4954.752262386938;2761.8619069046867;2784.6911951382817;2789.995601583428;2810.8172968756967;2822.1674605991284;5336.332958380202;5353.304070470814;5384.838454846361;5385.27298814655;5412.864678382994;4886.584640305614;5190.948090519122;5205.609579281569;5274.683518988873;5281.815136470282;5250.921236181461;5280.973595132;5294.258803767476;5308.92036619451;5322.600804939645;5146.716930568705;5152.793888244429;5160.161910948888;5195.558377537952;5202.505761952609;5162.767675454688;5471.206675032018;5914.556408269483;5922.615030023035;5950.9702451487465;6140.754369825157;6161.552035271798;6165.475934545614;6166.630002199882;6167.660778657038;4156.4388807511805;4336.934945975876;4369.86890393276;4392.714473559208;4393.253146964959;5892.381299963614;5901.439363260483;5908.615939963888;5914.2311499504785;5949.494292985145;5724.198612194213;5771.567132465166;5780.566457515787;5814.796482123105;5838.824835254949;5633.2395126657375;5749.511291540266;5763.250777398911;5791.247137872049;5805.41658961758;5679.37526872052;5704.687921778097;5711.143878239505;5718.885622287614;5736.5410767139365;5604.607677462538;5826.970865145647;5829.300483941955;5840.766369345179;5877.265341832271;5630.493255607015;5637.9718101409235;5664.745086471036;5665.886682266249;5668.9387730625 18:16:31 INFO - PROCESS | 29586 | |5;yui.html;766.6550052476385;783.7021931665967;808.7383681456931;820.2535692519807;920.6179435534253;14806.70386592269;15633.687326253468;15818.446354377598;16818.83181168188;16973.405930792425;15528.145951972638;15531.5340539784;15614.687706245868;15621.297041633125;15656.843431565681;706.5689929143218;733.0287369235784;839.911809260028;852.3096292003466;867.8871746672924;849.7493239494364;858.5707146426787;859.1837754133567;859.1923591823665;864.7751584588021;907.5507623726264;908.600215905003;911.6672414568668;913.4747520175893;913.8172365526888;906.3836591118037;908.3913777768911;909.6907051602453;910.194477887068;910.4127837544779;667.1327274543123;668.4819265069561;669.0432681265788;669.2370697404962;669.2437545573471;15507.147106909228;15593.298301576457;15623.453179138676;15646.295916683777;15715.842841571552;16275.477301018362;16639.25123369451;17086;17497.562560935952;17530.824691753045;12887.742245155137;12957.935210323889;12969.935150324281;13044.347782610927;13319.001074919322;4031.21391328691;4092.8362865485346;4105.835766569334;4179.2895207814745;4189.49726032878;78963.6051819743;79797;80135.5993220036;80583;81408;11361.204715669825;11413.372264525366;11480.655580332603;11564.537418503334;11920.165588408901;20182.32223582025;20322.89838550798;20354.28759993393;20366.185352586028;20395;8634.136586341316;8898.154675305836;9097.727068187965;9130.08699130095;9262.39794413361;5021.899562008813;5184.507471790174;5293;5294.205869119586;5366.7048312343195;3775.7545759526092;3885.514310711161;3913.2369188008065;3913.4716813229848;3913.8238779255003;651.4202359899693;652.3150691773619;661.2494818381076;662.9270780214173;668.4051194436955;601.4016054026272;672.4049216443483;774.6514068669121;792.7542461836733;796.4584082823619;10282.917571853042;10323.225758068174;10357.63748268807;10423.071731729655;10657.680269591925;678.7793966960758;687.29895506584;735.6432130416744;762.1654288554024;784.9293563579305;4523.5928766410525;4896.2410826321975;4964.975175124174;4975.10448119342;4990.226514890198;126605;127692.36153819172;129380;131708.7560184199;132268.33865830608;113272;113807.43096284465;115880.42059789647;119673;121366;101687;102992.97007029984;103659.481702591;103834;105136.47431762946;9200.769980750534;9621.663241786504;9623.951880240555;9753.463559504295;9789.167920726817;59072.40927590841;59535.404645952985;60504.69747651234;60856.695716521135;62035.699468655956;76820.2317976813;85834.57082714546;87989.12010879809;89698.55150724466;91440;11551.711207219887;11995.520179192734;12192.756144876876;12220.45007974661;12235;13123.212607243597;13601.251931143883;14316.427342906167;15359.923200383926;15699.529014129594;12869.613911582668;13866.861331386961;14109.858901410855;14118.78821817653;14121.717565648423;13514.662133446742;13610.93194534021;13653.590392288248;13711.520096796565;13743.793843092162;12718.300493472947;12924.095313327976;13334.799978000532;13555.59333220005;13558.11872228302;10212.897871021194;11210.49552770111;11261.549538018715;11374.43127843621;11546.653600392001;10924.836127458253;11279.436028198394;11415.657530273773;11614.187006909435;11641.185117041725 18:16:31 INFO - PROCESS | 29586 | __end_tp_report 18:16:31 INFO - PROCESS | 29586 | __start_cc_report 18:16:31 INFO - PROCESS | 29586 | _x_x_mozilla_cycle_collect,789 18:16:31 INFO - PROCESS | 29586 | __end_cc_report 18:16:31 INFO - PROCESS | 29586 | __startTimestamp1483755391750__endTimestamp 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | ------- Summary: start ------- 18:16:31 INFO - PROCESS | 29586 | Number of tests: 6 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | [#0] dojo.html Cycles:165 Average:5532.03 Median:5780.71 stddev:648.41 (11.2%) stddev-sans-first:621.09 18:16:31 INFO - PROCESS | 29586 | Values: 3075.1 3115.0 3124.7 3143.9 3156.3 5485.6 5553.6 5582.1 5609.8 5615.8 5550.8 5572.9 5582.2 5588.7 5595.1 5039.0 5060.5 5070.0 5104.1 5116.5 4922.1 4965.8 5127.0 5141.6 5146.0 4970.8 4971.4 4978.3 5005.7 5013.9 5024.3 5036.1 5100.8 5110.8 5137.0 4835.6 4982.7 4996.1 5055.3 5081.0 5401.4 5433.4 5447.5 5497.6 5570.1 5967.8 5982.0 5990.5 5991.8 5997.0 5850.8 6022.8 6037.5 6040.4 6050.6 5473.5 5496.2 5506.1 5524.1 5593.9 6067.9 6070.1 6075.6 6089.1 6126.0 5812.9 5821.9 5822.7 5824.6 5830.7 5781.6 5819.0 5855.4 5898.7 5926.1 5955.5 5962.9 5988.8 5992.0 5999.2 5901.1 5917.6 5954.9 5967.7 6002.5 4441.8 4459.8 4586.9 4590.9 4605.7 4006.1 4035.2 4052.0 4056.1 4110.7 5659.2 5663.8 5670.4 5672.3 5702.4 5788.2 5993.5 6000.3 6001.9 6007.5 5508.4 5569.0 5606.4 5624.8 5707.4 5145.0 5174.7 5179.1 5189.4 5189.8 6098.5 6146.2 6147.1 6158.6 6161.0 5795.0 5841.6 5925.4 5992.7 6001.7 6206.5 6210.4 6212.1 6218.8 6222.2 5927.3 5928.8 5934.1 5934.2 5973.2 5853.9 5870.7 5952.1 5954.3 6030.3 5918.2 5965.0 5965.4 5984.5 5991.6 5919.3 5931.9 5935.5 5937.2 5938.2 5640.0 5693.0 5813.8 5875.3 5883.6 5780.7 5792.7 5824.1 5901.3 5929.8 5737.0 5745.7 5762.5 5766.5 5773.5 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | [#1] ext.html Cycles:180 Average:61347.03 Median:40501.70 stddev:120939.28 (298.6%) stddev-sans-first:121192.47 18:16:31 INFO - PROCESS | 29586 | Values: 573.8 581.2 601.0 612.4 634.6 32587.8 36643.6 43903.3 45024.8 45187.8 47887.8 48311.0 48421.3 48457.8 48574.0 9307.3 9446.4 9470.6 9477.5 9483.1 8648.5 8901.8 10956.7 11214.0 11218.3 11441.6 11551.0 11553.2 11555.6 11561.4 11316.8 11391.8 11398.3 11402.6 11403.9 11146.2 11149.0 11170.9 11190.9 11268.8 44806.1 44994.3 45024.3 45162.3 45226.8 3521.0 3575.7 3740.3 3818.8 4381.7 30120.5 30271.7 30582.0 30634.7 30818.0 4468.4 4542.9 4587.2 4610.8 4685.7 351155.0 351521.2 355337.0 356250.0 356430.0 37565.4 62863.7 63238.0 63270.4 63271.0 38592.0 41136.0 41310.6 41318.0 41321.2 15317.4 15469.8 15497.9 15501.7 15503.3 11312.8 11581.0 12196.1 12308.8 12314.6 9069.0 9467.1 9478.7 9535.3 9583.8 3558.0 3560.6 3562.7 3574.8 3700.0 998.6 1055.0 1137.3 1153.7 1165.5 34170.1 34368.0 34521.7 34660.0 34798.5 1107.3 1151.6 1154.5 1180.9 1221.9 18150.5 18193.7 18203.8 18270.6 18278.2 574261.0 577366.0 578089.0 578919.0 579744.0 60531.4 60848.4 61085.4 61111.4 61185.0 1307.9 1341.9 1366.3 1409.2 1420.9 34158.7 35737.8 35782.6 35910.6 36067.3 52558.7 53050.5 53069.7 53182.0 53252.5 402057.0 405868.0 406056.0 406477.0 406544.0 30932.0 36269.8 45884.5 45992.5 46842.0 48159.3 52956.7 52965.2 53848.5 54076.5 41922.6 43480.0 43953.0 45269.3 45934.0 43046.1 43346.6 43645.3 44841.3 45294.8 45803.5 46827.3 46930.0 47047.8 47426.8 37790.4 39868.8 40437.6 40565.8 41342.8 30248.2 30315.2 30326.2 30519.0 30780.4 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | [#2] jquery.html Cycles:180 Average:28972.03 Median:2131.81 stddev:78491.59 (3681.9%) stddev-sans-first:78682.48 18:16:31 INFO - PROCESS | 29586 | Values: 408.5 411.2 420.0 420.8 432.5 18432.4 19372.7 19659.3 19774.5 20081.5 19550.9 19571.0 20609.3 20722.3 20747.0 5328.7 5580.1 5605.7 5685.1 5688.4 5561.1 5585.3 5599.8 5600.5 5885.7 6084.5 6228.0 6234.8 6236.6 6243.3 6038.3 6148.0 6206.2 6228.8 6241.3 6011.0 6041.6 6041.8 6042.3 6045.8 15084.2 15087.5 15383.9 15434.8 15498.9 2365.8 2380.4 2979.6 3127.9 3193.1 10665.4 10955.1 10971.4 10972.3 10990.2 1962.7 1991.7 1999.4 2013.2 2054.7 72679.0 80891.6 85051.0 86346.0 89869.0 11498.8 11542.2 11635.1 11823.6 11840.3 16845.4 16865.6 16871.6 16874.1 16896.5 7839.5 7971.3 8005.6 8147.4 8356.7 7628.6 7671.7 7770.3 7780.7 7792.6 4992.1 5094.2 5129.9 5142.1 5241.3 1779.6 2046.0 2108.5 2124.3 2139.3 891.4 1010.8 1075.6 1083.4 1090.0 20080.8 20098.6 20152.2 20193.4 20242.0 1020.6 1031.0 1043.1 1086.4 1098.6 10693.9 11446.7 11474.3 11479.5 11480.5 460705.0 460760.0 460856.0 475448.0 512721.0 94458.0 95345.0 97532.5 98734.0 99013.0 1002.5 1035.9 1069.0 1095.1 1103.9 17188.9 17627.4 17755.0 17966.0 18315.9 42622.6 43765.3 43890.0 43892.6 44072.8 58681.7 59985.0 61346.7 61432.0 61476.7 8797.4 9206.8 9230.8 9475.9 9677.1 8726.4 12277.1 12851.1 13840.3 14217.1 12245.8 12400.3 12502.4 12549.4 12675.6 11911.5 12159.2 12188.3 12452.6 12588.4 12253.1 12278.6 12610.9 12684.9 12687.4 9062.7 9090.1 9211.3 9311.9 9420.0 9543.7 12187.9 12450.5 12507.7 12809.5 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | [#3] mootools.html Cycles:180 Average:6035.59 Median:2498.08 stddev:3509.64 (140.5%) stddev-sans-first:3502.90 18:16:31 INFO - PROCESS | 29586 | Values: 1495.3 1533.5 1669.6 1745.1 1801.5 8860.6 9595.1 9604.4 9607.8 9612.4 8137.0 8349.8 8821.0 9059.2 9343.1 2463.5 2490.9 2496.7 2498.7 2498.8 2437.9 2506.7 2509.6 2511.8 2511.9 2438.1 2439.6 2514.3 2515.7 2520.7 2404.9 2417.4 2422.0 2453.5 2454.9 2393.0 2398.7 2406.3 2410.1 2445.1 9380.8 9407.2 9506.0 9513.7 9519.0 5458.4 5588.2 5590.3 5592.2 5595.7 3330.2 3463.1 3473.4 3524.9 3527.6 1089.8 1109.6 1132.5 1146.8 1159.4 13001.4 13034.3 13043.1 13082.3 13113.4 4674.6 4733.6 4751.1 4770.5 4911.0 10895.8 10917.7 11002.6 11003.3 11005.1 5358.5 5375.3 5390.6 5393.4 5402.4 4405.5 4533.0 4826.6 4881.2 4977.5 4158.6 4178.3 4230.1 4272.7 4284.1 1314.2 1320.3 1340.1 1344.2 1362.0 864.8 967.9 978.1 984.7 984.8 5975.3 6039.1 6044.6 6061.0 6113.5 438.7 449.0 450.9 452.8 463.2 4077.9 4100.7 4117.3 4136.6 4155.1 11111.8 11596.1 11598.4 11645.8 11655.5 11503.7 11524.9 11530.8 11536.5 11539.7 8020.0 8399.2 8400.6 8614.5 8629.5 6098.4 6215.6 6231.1 6231.8 6328.6 10244.6 10598.2 10622.6 10625.4 10630.8 10795.1 10889.7 10905.7 10920.9 10927.0 7264.3 7410.3 7489.6 8118.6 8245.0 8381.2 8930.1 8962.6 9238.4 9260.5 7881.8 7919.5 8140.0 8173.4 8345.6 7835.1 7937.6 7985.9 8114.4 8172.2 6780.6 7249.4 7305.7 7311.5 7311.6 5138.0 5178.7 5195.7 5210.9 5240.6 5169.8 5171.4 5185.8 5204.5 5324.8 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | [#4] prototype.html Cycles:180 Average:4719.86 Median:5202.60 stddev:1363.00 (26.2%) stddev-sans-first:1326.62 18:16:31 INFO - PROCESS | 29586 | Values: 342.1 346.8 354.5 358.8 363.7 5525.9 5528.9 5531.7 5536.2 5620.8 5259.7 5338.5 5377.6 5469.5 5523.6 4319.3 4328.9 4329.6 4353.6 4353.7 4311.5 4361.4 4374.1 4380.4 4394.6 4103.5 4276.9 4310.4 4322.8 4335.5 4265.2 4301.5 4309.2 4315.8 4318.3 3814.3 3819.8 3825.5 3834.5 3835.2 5188.6 5412.4 5417.2 5487.6 5507.7 4892.9 4895.9 4922.2 4934.5 4955.5 5709.6 5723.2 5725.9 5755.7 5759.9 4085.3 4151.2 4174.6 4198.9 4252.5 5938.3 5988.4 5993.6 5995.8 5998.0 4266.4 4274.9 4276.2 4303.0 4308.6 4159.9 4332.9 4340.8 4355.9 4365.4 4909.7 4914.7 4926.8 4926.9 4932.5 5759.3 5774.2 5826.3 5840.9 5849.0 1454.0 1479.3 1482.9 1486.5 1490.8 938.3 949.1 952.1 960.3 962.7 4842.9 4881.0 4883.0 4892.4 4919.7 5141.2 5184.5 5202.7 5205.6 5211.2 4694.7 4926.0 4942.7 4947.3 4954.8 2761.9 2784.7 2790.0 2810.8 2822.2 5336.3 5353.3 5384.8 5385.3 5412.9 4886.6 5190.9 5205.6 5274.7 5281.8 5250.9 5281.0 5294.3 5308.9 5322.6 5146.7 5152.8 5160.2 5195.6 5202.5 5162.8 5471.2 5914.6 5922.6 5951.0 6140.8 6161.6 6165.5 6166.6 6167.7 4156.4 4336.9 4369.9 4392.7 4393.3 5892.4 5901.4 5908.6 5914.2 5949.5 5724.2 5771.6 5780.6 5814.8 5838.8 5633.2 5749.5 5763.3 5791.2 5805.4 5679.4 5704.7 5711.1 5718.9 5736.5 5604.6 5827.0 5829.3 5840.8 5877.3 5630.5 5638.0 5664.7 5665.9 5668.9 18:16:31 INFO - PROCESS | 29586 | 18:16:31 INFO - PROCESS | 29586 | [#5] yui.html Cycles:180 Average:23047.05 Median:3830.63 stddev:34601.11 (903.3%) stddev-sans-first:34657.73 18:16:31 INFO - PROCESS | 29586 | Values: 766.7 783.7 808.7 820.3 920.6 14806.7 15633.7 15818.4 16818.8 16973.4 15528.1 15531.5 15614.7 15621.3 15656.8 706.6 733.0 839.9 852.3 867.9 849.7 858.6 859.2 859.2 864.8 907.6 908.6 911.7 913.5 913.8 906.4 908.4 909.7 910.2 910.4 667.1 668.5 669.0 669.2 669.2 15507.1 15593.3 15623.5 15646.3 15715.8 16275.5 16639.3 17086.0 17497.6 17530.8 12887.7 12957.9 12969.9 13044.3 13319.0 4031.2 4092.8 4105.8 4179.3 4189.5 78963.6 79797.0 80135.6 80583.0 81408.0 11361.2 11413.4 11480.7 11564.5 11920.2 20182.3 20322.9 20354.3 20366.2 20395.0 8634.1 8898.2 9097.7 9130.1 9262.4 5021.9 5184.5 5293.0 5294.2 5366.7 3775.8 3885.5 3913.2 3913.5 3913.8 651.4 652.3 661.2 662.9 668.4 601.4 672.4 774.7 792.8 796.5 10282.9 10323.2 10357.6 10423.1 10657.7 678.8 687.3 735.6 762.2 784.9 4523.6 4896.2 4965.0 4975.1 4990.2 126605.0 127692.4 129380.0 131708.8 132268.3 113272.0 113807.4 115880.4 119673.0 121366.0 101687.0 102993.0 103659.5 103834.0 105136.5 9200.8 9621.7 9624.0 9753.5 9789.2 59072.4 59535.4 60504.7 60856.7 62035.7 76820.2 85834.6 87989.1 89698.6 91440.0 11551.7 11995.5 12192.8 12220.5 12235.0 13123.2 13601.3 14316.4 15359.9 15699.5 12869.6 13866.9 14109.9 14118.8 14121.7 13514.7 13610.9 13653.6 13711.5 13743.8 12718.3 12924.1 13334.8 13555.6 13558.1 10212.9 11210.5 11261.5 11374.4 11546.7 10924.8 11279.4 11415.7 11614.2 11641.2 18:16:31 INFO - PROCESS | 29586 | -------- Summary: end -------- 18:16:31 INFO - PROCESS | 29586 | 18:16:32 INFO - TEST-INFO | 29586: exit 0 18:16:32 INFO - TEST-OK | dromaeo_css | took 1112709ms 18:16:32 INFO - TEST-START | kraken 18:16:32 INFO - operating with platform_type : linux_ 18:16:32 INFO - Initialising browser for kraken test... 18:16:32 INFO - TEST-INFO | started process 29892 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHxV65m/profile http://localhost:36481/getInfo.html) 18:16:36 INFO - PROCESS | 29892 | __metrics Screen width/height:1600/1200 18:16:36 INFO - PROCESS | 29892 | colorDepth:24 18:16:36 INFO - PROCESS | 29892 | Browser inner width/height: 1024/697 18:16:36 INFO - PROCESS | 29892 | __metrics 18:16:36 INFO - PROCESS | 29892 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:16:36 INFO - PROCESS | 29892 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:16:37 INFO - PROCESS | 29892 | console.error: PushService: 18:16:37 INFO - PROCESS | 29892 | stateChangeProcessEnqueue: Error transitioning state 18:16:37 INFO - PROCESS | 29892 | UnknownError 18:16:37 INFO - TEST-INFO | 29892: exit 0 18:16:37 INFO - Browser initialized. 18:16:37 INFO - Running cycle 1/1 for kraken test... 18:16:37 INFO - TEST-INFO | started process 29983 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHxV65m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:16:49 INFO - PROCESS | 29983 | RSS: Main: 143167488 18:16:49 INFO - PROCESS | 29983 | 18:16:52 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36481/tests/kraken/driver.html?testName=audio-beat-detection) 18:16:53 INFO - PROCESS | 29983 | RSS: Main: 186716160 18:16:53 INFO - PROCESS | 29983 | 18:16:55 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36481/tests/kraken/driver.html?testName=audio-dft) 18:16:56 INFO - PROCESS | 29983 | RSS: Main: 173936640 18:16:56 INFO - PROCESS | 29983 | 18:16:58 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36481/tests/kraken/driver.html?testName=audio-fft) 18:16:59 INFO - PROCESS | 29983 | RSS: Main: 265236480 18:16:59 INFO - PROCESS | 29983 | 18:17:01 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36481/tests/kraken/driver.html?testName=audio-oscillator) 18:17:02 INFO - PROCESS | 29983 | RSS: Main: 172089344 18:17:02 INFO - PROCESS | 29983 | 18:17:04 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36481/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:17:04 INFO - PROCESS | 29983 | RSS: Main: 173477888 18:17:04 INFO - PROCESS | 29983 | 18:17:07 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36481/tests/kraken/driver.html?testName=imaging-darkroom) 18:17:08 INFO - PROCESS | 29983 | RSS: Main: 209952768 18:17:08 INFO - PROCESS | 29983 | 18:17:11 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36481/tests/kraken/driver.html?testName=imaging-desaturate) 18:17:12 INFO - PROCESS | 29983 | RSS: Main: 208683008 18:17:12 INFO - PROCESS | 29983 | 18:17:15 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36481/tests/kraken/driver.html?testName=json-parse-financial) 18:17:15 INFO - PROCESS | 29983 | RSS: Main: 208179200 18:17:15 INFO - PROCESS | 29983 | 18:17:17 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36481/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:17:17 INFO - PROCESS | 29983 | RSS: Main: 169824256 18:17:17 INFO - PROCESS | 29983 | 18:17:19 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:17:20 INFO - PROCESS | 29983 | RSS: Main: 177348608 18:17:20 INFO - PROCESS | 29983 | 18:17:22 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:17:22 INFO - PROCESS | 29983 | RSS: Main: 187404288 18:17:22 INFO - PROCESS | 29983 | 18:17:25 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:17:25 INFO - PROCESS | 29983 | RSS: Main: 196550656 18:17:25 INFO - PROCESS | 29983 | 18:17:28 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:17:29 INFO - PROCESS | 29983 | RSS: Main: 192372736 18:17:29 INFO - PROCESS | 29983 | 18:17:30 INFO - PROCESS | 29983 | Cycle 1(1): loaded http://localhost:36481/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36481/tests/kraken/driver.html?testName=ai-astar) 18:17:31 INFO - PROCESS | 29983 | RSS: Main: 242544640 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | __start_tp_report 18:17:31 INFO - PROCESS | 29983 | _x_x_mozilla_page_load 18:17:31 INFO - PROCESS | 29983 | _x_x_mozilla_page_load_details 18:17:31 INFO - PROCESS | 29983 | |i|pagename|runs| 18:17:31 INFO - PROCESS | 29983 | |0;ai-astar;112;111;111;110;112;111;111;110;110;109 18:17:31 INFO - PROCESS | 29983 | |1;audio-beat-detection;145;121;116;163;117;118;115;115;142;119 18:17:31 INFO - PROCESS | 29983 | |2;audio-dft;160;163;160;160;159;160;160;161;160;161 18:17:31 INFO - PROCESS | 29983 | |3;audio-fft;74;76;75;114;74;75;74;75;102;75 18:17:31 INFO - PROCESS | 29983 | |4;audio-oscillator;83;83;133;81;82;82;102;81;82;107 18:17:31 INFO - PROCESS | 29983 | |5;imaging-gaussian-blur;116;117;116;116;116;117;117;117;118;117 18:17:31 INFO - PROCESS | 29983 | |6;imaging-darkroom;146;145;146;146;146;147;145;148;145;145 18:17:31 INFO - PROCESS | 29983 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;94 18:17:31 INFO - PROCESS | 29983 | |8;json-parse-financial;50;52;49;48;48;50;51;48;49;49 18:17:31 INFO - PROCESS | 29983 | |9;json-stringify-tinderbox;59;60;60;61;60;58;58;58;58;59 18:17:31 INFO - PROCESS | 29983 | |10;stanford-crypto-aes;73;81;76;79;73;83;73;79;83;72 18:17:31 INFO - PROCESS | 29983 | |11;stanford-crypto-ccm;126;125;128;134;123;131;121;122;151;136 18:17:31 INFO - PROCESS | 29983 | |12;stanford-crypto-pbkdf2;158;158;159;161;162;158;159;157;158;151 18:17:31 INFO - PROCESS | 29983 | |13;stanford-crypto-sha256-iterative;64;63;66;66;64;63;68;63;63;61 18:17:31 INFO - PROCESS | 29983 | __end_tp_report 18:17:31 INFO - PROCESS | 29983 | __start_cc_report 18:17:31 INFO - PROCESS | 29983 | _x_x_mozilla_cycle_collect,1607 18:17:31 INFO - PROCESS | 29983 | __end_cc_report 18:17:31 INFO - PROCESS | 29983 | __startTimestamp1483755451090__endTimestamp 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | ------- Summary: start ------- 18:17:31 INFO - PROCESS | 29983 | Number of tests: 14 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:0.95 (0.9%) stddev-sans-first:0.88 18:17:31 INFO - PROCESS | 29983 | Values: 112.0 111.0 111.0 110.0 112.0 111.0 111.0 110.0 110.0 109.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#1] audio-beat-detection Cycles:10 Average:127.10 Median:120.00 stddev:16.78 (14.0%) stddev-sans-first:16.50 18:17:31 INFO - PROCESS | 29983 | Values: 145.0 121.0 116.0 163.0 117.0 118.0 115.0 115.0 142.0 119.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 18:17:31 INFO - PROCESS | 29983 | Values: 160.0 163.0 160.0 160.0 159.0 160.0 160.0 161.0 160.0 161.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:14.32 (19.1%) stddev-sans-first:14.93 18:17:31 INFO - PROCESS | 29983 | Values: 74.0 76.0 75.0 114.0 74.0 75.0 74.0 75.0 102.0 75.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:82.00 stddev:17.35 (21.2%) stddev-sans-first:18.12 18:17:31 INFO - PROCESS | 29983 | Values: 83.0 83.0 133.0 81.0 82.0 82.0 102.0 81.0 82.0 107.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 18:17:31 INFO - PROCESS | 29983 | Values: 116.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 118.0 117.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 18:17:31 INFO - PROCESS | 29983 | Values: 146.0 145.0 146.0 146.0 146.0 147.0 145.0 148.0 145.0 145.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 18:17:31 INFO - PROCESS | 29983 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:49.50 stddev:1.35 (2.7%) stddev-sans-first:1.41 18:17:31 INFO - PROCESS | 29983 | Values: 50.0 52.0 49.0 48.0 48.0 50.0 51.0 48.0 49.0 49.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.50 stddev:1.10 (1.8%) stddev-sans-first:1.17 18:17:31 INFO - PROCESS | 29983 | Values: 59.0 60.0 60.0 61.0 60.0 58.0 58.0 58.0 58.0 59.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:79.00 stddev:4.34 (5.5%) stddev-sans-first:4.33 18:17:31 INFO - PROCESS | 29983 | Values: 73.0 81.0 76.0 79.0 73.0 83.0 73.0 79.0 83.0 72.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:129.50 stddev:9.02 (7.0%) stddev-sans-first:9.47 18:17:31 INFO - PROCESS | 29983 | Values: 126.0 125.0 128.0 134.0 123.0 131.0 121.0 122.0 151.0 136.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.10 Median:158.50 stddev:2.92 (1.8%) stddev-sans-first:3.10 18:17:31 INFO - PROCESS | 29983 | Values: 158.0 158.0 159.0 161.0 162.0 158.0 159.0 157.0 158.0 151.0 18:17:31 INFO - PROCESS | 29983 | 18:17:31 INFO - PROCESS | 29983 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:2.02 (3.2%) stddev-sans-first:2.15 18:17:31 INFO - PROCESS | 29983 | Values: 64.0 63.0 66.0 66.0 64.0 63.0 68.0 63.0 63.0 61.0 18:17:31 INFO - PROCESS | 29983 | -------- Summary: end -------- 18:17:31 INFO - PROCESS | 29983 | 18:17:32 INFO - TEST-INFO | 29983: exit 0 18:17:32 INFO - TEST-OK | kraken | took 59602ms 18:17:32 INFO - SUITE-END | took 1172s 18:17:32 INFO - Completed test suite (00:19:32) 18:17:32 INFO - javascript benchmark 18:17:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3075.123589776914, 3115.0499097775178, 3124.7343975762074, 3143.874245030199, 3156.321390900956, 5485.561155107592, 5553.555715542757, 5582.106862901936, 5609.831705048845, 5615.803446879355, 5550.840264810722, 5572.944270557293, 5582.246396736439, 5588.748506317215, 5595.132754423071, 5038.974805125969, 5060.4686507916695, 5069.974650126745, 5104.055749686301, 5116.513931176551, 4922.114019476493, 4965.801367945278, 5126.974365128169, 5141.5886729061585, 5146.0479811235045, 4970.751462426864, 4971.37857767779, 4978.303037574742, 5005.749712514378, 5013.924791128118, 5024.321716568253, 5036.068327359432, 5100.770465329069, 5110.821121260775, 5137.0, 4835.637327200474, 4982.6761260518315, 4996.050750357421, 5055.292259083732, 5080.974595127001, 5401.432849550819, 5433.402325744165, 5447.536959358459, 5497.642653227527, 5570.053090974504, 5967.791127310524, 5982.04287314032, 5990.460858522754, 5991.790287339966, 5996.97001514994, 5850.795222167247, 6022.78920237794, 6037.516998640098, 6040.395960403968, 6050.6369617823075, 5473.452654734535, 5496.203050557727, 5506.119020956668, 5524.14375771756, 5593.944060559424, 6067.908981365283, 6070.119832624245, 6075.605085669417, 6089.086637004485, 6125.9693701531205, 5812.912806307909, 5821.912671309934, 5822.650640961471, 5824.563157763185, 5830.737616807254, 5781.566382521328, 5819.010768169422, 5855.414458554111, 5898.675572843449, 5926.051831706906, 5955.464008239279, 5962.940370596239, 5988.820335389945, 5992.041273396323, 5999.190109335271, 5901.114832775118, 5917.615355001911, 5954.940450595439, 5967.671778052162, 6002.4897883679505, 4441.755703436342, 4459.843905463294, 4586.931196032062, 4590.908181836345, 4605.6776025678555, 4006.1186538961383, 4035.1929614077303, 4052.0477687743355, 4056.1076563156057, 4110.650594699424, 5659.236003139606, 5663.830085097454, 5670.404607516234, 5672.290963629546, 5702.401247868914, 5788.18965344852, 5993.460588547052, 6000.33996260411, 6001.909971350433, 6007.519398448114, 5508.394076651565, 5569.0, 5606.383297837235, 5624.831255062355, 5707.3721890592005, 5145.0, 5174.715390653475, 5179.067767801756, 5189.42916279209, 5189.844304670866, 6098.5426093042315, 6146.231721034871, 6147.0779383092895, 6158.630482171084, 6160.969195153995, 5794.971025144847, 5841.649501029951, 5925.407459254213, 5992.700364981646, 6001.699915004319, 6206.503479721521, 6210.4100110489435, 6212.099245609271, 6218.844528886633, 6222.160008398808, 5927.2590926134235, 5928.79249226275, 5934.139549765174, 5934.169216309807, 5973.223480947449, 5853.912191317048, 5870.706464676835, 5952.077427998667, 5954.255718035246, 6030.336662966983, 5918.171455996078, 5965.045592705146, 5965.403459654, 5984.521238300838, 5991.64050156992, 5919.260092488439, 5931.881362372815, 5935.465808077293, 5937.198478205387, 5938.2280303560265, 5639.971800140973, 5693.032184528557, 5813.82558523245, 5875.294964604275, 5883.646981181143, 5780.710964451845, 5792.739326730223, 5824.097264923944, 5901.29184497863, 5929.762809487572, 5737.0, 5745.712714364348, 5762.538996880324, 5766.538676905921, 5773.451522105393], "value": 5486.281831979241, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.8335882594048, 581.2153592649922, 601.0143364881592, 612.415143537921, 634.6097150252594, 32587.83706081469, 36643.633563664356, 43903.34144987828, 45024.77487612566, 45187.774061129734, 47887.76056119723, 48311.0, 48421.27368089473, 48457.75771121148, 48574.0, 9307.34848560601, 9446.433214007147, 9470.573824177929, 9477.526123693822, 9483.146516813486, 8648.524331161783, 8901.777455563599, 10956.726081847937, 11214.0, 11218.326900385962, 11441.59954401596, 11551.0, 11553.249038812492, 11555.595554155607, 11561.42192890352, 11316.830247546251, 11391.829122563167, 11398.25911315766, 11402.600908968188, 11403.885961140366, 11146.164037697205, 11149.0, 11170.888291117066, 11190.888091119148, 11268.830967535412, 44806.103877922586, 44994.32508512342, 45024.3246351305, 45162.32256516155, 45226.77386613079, 3520.9647903520895, 3575.6960658344074, 3740.3080430120513, 3818.7517811342173, 4381.671374646914, 30120.54819177714, 30271.697283027108, 30582.0, 30634.69365306341, 30818.0, 4468.352088947117, 4542.931856022163, 4587.220172570637, 4610.838620648295, 4685.695429797052, 351155.0, 351521.24239378894, 355337.0, 356250.0, 356430.0, 37565.43651845225, 62863.685681571296, 63238.0, 63270.36729632645, 63271.0, 38592.0, 41136.0, 41310.58689413127, 41318.0, 41321.1735765283, 15317.387304507918, 15469.845301547066, 15497.922510387376, 15501.689966200613, 15503.302351394213, 11312.830307545393, 11581.0, 12196.14626976121, 12308.753824923451, 12314.630561083182, 9069.0, 9467.100625440573, 9478.668246611473, 9535.284853636005, 9583.808323833484, 3558.0430763080203, 3560.572731272264, 3562.7327950403824, 3574.803385813805, 3699.963000370016, 998.6155330197926, 1054.9630762923261, 1137.3281276523496, 1153.7231064544617, 1165.4580620011566, 34170.064644455495, 34368.0, 34521.654783452344, 34660.0, 34798.47802282968, 1107.2526044919648, 1151.6026970695098, 1154.4574050196395, 1180.9350485723369, 1221.9205751626293, 18150.547956163475, 18193.7270940936, 18203.817961820212, 18270.63458730818, 18278.17748201334, 574261.0, 577366.0, 578089.0, 578919.0, 579744.0, 60531.394686053456, 60848.39151608427, 61085.38914610797, 61111.38888611146, 61185.0, 1307.8888294495075, 1341.9329033548283, 1366.3373263966969, 1409.1826740490492, 1420.921849298278, 34158.65841341555, 35737.821310893276, 35782.64217357793, 35910.64089359073, 36067.27865442729, 52558.73720631372, 53050.4694953061, 53069.73465132649, 53182.0, 53252.46747532475, 402057.0, 405868.0, 406056.0, 406477.0, 406544.0, 30932.0, 36269.81865090763, 45884.54115458803, 45992.54007460016, 46842.0, 48159.295903365855, 52956.73521632367, 52965.20552191643, 53848.46151538435, 54076.45923540714, 41922.58077419187, 43480.0, 43953.0, 45269.32096018497, 45934.0, 43046.14359652658, 43346.566534334255, 43645.345319820866, 44841.327380088675, 45294.77352613216, 45803.54196457993, 46827.29759053685, 46930.0, 47047.764761175975, 47426.76286618545, 37790.43314350232, 39868.80065599769, 40437.59562404456, 40565.79717101395, 41342.793286033375, 30248.243793904447, 30315.242118947204, 30326.241843953194, 30519.0, 30780.384392311582], "value": 20433.27522483905, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.46695062942865, 411.22894572676233, 419.9832006719731, 420.7853994462824, 432.5242233543103, 18432.44702658919, 19372.70940935887, 19659.31192408266, 19774.505637359074, 20081.497962550944, 19550.902245488753, 19571.0, 20609.27867524637, 20722.27472038479, 20747.0, 5328.706921119337, 5580.107182850745, 5605.747741351629, 5685.147227915825, 5688.4311568842995, 5561.138023606327, 5585.3297604287545, 5599.832005039835, 5600.4959553640165, 5885.7351419186025, 6084.482818960394, 6228.03465462854, 6234.750609975618, 6236.594621349598, 6243.344448832875, 6038.305594856582, 6148.047052706837, 6206.224221972254, 6228.813135605939, 6241.28225254097, 6011.008183649693, 6041.577089603729, 6041.78853740117, 6042.274927008743, 6045.848853778647, 15084.17037062961, 15087.4719384821, 15383.923080384526, 15434.768478472832, 15498.922505387514, 2365.8225633077586, 2380.392999785061, 2979.553067039961, 3127.937441251185, 3193.0740085375214, 10665.413402262873, 10955.123590112771, 10971.396573188471, 10972.341659500455, 10990.17573681977, 1962.6859702447682, 1991.661417559004, 1999.4201681512488, 2013.1544751204383, 2054.732884724976, 72679.0, 80891.59554202251, 85051.0, 86345.99999999937, 89869.0, 11498.827517587242, 11542.249753765978, 11635.138377859503, 11823.645290641294, 11840.348780817138, 16845.41041063557, 16865.57836054084, 16871.578210544834, 16874.071926044184, 16896.493105206617, 7839.529628222325, 7971.282584567417, 8005.599720013976, 8147.388945829087, 8356.749297521084, 7628.618569071524, 7671.693132274758, 7770.261825126717, 7780.688772449152, 7792.649330780128, 4992.076465853828, 5094.159463688524, 5129.897402052013, 5142.125838607446, 5241.292425522582, 1779.599590092219, 2045.954045954046, 2108.493961449241, 2124.341454149219, 2139.283340081059, 891.4473026723343, 1010.7927874785652, 1075.6074032978063, 1083.4474418046696, 1090.013537748339, 20080.799192007893, 20098.598028039356, 20152.193912243347, 20193.39419817408, 20242.0, 1020.642775028734, 1030.99484502577, 1043.139409986764, 1086.3916206924148, 1098.5880294889416, 10693.89306106929, 11446.713832154097, 11474.311541307548, 11479.540818367172, 11480.483378247998, 460705.0, 460760.0, 460856.0, 475448.0, 512721.0, 94458.0, 95345.0, 97532.51233743786, 98734.0, 99013.0, 1002.4536627538009, 1035.9430231337349, 1069.0, 1095.085603521053, 1103.8509801176738, 17188.914055429643, 17627.383041593483, 17755.0, 17966.0, 18315.908420457814, 42622.57377426186, 43765.34351984659, 43890.0, 43892.56107438885, 44072.779636101615, 58681.70659146677, 59985.0, 61346.69326653338, 61432.0, 61476.69261653842, 8797.428167169113, 9206.769830754285, 9230.815383692154, 9475.857862132212, 9677.080677335642, 8726.376253237593, 12277.140600157903, 12851.100422970676, 13840.307984600528, 14217.146971181348, 12245.816312755138, 12400.31798251105, 12502.364666407855, 12549.435275412447, 12675.556355527513, 11911.523539058688, 12159.209651372632, 12188.329641869428, 12452.564160254347, 12588.433520491782, 12253.081018923438, 12278.631641050782, 12610.873891261337, 12684.936575317064, 12687.429065691882, 9062.682806101755, 9090.13643703847, 9211.263098952202, 9311.86032209531, 9420.0, 9543.713688589354, 12187.939060304641, 12450.501979920702, 12507.74984500323, 12809.487620495076], "value": 9512.478943944046, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.2972103111538, 1533.5322726568397, 1669.6076422040826, 1745.1361576019872, 1801.5496125968507, 8860.645574177033, 9595.088466595662, 9604.42373457593, 9607.807843843128, 9612.37519561228, 8137.0, 8349.791255218608, 8821.0, 9059.184673379394, 9343.065693430653, 2463.519613675334, 2490.9252722418264, 2496.7378425265356, 2498.675172227608, 2498.800095992325, 2437.902483900642, 2506.686664166979, 2509.573372526675, 2511.8116141289383, 2511.8744062796877, 2438.1344622658967, 2439.560879041772, 2514.283429222672, 2515.71069327027, 2520.6723125993594, 2404.855708657486, 2417.419819243378, 2422.0190822716827, 2453.4847681986626, 2454.938626534351, 2392.97607023931, 2398.736139024706, 2406.338256979327, 2410.09621391978, 2445.144199530168, 9380.812383752354, 9407.20038796703, 9505.952470237604, 9513.714588562285, 9519.0, 5458.399576046631, 5588.245586845765, 5590.329160500766, 5592.161175823659, 5595.664260144405, 3330.163635643258, 3463.0822831949554, 3473.4095203815414, 3524.9118772030906, 3527.6472352764777, 1089.7929393415225, 1109.6282745280341, 1132.47905963257, 1146.8394424780538, 1159.4289737405863, 13001.414936327794, 13034.348282585834, 13043.08698391113, 13082.345882705828, 13113.409896554582, 4674.6260299176665, 4733.644976626698, 4751.09729151467, 4770.529910817835, 4911.0, 10895.782084358269, 10917.672469825919, 11002.614908478165, 11003.33979961205, 11005.119590432747, 5358.517733404013, 5375.274337964403, 5390.622656414092, 5393.43368946255, 5402.351717793811, 4405.537418571004, 4532.9546704533195, 4826.565609095198, 4881.194602890554, 4977.452480227172, 4158.600203939863, 4178.289690752579, 4230.111676547959, 4272.700910936267, 4284.057507348441, 1314.2246074815714, 1320.3165963396746, 1340.0887396570427, 1344.2337867415479, 1361.9931900340632, 864.7578677970179, 967.883853937532, 978.1001478639527, 984.6602921991902, 984.7592034037165, 5975.282966044103, 6039.094135879654, 6044.576879618385, 6060.969695151496, 6113.510919126459, 438.68853114289215, 449.0099330975192, 450.88727818045487, 452.7713504680115, 463.1963543252454, 4077.91844163121, 4100.7334523255895, 4117.300058989979, 4136.565660605654, 4155.065110350208, 11111.777764444665, 11596.130290228266, 11598.420078996016, 11645.825312620318, 11655.533778648929, 11503.654890353135, 11524.88475115255, 11530.76938461226, 11536.538538458535, 11539.711507212387, 8019.959900200578, 8399.160083991552, 8400.579971001425, 8614.483131012159, 8629.525376104375, 6098.420650038239, 6215.627062376272, 6231.1276421300145, 6231.844203894939, 6328.557001009885, 10244.64143754965, 10598.20513461509, 10622.628208012682, 10625.415602141647, 10630.787384252117, 10795.082417994558, 10889.727756806144, 10905.727356816144, 10920.945395272973, 10927.0, 7264.309890560388, 7410.258974102547, 7489.625518724151, 8118.59407029658, 8244.958775206085, 8381.203785640355, 8930.062343453843, 8962.596683149144, 9238.399504032217, 9260.536973151451, 7881.763547093597, 7919.485233459806, 8140.023197216372, 8173.386995975445, 8345.624446899783, 7835.059792824897, 7937.642806073625, 7985.8802117969435, 8114.350851931949, 8172.182781722018, 6780.559263647847, 7249.383802376857, 7305.671244794104, 7311.4516411268305, 7311.561306321638, 5137.974310128426, 5178.741062946913, 5195.74021298926, 5210.869728256824, 5240.613510972236, 5169.844904652867, 5171.3535808024, 5185.792568297225, 5204.5315921565725, 5324.8136315228785], "value": 4722.373266086484, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.0780995217887, 346.7728637742279, 354.5004630413351, 358.8259694048384, 363.73265649747447, 5525.861853453661, 5528.889422211553, 5531.695756733378, 5536.197251398545, 5620.831375058755, 5259.658122222053, 5338.466153384659, 5377.623566350348, 5469.4958886306285, 5523.613347065707, 4319.265724826787, 4328.870133895988, 4329.592882313245, 4353.564643535645, 4353.651707863379, 4311.5472875348105, 4361.389405483219, 4374.125174965004, 4380.386745855567, 4394.626456751164, 4103.548609652936, 4276.850310239127, 4310.439642846425, 4322.762248076355, 4335.458067741532, 4265.168292183025, 4301.548337424557, 4309.2458819706735, 4315.762633055181, 4318.287482565389, 3814.313423583754, 3819.75171613847, 3825.5218097737784, 3834.4631751554803, 3835.17543728097, 5188.6367954243215, 5412.404635490093, 5417.187421886709, 5487.64330318532, 5507.7246137692955, 4892.8532144035735, 4895.8776030599165, 4922.16323225049, 4934.470314202608, 4955.479674634147, 5709.571782116358, 5723.227364305807, 5725.914111288334, 5755.740991655385, 5759.856003599902, 4085.3054980653064, 4151.232022075896, 4174.5616710245295, 4198.895027624303, 4252.532221455637, 5938.317093534256, 5988.401159884021, 5993.6403815770755, 5995.820125396245, 5998.0, 4266.360045993126, 4274.850380236677, 4276.2089013532595, 4303.0, 4308.5781692041555, 4159.875203743893, 4332.913341733148, 4340.804663790137, 4355.88739461361, 4365.388845561624, 4909.656324057353, 4914.70511769295, 4926.802927882916, 4926.926096108561, 4932.482089380635, 5759.251297331404, 5774.220480235199, 5826.300843898759, 5840.941590584125, 5849.040571408541, 1453.9709205815825, 1479.3195130239994, 1482.85171482853, 1486.4797320937585, 1490.8285547161997, 938.3290946972947, 949.050949050949, 952.0859974424492, 960.3229723045237, 962.6967505235829, 4842.878928026757, 4880.951190488121, 4883.0, 4892.3884514435695, 4919.655624106278, 5141.177411614199, 5184.481551844785, 5202.687838729688, 5205.583553315726, 5211.2443695663915, 4694.6713730039255, 4925.950740492621, 4942.703437793743, 4947.282644016669, 4954.752262386938, 2761.8619069046867, 2784.6911951382817, 2789.995601583428, 2810.8172968756967, 2822.1674605991284, 5336.332958380202, 5353.304070470814, 5384.838454846361, 5385.27298814655, 5412.864678382994, 4886.584640305614, 5190.948090519122, 5205.609579281569, 5274.683518988873, 5281.815136470282, 5250.921236181461, 5280.973595132, 5294.258803767476, 5308.92036619451, 5322.600804939645, 5146.716930568705, 5152.793888244429, 5160.161910948888, 5195.558377537952, 5202.505761952609, 5162.767675454688, 5471.206675032018, 5914.556408269483, 5922.615030023035, 5950.9702451487465, 6140.754369825157, 6161.552035271798, 6165.475934545614, 6166.630002199882, 6167.660778657038, 4156.4388807511805, 4336.934945975876, 4369.86890393276, 4392.714473559208, 4393.253146964959, 5892.381299963614, 5901.439363260483, 5908.615939963888, 5914.2311499504785, 5949.494292985145, 5724.198612194213, 5771.567132465166, 5780.566457515787, 5814.796482123105, 5838.824835254949, 5633.2395126657375, 5749.511291540266, 5763.250777398911, 5791.247137872049, 5805.41658961758, 5679.37526872052, 5704.687921778097, 5711.143878239505, 5718.885622287614, 5736.5410767139365, 5604.607677462538, 5826.970865145647, 5829.300483941955, 5840.766369345179, 5877.265341832271, 5630.493255607015, 5637.9718101409235, 5664.745086471036, 5665.886682266249, 5668.9387730625], "value": 4289.99409717099, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.6550052476385, 783.7021931665967, 808.7383681456931, 820.2535692519807, 920.6179435534253, 14806.70386592269, 15633.687326253468, 15818.446354377598, 16818.83181168188, 16973.405930792425, 15528.145951972638, 15531.5340539784, 15614.687706245868, 15621.297041633125, 15656.843431565681, 706.5689929143218, 733.0287369235784, 839.911809260028, 852.3096292003466, 867.8871746672924, 849.7493239494364, 858.5707146426787, 859.1837754133567, 859.1923591823665, 864.7751584588021, 907.5507623726264, 908.600215905003, 911.6672414568668, 913.4747520175893, 913.8172365526888, 906.3836591118037, 908.3913777768911, 909.6907051602453, 910.194477887068, 910.4127837544779, 667.1327274543123, 668.4819265069561, 669.0432681265788, 669.2370697404962, 669.2437545573471, 15507.147106909228, 15593.298301576457, 15623.453179138676, 15646.295916683777, 15715.842841571552, 16275.477301018362, 16639.25123369451, 17086.0, 17497.562560935952, 17530.824691753045, 12887.742245155137, 12957.935210323889, 12969.935150324281, 13044.347782610927, 13319.001074919322, 4031.21391328691, 4092.8362865485346, 4105.835766569334, 4179.2895207814745, 4189.49726032878, 78963.6051819743, 79797.0, 80135.5993220036, 80583.0, 81408.0, 11361.204715669825, 11413.372264525366, 11480.655580332603, 11564.537418503334, 11920.165588408901, 20182.32223582025, 20322.89838550798, 20354.28759993393, 20366.185352586028, 20395.0, 8634.136586341316, 8898.154675305836, 9097.727068187965, 9130.08699130095, 9262.39794413361, 5021.899562008813, 5184.507471790174, 5293.0, 5294.205869119586, 5366.7048312343195, 3775.7545759526092, 3885.514310711161, 3913.2369188008065, 3913.4716813229848, 3913.8238779255003, 651.4202359899693, 652.3150691773619, 661.2494818381076, 662.9270780214173, 668.4051194436955, 601.4016054026272, 672.4049216443483, 774.6514068669121, 792.7542461836733, 796.4584082823619, 10282.917571853042, 10323.225758068174, 10357.63748268807, 10423.071731729655, 10657.680269591925, 678.7793966960758, 687.29895506584, 735.6432130416744, 762.1654288554024, 784.9293563579305, 4523.5928766410525, 4896.2410826321975, 4964.975175124174, 4975.10448119342, 4990.226514890198, 126605.0, 127692.36153819172, 129380.0, 131708.7560184199, 132268.33865830608, 113272.0, 113807.43096284465, 115880.42059789647, 119673.0, 121366.0, 101687.0, 102992.97007029984, 103659.481702591, 103834.0, 105136.47431762946, 9200.769980750534, 9621.663241786504, 9623.951880240555, 9753.463559504295, 9789.167920726817, 59072.40927590841, 59535.404645952985, 60504.69747651234, 60856.695716521135, 62035.699468655956, 76820.2317976813, 85834.57082714546, 87989.12010879809, 89698.55150724466, 91440.0, 11551.711207219887, 11995.520179192734, 12192.756144876876, 12220.45007974661, 12235.0, 13123.212607243597, 13601.251931143883, 14316.427342906167, 15359.923200383926, 15699.529014129594, 12869.613911582668, 13866.861331386961, 14109.858901410855, 14118.78821817653, 14121.717565648423, 13514.662133446742, 13610.93194534021, 13653.590392288248, 13711.520096796565, 13743.793843092162, 12718.300493472947, 12924.095313327976, 13334.799978000532, 13555.59333220005, 13558.11872228302, 10212.897871021194, 11210.49552770111, 11261.549538018715, 11374.43127843621, 11546.653600392001, 10924.836127458253, 11279.436028198394, 11415.657530273773, 11614.187006909435, 11641.185117041725], "value": 7977.562938112693, "unit": "score"}], "name": "dromaeo_css", "value": 7460.024297655867, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 111.0, 110.0, 112.0, 111.0, 111.0, 110.0, 110.0, 109.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 116.0, 163.0, 117.0, 118.0, 115.0, 115.0, 142.0, 119.0], "value": 127.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 160.0, 160.0, 159.0, 160.0, 160.0, 161.0, 160.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 114.0, 74.0, 75.0, 74.0, 75.0, 102.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 133.0, 81.0, 82.0, 82.0, 102.0, 81.0, 82.0, 107.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 145.0, 148.0, 145.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 49.0, 48.0, 48.0, 50.0, 51.0, 48.0, 49.0, 49.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 61.0, 60.0, 58.0, 58.0, 58.0, 58.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 81.0, 76.0, 79.0, 73.0, 83.0, 73.0, 79.0, 83.0, 72.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 125.0, 128.0, 134.0, 123.0, 131.0, 121.0, 122.0, 151.0, 136.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 159.0, 161.0, 162.0, 158.0, 159.0, 157.0, 158.0, 151.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 66.0, 66.0, 64.0, 63.0, 68.0, 63.0, 63.0, 61.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1466.1999999999998, "alertThreshold": 2.0}]} 18:17:32 INFO - Return code: 0 18:17:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:17:32 INFO - # TBPL SUCCESS # 18:17:32 INFO - Running post-action listener: _resource_record_post_action 18:17:32 INFO - [mozharness: 2017-01-07 02:17:32.406845Z] Finished run-tests step (success) 18:17:32 INFO - Running post-run listener: _resource_record_post_run 18:17:32 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 337084416; Read time: 56; Write time: 184420 18:17:32 INFO - TinderboxPrint: CPU usage
13.0% 18:17:32 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 56 18:17:32 INFO - TinderboxPrint: I/O write bytes / time
337,084,416 / 184,420 18:17:32 INFO - TinderboxPrint: CPU idle
8,239.8 (87.0%) 18:17:32 INFO - TinderboxPrint: CPU user
1,186.5 (12.5%) 18:17:32 INFO - TinderboxPrint: Swap in / out
0 / 0 18:17:32 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 117161984; Read time: 0; Write time: 71336 18:17:32 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219922432; Read time: 16; Write time: 113084 18:17:33 INFO - Running post-run listener: _upload_blobber_files 18:17:33 INFO - Blob upload gear active. 18:17:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:17:33 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 18:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:17:33 INFO - (blobuploader) - INFO - Open directory for files ... 18:17:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:17:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:17:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:17:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:17:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:17:34 INFO - (blobuploader) - INFO - Done attempting. 18:17:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:17:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:17:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:17:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:17:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:17:38 INFO - (blobuploader) - INFO - Done attempting. 18:17:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:17:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:17:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:17:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:17:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:17:38 INFO - (blobuploader) - INFO - Done attempting. 18:17:38 INFO - (blobuploader) - INFO - Iteration through files over. 18:17:38 INFO - Return code: 0 18:17:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:17:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:17:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d89dad26ed1daa4334f55626324a07ac312d5bd55b586a39ca5ea5462d1d49cd4207c505331e61e78d94f3e85993897008558bf3035331353423c82f5f6dfb97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb09b9936db1026792d3a7c7fff83379725992a19106ac4ebedaf967dc1aae10640009f5eb01db7761907414795a9e08d95df673a102bd274d60670f65f22de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5771e91d6b4939891d2609fba5bc58cd7f9bde78c497ba2fbf4cb24ef467e3e771e50e8d4eaa096d0292c68e91b71943c588230734acabbc85521a6fdb449e6"} 18:17:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:17:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:17:38 INFO - Contents: 18:17:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d89dad26ed1daa4334f55626324a07ac312d5bd55b586a39ca5ea5462d1d49cd4207c505331e61e78d94f3e85993897008558bf3035331353423c82f5f6dfb97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb09b9936db1026792d3a7c7fff83379725992a19106ac4ebedaf967dc1aae10640009f5eb01db7761907414795a9e08d95df673a102bd274d60670f65f22de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5771e91d6b4939891d2609fba5bc58cd7f9bde78c497ba2fbf4cb24ef467e3e771e50e8d4eaa096d0292c68e91b71943c588230734acabbc85521a6fdb449e6"} 18:17:38 INFO - Running post-run listener: copy_logs_to_upload_dir 18:17:38 INFO - Copying logs to upload dir... 18:17:38 INFO - mkdir: /builds/slave/test/build/upload/logs 18:17:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.446163 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-01-06 18:17:38.213536) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 18:17:38.216930) ========= 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=920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d89dad26ed1daa4334f55626324a07ac312d5bd55b586a39ca5ea5462d1d49cd4207c505331e61e78d94f3e85993897008558bf3035331353423c82f5f6dfb97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb09b9936db1026792d3a7c7fff83379725992a19106ac4ebedaf967dc1aae10640009f5eb01db7761907414795a9e08d95df673a102bd274d60670f65f22de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5771e91d6b4939891d2609fba5bc58cd7f9bde78c497ba2fbf4cb24ef467e3e771e50e8d4eaa096d0292c68e91b71943c588230734acabbc85521a6fdb449e6"} build_url:https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011451 build_url: 'https://queue.taskcluster.net/v1/task/cUNm1X8BSVCmWkkSbUm2bQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d89dad26ed1daa4334f55626324a07ac312d5bd55b586a39ca5ea5462d1d49cd4207c505331e61e78d94f3e85993897008558bf3035331353423c82f5f6dfb97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb09b9936db1026792d3a7c7fff83379725992a19106ac4ebedaf967dc1aae10640009f5eb01db7761907414795a9e08d95df673a102bd274d60670f65f22de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5771e91d6b4939891d2609fba5bc58cd7f9bde78c497ba2fbf4cb24ef467e3e771e50e8d4eaa096d0292c68e91b71943c588230734acabbc85521a6fdb449e6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 18:17:38.249823) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 18:17:38.250569) ========= 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=920470bf432669ad7e3a5387000001ca-1483753438.646711-476539695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 18:17:38.333844) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 18:17:38.334607) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 18:17:38.335219) ========= ========= Total master_lag: 0.17 =========