builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1475583778.94 results: success (0) buildid: 20161004023801 builduid: 6613d6dc7fe644d093760566547fdf86 revision: 581e2e9b9d79cbaf4e333447e5966e03b92a2eb7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:58.941602) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:58.942031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:58.942319) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:58.997773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:58.998068) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-04 05:22:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.6M=0s 2016-10-04 05:22:59 (37.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107242 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.132728) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.133010) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017106 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.203318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.203620) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 581e2e9b9d79cbaf4e333447e5966e03b92a2eb7 --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-aurora --rev 581e2e9b9d79cbaf4e333447e5966e03b92a2eb7 --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=bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-04 05:22:59,920 truncating revision to first 12 chars 2016-10-04 05:22:59,920 Setting DEBUG logging. 2016-10-04 05:22:59,920 attempt 1/10 2016-10-04 05:22:59,920 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/581e2e9b9d79?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 05:23:00,241 unpacking tar archive at: mozilla-aurora-581e2e9b9d79/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645590 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.868951) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.869253) ========= script_repo_revision: 581e2e9b9d79cbaf4e333447e5966e03b92a2eb7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.869630) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.869906) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 05:22:59.885485) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-10-04 05:22:59.885868) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:23:00 INFO - MultiFileLogger online at 20161004 05:23:00 in /builds/slave/test 05:23:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:23:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:00 INFO - {'append_to_log': False, 05:23:00 INFO - 'base_work_dir': '/builds/slave/test', 05:23:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:23:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:00 INFO - 'branch': 'Mozilla-Aurora', 05:23:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:00 INFO - 'config_files': ('talos/linux_config.py',), 05:23:00 INFO - 'default_actions': ('clobber', 05:23:00 INFO - 'read-buildbot-config', 05:23:00 INFO - 'download-and-extract', 05:23:00 INFO - 'populate-webroot', 05:23:00 INFO - 'create-virtualenv', 05:23:00 INFO - 'install', 05:23:00 INFO - 'run-tests'), 05:23:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:00 INFO - 'download_minidump_stackwalk': True, 05:23:00 INFO - 'download_symbols': 'ondemand', 05:23:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:00 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:00 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:00 INFO - 'installer_path': 'installer.exe', 05:23:00 INFO - 'log_level': 'info', 05:23:00 INFO - 'log_name': 'talos', 05:23:00 INFO - 'log_to_console': True, 05:23:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:23:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:23:00 INFO - 'opt_config_files': (), 05:23:00 INFO - 'pip_index': False, 05:23:00 INFO - 'sps_profile': False, 05:23:00 INFO - 'sps_profile_interval': 0, 05:23:00 INFO - 'suite': 'dromaeojs', 05:23:00 INFO - 'system_bits': '32', 05:23:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:00 INFO - 'title': 'talos-linux64-ix-055', 05:23:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:00 INFO - 'use_talos_json': True, 05:23:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:00 INFO - 'work_dir': 'build'} 05:23:00 INFO - [mozharness: 2016-10-04 12:23:00.692840Z] Running clobber step. 05:23:00 INFO - Running pre-action listener: _resource_record_pre_action 05:23:00 INFO - Running main action method: clobber 05:23:00 INFO - rmtree: /builds/slave/test/build 05:23:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:01 INFO - Running post-action listener: _resource_record_post_action 05:23:01 INFO - [mozharness: 2016-10-04 12:23:01.149810Z] Finished clobber step (success) 05:23:01 INFO - [mozharness: 2016-10-04 12:23:01.149911Z] Running read-buildbot-config step. 05:23:01 INFO - Running pre-action listener: _resource_record_pre_action 05:23:01 INFO - Running main action method: read_buildbot_config 05:23:01 INFO - Using buildbot properties: 05:23:01 INFO - { 05:23:01 INFO - "project": "", 05:23:01 INFO - "product": "firefox", 05:23:01 INFO - "script_repo_revision": "production", 05:23:01 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:23:01 INFO - "repository": "", 05:23:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:23:01 INFO - "buildid": "20161004023801", 05:23:01 INFO - "pgo_build": "True", 05:23:01 INFO - "basedir": "/builds/slave/test", 05:23:01 INFO - "buildnumber": 502, 05:23:01 INFO - "slavename": "talos-linux64-ix-055", 05:23:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:23:01 INFO - "platform": "ubuntu64_hw", 05:23:01 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:23:01 INFO - "branch": "mozilla-aurora", 05:23:01 INFO - "repo_path": "releases/mozilla-aurora", 05:23:01 INFO - "revision": "581e2e9b9d79cbaf4e333447e5966e03b92a2eb7", 05:23:01 INFO - "stage_platform": "linux64-pgo", 05:23:01 INFO - "builduid": "6613d6dc7fe644d093760566547fdf86", 05:23:01 INFO - "slavebuilddir": "test" 05:23:01 INFO - } 05:23:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 05:23:01 INFO - Running post-action listener: _resource_record_post_action 05:23:01 INFO - [mozharness: 2016-10-04 12:23:01.152367Z] Finished read-buildbot-config step (success) 05:23:01 INFO - [mozharness: 2016-10-04 12:23:01.152457Z] Running download-and-extract step. 05:23:01 INFO - Running pre-action listener: _resource_record_pre_action 05:23:01 INFO - Running main action method: download_and_extract 05:23:01 INFO - mkdir: /builds/slave/test/build/tests 05:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:01 INFO - trying https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:23:01 INFO - Downloading https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:23:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:23:01 INFO - Downloaded 1361 bytes. 05:23:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:23:01 INFO - Using the following test package requirements: 05:23:01 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 05:23:01 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:23:01 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:23:01 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'jsshell-linux-x86_64.zip'], 05:23:01 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:23:01 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 05:23:01 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:23:01 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:23:01 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:23:01 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:23:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:23:01 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:23:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 05:23:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:23:01 INFO - Fetch https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 05:23:02 INFO - Expected file size: 21904841 05:23:06 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:23:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 05:23:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:23:06 INFO - Fetch https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 05:23:07 INFO - Expected file size: 21904841 05:23:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 05:23:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:23:11 INFO - Fetch https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 05:23:12 INFO - Expected file size: 14414059 05:23:13 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')]} 05:23:13 INFO - trying https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:23:13 INFO - Downloading https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:23:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:23:15 INFO - Downloaded 63933239 bytes. 05:23:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:23:15 INFO - mkdir: /builds/slave/test/properties 05:23:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:15 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:15 INFO - Contents: 05:23:15 INFO - build_url:https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:23:15 INFO - Running post-action listener: _resource_record_post_action 05:23:15 INFO - Running post-action listener: set_extra_try_arguments 05:23:15 INFO - [mozharness: 2016-10-04 12:23:15.688730Z] Finished download-and-extract step (success) 05:23:15 INFO - [mozharness: 2016-10-04 12:23:15.688958Z] Running populate-webroot step. 05:23:15 INFO - Running pre-action listener: _resource_record_pre_action 05:23:15 INFO - Running main action method: populate_webroot 05:23:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:15 INFO - '--tppagecycles', 05:23:15 INFO - '7'], 05:23:15 INFO - 'tests': ['tsvgm']}}, 05:23:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['damp', 'tps']}, 05:23:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'tests': ['damp', 'tps']}, 05:23:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['dromaeo_dom']}, 05:23:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['basic_compositor_video']}, 05:23:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:23:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['a11yr', 05:23:15 INFO - 'ts_paint', 05:23:15 INFO - 'tpaint', 05:23:15 INFO - 'sessionrestore', 05:23:15 INFO - 'sessionrestore_no_auto_restore', 05:23:15 INFO - 'tabpaint']}, 05:23:15 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:15 INFO - 'ts_paint', 05:23:15 INFO - 'tpaint', 05:23:15 INFO - 'sessionrestore', 05:23:15 INFO - 'sessionrestore_no_auto_restore', 05:23:15 INFO - 'tabpaint']}, 05:23:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:15 INFO - 'ts_paint', 05:23:15 INFO - 'tpaint', 05:23:15 INFO - 'sessionrestore', 05:23:15 INFO - 'sessionrestore_no_auto_restore', 05:23:15 INFO - 'tabpaint']}, 05:23:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:15 INFO - 'ts_paint', 05:23:15 INFO - 'tpaint', 05:23:15 INFO - 'sessionrestore', 05:23:15 INFO - 'sessionrestore_no_auto_restore', 05:23:15 INFO - 'tabpaint']}, 05:23:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['a11yr', 05:23:15 INFO - 'ts_paint', 05:23:15 INFO - 'tpaint', 05:23:15 INFO - 'sessionrestore', 05:23:15 INFO - 'sessionrestore_no_auto_restore', 05:23:15 INFO - 'tabpaint']}, 05:23:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['a11yr', 05:23:15 INFO - 'ts_paint', 05:23:15 INFO - 'tpaint', 05:23:15 INFO - 'sessionrestore', 05:23:15 INFO - 'sessionrestore_no_auto_restore', 05:23:15 INFO - 'tabpaint']}, 05:23:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['tsvgx', 05:23:15 INFO - 'tsvgr_opacity', 05:23:15 INFO - 'tart', 05:23:15 INFO - 'tscrollx', 05:23:15 INFO - 'cart']}, 05:23:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:15 INFO - 'tsvgr_opacity', 05:23:15 INFO - 'tart', 05:23:15 INFO - 'tscrollx', 05:23:15 INFO - 'cart']}, 05:23:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'talos_options': ['--disable-e10s'], 05:23:15 INFO - 'tests': ['tp5o']}, 05:23:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'tests': ['tp5o']}, 05:23:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'talos_options': ['--disable-e10s', 05:23:15 INFO - '--xperf_path', 05:23:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:15 INFO - 'tests': ['tp5n']}, 05:23:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:15 INFO - 'talos_options': ['--xperf_path', 05:23:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:15 INFO - 'tests': ['tp5n']}}, 05:23:15 INFO - 'talos.zip': {'path': '', 05:23:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:15 INFO - Running post-action listener: _resource_record_post_action 05:23:15 INFO - [mozharness: 2016-10-04 12:23:15.711392Z] Finished populate-webroot step (success) 05:23:15 INFO - [mozharness: 2016-10-04 12:23:15.711483Z] Running create-virtualenv step. 05:23:15 INFO - Running pre-action listener: _resource_record_pre_action 05:23:15 INFO - Running main action method: create_virtualenv 05:23:15 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:15 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 05:23:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:15 INFO - Using real prefix '/usr' 05:23:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:16 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:18 INFO - Installing pip.................done. 05:23:18 INFO - Return code: 0 05:23:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:18 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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:18 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 05:23:18 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 05:23:18 INFO - Using env: {'DISPLAY': ':0', 05:23:18 INFO - 'HOME': '/home/cltbld', 05:23:18 INFO - 'LANG': 'en_US.UTF-8', 05:23:18 INFO - 'LANGUAGE': 'en_US:en', 05:23:18 INFO - 'LOGNAME': 'cltbld', 05:23:18 INFO - 'MAIL': '/var/mail/cltbld', 05:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:18 INFO - 'MOZ_NO_REMOTE': '1', 05:23:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:18 INFO - 'NO_EM_RESTART': '1', 05:23:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:18 INFO - 'PWD': '/builds/slave/test', 05:23:18 INFO - 'SHELL': '/bin/bash', 05:23:18 INFO - 'SHLVL': '1', 05:23:18 INFO - 'TERM': 'linux', 05:23:18 INFO - 'TMOUT': '86400', 05:23:18 INFO - 'USER': 'cltbld', 05:23:18 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:18 INFO - '_': '/tools/buildbot/bin/python'} 05:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:18 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 05:23:18 INFO - Cleaning up... 05:23:18 INFO - Return code: 0 05:23:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:18 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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:18 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 05:23:18 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 05:23:18 INFO - Using env: {'DISPLAY': ':0', 05:23:18 INFO - 'HOME': '/home/cltbld', 05:23:18 INFO - 'LANG': 'en_US.UTF-8', 05:23:18 INFO - 'LANGUAGE': 'en_US:en', 05:23:18 INFO - 'LOGNAME': 'cltbld', 05:23:18 INFO - 'MAIL': '/var/mail/cltbld', 05:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:18 INFO - 'MOZ_NO_REMOTE': '1', 05:23:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:18 INFO - 'NO_EM_RESTART': '1', 05:23:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:18 INFO - 'PWD': '/builds/slave/test', 05:23:18 INFO - 'SHELL': '/bin/bash', 05:23:18 INFO - 'SHLVL': '1', 05:23:18 INFO - 'TERM': 'linux', 05:23:18 INFO - 'TMOUT': '86400', 05:23:18 INFO - 'USER': 'cltbld', 05:23:18 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:18 INFO - '_': '/tools/buildbot/bin/python'} 05:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:18 INFO - Downloading/unpacking psutil>=3.1.1 05:23:18 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 05:23:18 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 05:23:18 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 05:23:18 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 05:23:18 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 05:23:18 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 05:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:20 INFO - Installing collected packages: psutil 05:23:20 INFO - Running setup.py install for psutil 05:23:20 INFO - building 'psutil._psutil_linux' extension 05:23:20 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 05:23:20 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 05:23:20 INFO - building 'psutil._psutil_posix' extension 05:23:20 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 05:23:21 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 05:23:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:21 INFO - Successfully installed psutil 05:23:21 INFO - Cleaning up... 05:23:21 INFO - Return code: 0 05:23:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:21 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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:21 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 05:23:21 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 05:23:21 INFO - Using env: {'DISPLAY': ':0', 05:23:21 INFO - 'HOME': '/home/cltbld', 05:23:21 INFO - 'LANG': 'en_US.UTF-8', 05:23:21 INFO - 'LANGUAGE': 'en_US:en', 05:23:21 INFO - 'LOGNAME': 'cltbld', 05:23:21 INFO - 'MAIL': '/var/mail/cltbld', 05:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:21 INFO - 'MOZ_NO_REMOTE': '1', 05:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:21 INFO - 'NO_EM_RESTART': '1', 05:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:21 INFO - 'PWD': '/builds/slave/test', 05:23:21 INFO - 'SHELL': '/bin/bash', 05:23:21 INFO - 'SHLVL': '1', 05:23:21 INFO - 'TERM': 'linux', 05:23:21 INFO - 'TMOUT': '86400', 05:23:21 INFO - 'USER': 'cltbld', 05:23:21 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:21 INFO - '_': '/tools/buildbot/bin/python'} 05:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:23:21 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 05:23:21 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 05:23:21 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 05:23:21 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 05:23:21 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 05:23:21 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 05:23:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:23:23 INFO - Installing collected packages: mozsystemmonitor 05:23:23 INFO - Running setup.py install for mozsystemmonitor 05:23:23 INFO - Successfully installed mozsystemmonitor 05:23:23 INFO - Cleaning up... 05:23:23 INFO - Return code: 0 05:23:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:23:23 INFO - Using env: {'DISPLAY': ':0', 05:23:23 INFO - 'HOME': '/home/cltbld', 05:23:23 INFO - 'LANG': 'en_US.UTF-8', 05:23:23 INFO - 'LANGUAGE': 'en_US:en', 05:23:23 INFO - 'LOGNAME': 'cltbld', 05:23:23 INFO - 'MAIL': '/var/mail/cltbld', 05:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:23 INFO - 'MOZ_NO_REMOTE': '1', 05:23:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:23 INFO - 'NO_EM_RESTART': '1', 05:23:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:23 INFO - 'PWD': '/builds/slave/test', 05:23:23 INFO - 'SHELL': '/bin/bash', 05:23:23 INFO - 'SHLVL': '1', 05:23:23 INFO - 'TERM': 'linux', 05:23:23 INFO - 'TMOUT': '86400', 05:23:23 INFO - 'USER': 'cltbld', 05:23:23 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:23 INFO - '_': '/tools/buildbot/bin/python'} 05:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:23 INFO - Downloading/unpacking jsonschema==2.5.1 05:23:23 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 05:23:23 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 05:23:23 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 05:23:23 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 05:23:23 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 05:23:23 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 05:23:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:23:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:23:25 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 05:23:25 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 05:23:25 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 05:23:25 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 05:23:25 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 05:23:25 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 05:23:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:23:25 INFO - warning: no files found matching '*.txt' 05:23:25 INFO - no previously-included directories found matching 'build' 05:23:25 INFO - no previously-included directories found matching 'dist' 05:23:25 INFO - no previously-included directories found matching '.git*' 05:23:25 INFO - Installing collected packages: jsonschema, functools32 05:23:25 INFO - Running setup.py install for functools32 05:23:26 INFO - warning: no files found matching '*.txt' 05:23:26 INFO - no previously-included directories found matching 'build' 05:23:26 INFO - no previously-included directories found matching 'dist' 05:23:26 INFO - no previously-included directories found matching '.git*' 05:23:26 INFO - Successfully installed jsonschema functools32 05:23:26 INFO - Cleaning up... 05:23:26 INFO - Return code: 0 05:23:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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', '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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:23: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 functools32==3.2.3-2 05:23:26 INFO - Using env: {'DISPLAY': ':0', 05:23:26 INFO - 'HOME': '/home/cltbld', 05:23:26 INFO - 'LANG': 'en_US.UTF-8', 05:23:26 INFO - 'LANGUAGE': 'en_US:en', 05:23:26 INFO - 'LOGNAME': 'cltbld', 05:23:26 INFO - 'MAIL': '/var/mail/cltbld', 05:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:26 INFO - 'MOZ_NO_REMOTE': '1', 05:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:26 INFO - 'NO_EM_RESTART': '1', 05:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:26 INFO - 'PWD': '/builds/slave/test', 05:23:26 INFO - 'SHELL': '/bin/bash', 05:23:26 INFO - 'SHLVL': '1', 05:23:26 INFO - 'TERM': 'linux', 05:23:26 INFO - 'TMOUT': '86400', 05:23:26 INFO - 'USER': 'cltbld', 05:23:26 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:26 INFO - '_': '/tools/buildbot/bin/python'} 05:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:23:26 INFO - Cleaning up... 05:23:26 INFO - Return code: 0 05:23:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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', '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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:23: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 blobuploader==1.2.4 05:23:26 INFO - Using env: {'DISPLAY': ':0', 05:23:26 INFO - 'HOME': '/home/cltbld', 05:23:26 INFO - 'LANG': 'en_US.UTF-8', 05:23:26 INFO - 'LANGUAGE': 'en_US:en', 05:23:26 INFO - 'LOGNAME': 'cltbld', 05:23:26 INFO - 'MAIL': '/var/mail/cltbld', 05:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:26 INFO - 'MOZ_NO_REMOTE': '1', 05:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:26 INFO - 'NO_EM_RESTART': '1', 05:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:26 INFO - 'PWD': '/builds/slave/test', 05:23:26 INFO - 'SHELL': '/bin/bash', 05:23:26 INFO - 'SHLVL': '1', 05:23:26 INFO - 'TERM': 'linux', 05:23:26 INFO - 'TMOUT': '86400', 05:23:26 INFO - 'USER': 'cltbld', 05:23:26 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:26 INFO - '_': '/tools/buildbot/bin/python'} 05:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:28 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23:28 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 05:23:28 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 05:23:28 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 05:23:28 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 05:23:28 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 05:23:28 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 05:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23:28 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 05:23:28 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 05:23:28 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 05:23:28 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 05:23:28 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 05:23:28 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 05:23:29 INFO - Downloading docopt-0.6.1.tar.gz 05:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:29 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:29 INFO - Running setup.py install for blobuploader 05:23:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:29 INFO - Running setup.py install for requests 05:23:29 INFO - Running setup.py install for docopt 05:23:29 INFO - Successfully installed blobuploader requests docopt 05:23:29 INFO - Cleaning up... 05:23:29 INFO - Return code: 0 05:23:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:29 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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:29 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 05:23:29 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 05:23:29 INFO - Using env: {'DISPLAY': ':0', 05:23:29 INFO - 'HOME': '/home/cltbld', 05:23:29 INFO - 'LANG': 'en_US.UTF-8', 05:23:29 INFO - 'LANGUAGE': 'en_US:en', 05:23:29 INFO - 'LOGNAME': 'cltbld', 05:23:29 INFO - 'MAIL': '/var/mail/cltbld', 05:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:29 INFO - 'MOZ_NO_REMOTE': '1', 05:23:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:29 INFO - 'NO_EM_RESTART': '1', 05:23:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:29 INFO - 'PWD': '/builds/slave/test', 05:23:29 INFO - 'SHELL': '/bin/bash', 05:23:29 INFO - 'SHLVL': '1', 05:23:29 INFO - 'TERM': 'linux', 05:23:29 INFO - 'TMOUT': '86400', 05:23:29 INFO - 'USER': 'cltbld', 05:23:29 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:29 INFO - '_': '/tools/buildbot/bin/python'} 05:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:29 INFO - Running setup.py (path:/tmp/pip-ZVnxY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:30 INFO - Running setup.py (path:/tmp/pip-gPa0D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:30 INFO - Running setup.py (path:/tmp/pip-AICmgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:30 INFO - Running setup.py (path:/tmp/pip-mVGVLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:30 INFO - Running setup.py (path:/tmp/pip-PFNIu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:30 INFO - Running setup.py (path:/tmp/pip-qctGmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:30 INFO - Running setup.py (path:/tmp/pip-21ovy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:30 INFO - Running setup.py (path:/tmp/pip-AYHAyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:30 INFO - Running setup.py (path:/tmp/pip-yNNqqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:30 INFO - Running setup.py (path:/tmp/pip-F2ck8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:30 INFO - Running setup.py (path:/tmp/pip-IwYPWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:30 INFO - Running setup.py (path:/tmp/pip-xPcw40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:30 INFO - Running setup.py (path:/tmp/pip-xfAnDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:30 INFO - Running setup.py (path:/tmp/pip-ZtvGmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:30 INFO - Running setup.py (path:/tmp/pip-mQcH5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:31 INFO - Running setup.py (path:/tmp/pip-TKlC58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:31 INFO - Running setup.py (path:/tmp/pip-A2mXG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:31 INFO - Running setup.py install for manifestparser 05:23:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:31 INFO - Running setup.py install for mozcrash 05:23:31 INFO - Running setup.py install for mozdebug 05:23:31 INFO - Running setup.py install for mozdevice 05:23:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:31 INFO - Running setup.py install for mozfile 05:23:31 INFO - Running setup.py install for mozhttpd 05:23:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:31 INFO - Running setup.py install for mozinfo 05:23:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:31 INFO - Running setup.py install for mozInstall 05:23:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Running setup.py install for mozleak 05:23:32 INFO - Running setup.py install for mozlog 05:23:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Running setup.py install for moznetwork 05:23:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Running setup.py install for mozprocess 05:23:32 INFO - Running setup.py install for mozprofile 05:23:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Running setup.py install for mozrunner 05:23:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Running setup.py install for mozscreenshot 05:23:32 INFO - Running setup.py install for moztest 05:23:32 INFO - Running setup.py install for mozversion 05:23:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:32 INFO - Cleaning up... 05:23:32 INFO - Return code: 0 05:23:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:32 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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:32 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 05:23:32 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 05:23:32 INFO - Using env: {'DISPLAY': ':0', 05:23:33 INFO - 'HOME': '/home/cltbld', 05:23:33 INFO - 'LANG': 'en_US.UTF-8', 05:23:33 INFO - 'LANGUAGE': 'en_US:en', 05:23:33 INFO - 'LOGNAME': 'cltbld', 05:23:33 INFO - 'MAIL': '/var/mail/cltbld', 05:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:33 INFO - 'MOZ_NO_REMOTE': '1', 05:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:33 INFO - 'NO_EM_RESTART': '1', 05:23:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:33 INFO - 'PWD': '/builds/slave/test', 05:23:33 INFO - 'SHELL': '/bin/bash', 05:23:33 INFO - 'SHLVL': '1', 05:23:33 INFO - 'TERM': 'linux', 05:23:33 INFO - 'TMOUT': '86400', 05:23:33 INFO - 'USER': 'cltbld', 05:23:33 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:33 INFO - '_': '/tools/buildbot/bin/python'} 05:23:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:33 INFO - Running setup.py (path:/tmp/pip-1FjuGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:33 INFO - Running setup.py (path:/tmp/pip-mSsKro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:33 INFO - Running setup.py (path:/tmp/pip-mtNR8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:33 INFO - Running setup.py (path:/tmp/pip-hWKRFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:33 INFO - Running setup.py (path:/tmp/pip-wwiPk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:33 INFO - Running setup.py (path:/tmp/pip-pa8V9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:33 INFO - Running setup.py (path:/tmp/pip-BFfct7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:33 INFO - Running setup.py (path:/tmp/pip-LJG3qU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:33 INFO - Running setup.py (path:/tmp/pip-7VYcmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:33 INFO - Running setup.py (path:/tmp/pip-IkUTRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:33 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)) 05:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:33 INFO - Running setup.py (path:/tmp/pip-SkLcq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:34 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)) 05:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:34 INFO - Running setup.py (path:/tmp/pip-AhLV4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:34 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)) 05:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:34 INFO - Running setup.py (path:/tmp/pip-ZM6T0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:34 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)) 05:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:34 INFO - Running setup.py (path:/tmp/pip-0EvE8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:34 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)) 05:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:34 INFO - Running setup.py (path:/tmp/pip-Sct0Xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:34 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)) 05:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:34 INFO - Running setup.py (path:/tmp/pip-B7SUSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:34 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)) 05:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:34 INFO - Running setup.py (path:/tmp/pip-F9KxL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:34 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)) 05:23:34 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)) 05:23:34 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)) 05:23:34 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)) 05:23:34 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)) 05:23:34 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)) 05:23:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:36 INFO - Downloading blessings-1.6.tar.gz 05:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:36 INFO - Installing collected packages: blessings 05:23:36 INFO - Running setup.py install for blessings 05:23:36 INFO - Successfully installed blessings 05:23:36 INFO - Cleaning up... 05:23:36 INFO - Return code: 0 05:23:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:36 INFO - Reading from file tmpfile_stdout 05:23:36 INFO - Current package versions: 05:23:36 INFO - argparse == 1.2.1 05:23:36 INFO - blessings == 1.6 05:23:36 INFO - blobuploader == 1.2.4 05:23:36 INFO - docopt == 0.6.1 05:23:36 INFO - functools32 == 3.2.3-2 05:23:36 INFO - jsonschema == 2.5.1 05:23:36 INFO - manifestparser == 1.1 05:23:36 INFO - mozInstall == 1.12 05:23:36 INFO - mozcrash == 0.17 05:23:36 INFO - mozdebug == 0.1 05:23:36 INFO - mozdevice == 0.48 05:23:36 INFO - mozfile == 1.2 05:23:36 INFO - mozhttpd == 0.7 05:23:36 INFO - mozinfo == 0.9 05:23:36 INFO - mozleak == 0.1 05:23:36 INFO - mozlog == 3.4 05:23:36 INFO - moznetwork == 0.27 05:23:36 INFO - mozprocess == 0.23 05:23:36 INFO - mozprofile == 0.28 05:23:36 INFO - mozrunner == 6.12 05:23:36 INFO - mozscreenshot == 0.1 05:23:36 INFO - mozsystemmonitor == 0.3 05:23:36 INFO - moztest == 0.7 05:23:36 INFO - mozversion == 1.4 05:23:36 INFO - psutil == 3.1.1 05:23:36 INFO - requests == 1.2.3 05:23:36 INFO - wsgiref == 0.1.2 05:23:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:36 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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:36 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 05:23:36 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 05:23:36 INFO - Using env: {'DISPLAY': ':0', 05:23:36 INFO - 'HOME': '/home/cltbld', 05:23:36 INFO - 'LANG': 'en_US.UTF-8', 05:23:36 INFO - 'LANGUAGE': 'en_US:en', 05:23:36 INFO - 'LOGNAME': 'cltbld', 05:23:36 INFO - 'MAIL': '/var/mail/cltbld', 05:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:36 INFO - 'MOZ_NO_REMOTE': '1', 05:23:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:36 INFO - 'NO_EM_RESTART': '1', 05:23:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:36 INFO - 'PWD': '/builds/slave/test', 05:23:36 INFO - 'SHELL': '/bin/bash', 05:23:36 INFO - 'SHLVL': '1', 05:23:36 INFO - 'TERM': 'linux', 05:23:36 INFO - 'TMOUT': '86400', 05:23:36 INFO - 'USER': 'cltbld', 05:23:36 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:36 INFO - '_': '/tools/buildbot/bin/python'} 05:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 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)) 05:23:37 INFO - Cleaning up... 05:23:37 INFO - Return code: 0 05:23:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23:37 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')]} 05:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:37 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')]} 05:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:37 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 0x7f2230ccff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', '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 05:23:37 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 05:23:37 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 05:23:37 INFO - Using env: {'DISPLAY': ':0', 05:23:37 INFO - 'HOME': '/home/cltbld', 05:23:37 INFO - 'LANG': 'en_US.UTF-8', 05:23:37 INFO - 'LANGUAGE': 'en_US:en', 05:23:37 INFO - 'LOGNAME': 'cltbld', 05:23:37 INFO - 'MAIL': '/var/mail/cltbld', 05:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:37 INFO - 'MOZ_NO_REMOTE': '1', 05:23:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:37 INFO - 'NO_EM_RESTART': '1', 05:23:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:37 INFO - 'PWD': '/builds/slave/test', 05:23:37 INFO - 'SHELL': '/bin/bash', 05:23:37 INFO - 'SHLVL': '1', 05:23:37 INFO - 'TERM': 'linux', 05:23:37 INFO - 'TMOUT': '86400', 05:23:37 INFO - 'USER': 'cltbld', 05:23:37 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:37 INFO - '_': '/tools/buildbot/bin/python'} 05:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:37 INFO - Cleaning up... 05:23:37 INFO - Return code: 0 05:23:37 INFO - Running post-action listener: _resource_record_post_action 05:23:37 INFO - Running post-action listener: _start_resource_monitoring 05:23:37 INFO - Starting resource monitoring. 05:23:37 INFO - [mozharness: 2016-10-04 12:23:37.458411Z] Finished create-virtualenv step (success) 05:23:37 INFO - [mozharness: 2016-10-04 12:23:37.458915Z] Running install step. 05:23:37 INFO - Running pre-action listener: _resource_record_pre_action 05:23:37 INFO - Running main action method: install 05:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:37 INFO - Reading from file tmpfile_stdout 05:23:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:37 INFO - Reading from file tmpfile_stdout 05:23:37 INFO - Output received: 05:23:37 INFO - Usage: mozinstall [options] installer 05:23:37 INFO - Options: 05:23:37 INFO - -h, --help show this help message and exit 05:23:37 INFO - -d DEST, --destination=DEST 05:23:37 INFO - Directory to install application into. [default: 05:23:37 INFO - "/builds/slave/test"] 05:23:37 INFO - --app=APP Application being installed. [default: firefox] 05:23:37 INFO - mkdir: /builds/slave/test/build/application 05:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:23:53 INFO - Reading from file tmpfile_stdout 05:23:53 INFO - Output received: 05:23:53 INFO - /builds/slave/test/build/application/firefox/firefox 05:23:53 INFO - Running post-action listener: _resource_record_post_action 05:23:53 INFO - [mozharness: 2016-10-04 12:23:53.713139Z] Finished install step (success) 05:23:53 INFO - [mozharness: 2016-10-04 12:23:53.713384Z] Running run-tests step. 05:23:53 INFO - Running pre-action listener: _resource_record_pre_action 05:23:53 INFO - Running main action method: run_tests 05:23:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:23:53 INFO - Python 2.7.3 05:23:53 INFO - Return code: 0 05:23:53 INFO - grabbing minidump binary from tooltool 05:23:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:53 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 0x13d1f60>, '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 0x13d2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13d31d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:23:53 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 05:23:53 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 05:23:53 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 05:23:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:23:53 INFO - Return code: 0 05:23:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:23:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:23:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:23:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:23:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:23:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 05:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 05:23:53 INFO - Using env: {'DISPLAY': ':0', 05:23:53 INFO - 'HOME': '/home/cltbld', 05:23:53 INFO - 'LANG': 'en_US.UTF-8', 05:23:53 INFO - 'LANGUAGE': 'en_US:en', 05:23:53 INFO - 'LOGNAME': 'cltbld', 05:23:53 INFO - 'MAIL': '/var/mail/cltbld', 05:23:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:23:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:53 INFO - 'MOZ_NO_REMOTE': '1', 05:23:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:23:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:53 INFO - 'NO_EM_RESTART': '1', 05:23:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:53 INFO - 'PWD': '/builds/slave/test', 05:23:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:23:53 INFO - 'SHELL': '/bin/bash', 05:23:53 INFO - 'SHLVL': '1', 05:23:53 INFO - 'TERM': 'linux', 05:23:53 INFO - 'TMOUT': '86400', 05:23:53 INFO - 'USER': 'cltbld', 05:23:53 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994', 05:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:53 INFO - '_': '/tools/buildbot/bin/python'} 05:23:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 05:23:54 INFO - mozversion application_buildid: 20161004023801 05:23:54 INFO - mozversion application_changeset: 581e2e9b9d79cbaf4e333447e5966e03b92a2eb7 05:23:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:23:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:23:54 INFO - mozversion application_name: Firefox 05:23:54 INFO - mozversion application_remotingname: firefox-dev 05:23:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:23:54 INFO - mozversion application_vendor: Mozilla 05:23:54 INFO - mozversion application_version: 51.0a2 05:23:54 INFO - mozversion platform_buildid: 20161004023801 05:23:54 INFO - mozversion platform_changeset: 581e2e9b9d79cbaf4e333447e5966e03b92a2eb7 05:23:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:23:54 INFO - mozversion platform_version: 51.0a2 05:23:54 INFO - using testdate: 1475583834 05:23:54 INFO - actual date: 1475583834 05:23:54 INFO - starting webserver on 'localhost:46619' 05:23:54 INFO - SUITE-START | Running 2 tests 05:23:54 INFO - TEST-START | dromaeo_css 05:23:54 INFO - operating with platform_type : linux_ 05:23:54 INFO - Initialising browser for dromaeo_css test... 05:23:54 INFO - TEST-INFO | started process 3759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp68LDaa/profile http://localhost:46619/getInfo.html) 05:23:58 INFO - PROCESS | 3759 | __metrics Screen width/height:1600/1200 05:23:58 INFO - PROCESS | 3759 | colorDepth:24 05:23:58 INFO - PROCESS | 3759 | Browser inner width/height: 1024/697 05:23:58 INFO - PROCESS | 3759 | __metrics 05:23:58 INFO - PROCESS | 3759 | 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" 05:23:58 INFO - PROCESS | 3759 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:23:59 INFO - PROCESS | 3759 | console.error: PushService: 05:23:59 INFO - PROCESS | 3759 | stateChangeProcessEnqueue: Error transitioning state 05:23:59 INFO - PROCESS | 3759 | UnknownError 05:23:59 INFO - TEST-INFO | 3759: exit 0 05:23:59 INFO - Browser initialized. 05:23:59 INFO - Running cycle 1/1 for dromaeo_css test... 05:23:59 INFO - TEST-INFO | started process 3849 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp68LDaa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:11 INFO - PROCESS | 3849 | RSS: Main: 143749120 05:24:11 INFO - PROCESS | 3849 | 05:25:02 INFO - PROCESS | 3849 | 1475583902571 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] 05:27:11 INFO - PROCESS | 3849 | Cycle 1(1): loaded http://localhost:46619/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46619/tests/dromaeo/cssquery-ext.html) 05:27:12 INFO - PROCESS | 3849 | RSS: Main: 241983488 05:27:12 INFO - PROCESS | 3849 | 05:30:14 INFO - PROCESS | 3849 | Cycle 1(1): loaded http://localhost:46619/tests/dromaeo/cssquery-ext.html (next: http://localhost:46619/tests/dromaeo/cssquery-jquery.html) 05:30:15 INFO - PROCESS | 3849 | RSS: Main: 233201664 05:30:15 INFO - PROCESS | 3849 | 05:33:17 INFO - PROCESS | 3849 | Cycle 1(1): loaded http://localhost:46619/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46619/tests/dromaeo/cssquery-mootools.html) 05:33:17 INFO - PROCESS | 3849 | RSS: Main: 175067136 05:33:17 INFO - PROCESS | 3849 | 05:36:19 INFO - PROCESS | 3849 | Cycle 1(1): loaded http://localhost:46619/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46619/tests/dromaeo/cssquery-prototype.html) 05:36:20 INFO - PROCESS | 3849 | RSS: Main: 174153728 05:36:20 INFO - PROCESS | 3849 | 05:39:23 INFO - PROCESS | 3849 | Cycle 1(1): loaded http://localhost:46619/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46619/tests/dromaeo/cssquery-yui.html) 05:39:23 INFO - PROCESS | 3849 | RSS: Main: 176320512 05:39:23 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | Cycle 1(1): loaded http://localhost:46619/tests/dromaeo/cssquery-yui.html (next: http://localhost:46619/tests/dromaeo/cssquery-dojo.html) 05:42:26 INFO - PROCESS | 3849 | RSS: Main: 267444224 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | __start_tp_report 05:42:26 INFO - PROCESS | 3849 | _x_x_mozilla_page_load 05:42:26 INFO - PROCESS | 3849 | _x_x_mozilla_page_load_details 05:42:26 INFO - PROCESS | 3849 | |i|pagename|runs| 05:42:26 INFO - PROCESS | 3849 | |0;dojo.html;3039.908802735918;3125.8905938292164;3134.326119884223;3156.037408590379;3163.873445062198;5617.691026993519;5681.176229446728;5683.204351390808;5715.2855893013375;5748.971255143723;5618.5786066045;5688.744006519706;5691.9146212806845;5698.059820129684;5711.628744131639;5122.897542049148;5165.173572228444;5178.559822415099;5183.637145399824;5231.189165679326;5008.649394542384;5038.445770965209;5250.6587071840395;5254.7635356409055;5268.868278293055;5076.771545280453;5078.822241221558;5116.513931176532;5150.227465880111;5203.5056669616515;5182.8704282393055;5194.402643695986;5281.86795330116;5283.049051170788;5311.415744268127;4937.604991600663;5079.568236699885;5156.690598564098;5222.190560463135;5239.869003274911;5472.151816468454;5475.178723191513;5487;5519.448055194489;5542.307211598551;5869.823905282806;5878.030125029408;5967.940320596782;5975.43233392827;5975.940240597582;5893.14549390336;5998.1602575639445;6012.008018676913;6024.246969128859;6026.819195424145;5507.339119305669;5533.225348451179;5554.389017208104;5579.609427340088;5600.131979543177;6152.969235153885;6160.168377269101;6170.5372097092895;6176.258848938156;6179.81460556184;5729.9713501432225;5895.911561326584;5908.084246941731;5910.970445147746;5917.6745279010065;5820.854478638068;5936.851578710481;5970.34326224115;5993.460588547052;6066.363031881677;6017;6026.0659597762415;6027.336992930774;6028.09578563219;6039.245094363205;6012.068129439944;6067.180930574405;6073.969630151821;6077.817665470043;6099.96950015256;4481.75350355727;4509.819607215674;4565.474970378383;4599.839005634787;4614.630829533629;4076.3477843544892;4083.4283200351974;4091.0386059276047;4110.815013324408;4128.174365126986;5722.198892155102;5728.77084916607;5730.426957304237;5733.0540460823495;5743.167240750151;5811.099279611667;5858.150568167592;6039.275286965592;6097.024476083805;6099.237595300588;5821.88356232873;5826.854328641818;5842.795502157404;5853.853653658607;5858.53131749459;5300.1254792958725;5306.734663266821;5309.5752339812725;5313.734313284321;5315.468453154654;6011.489023433057;6024.969875150771;6262.373762623793;6287.9056814147825;6328.810135695844;5999.70001499932;6017.398260173947;6024.548158888013;6100.786472473442;6143.416375444325;6218.284897236876;6269.310375858743;6276.623402595676;6277.843053923689;6278.309385967448;5649.067903795828;5983.282006159115;5985.760569577343;5987.820365389045;6027.21646185993;5881;5961.8807623846415;5993.490553303018;6015.39846015395;6096.177016102769;5861.296644402699;5908;5912.260967379078;5918.082697181943;5922.289325280994;5892.086726557391;5894.2926848778425;5896.262967129109;5900.380460051632;5906.379830117776;5653.293338332708;5802.854928626818;5824.068149096124;5839.0949402842625;5877.94122058774;5952.136940143741;5958;5961.642301461926;5962.373950735284;5994.100884867305;5634.154876768517;5806.274215723035;5811.186433899173;5813.389594092729;5816.592838501264 05:42:26 INFO - PROCESS | 3849 | |1;ext.html;590.630855715178;609.7530500147441;629.4932579273683;659.0707102984792;663.2538394306405;35519.46720799187;46884.76557617211;48043.75978120113;48144.51855481449;48158;50545.74727126368;51010;51023;51027;51079.48920510794;8685.348598855111;9525.333226674118;9676.17752491039;9681.806363872718;9691.176250018756;8519.787005324855;8795.252403545706;8796.824063518727;11147.777044459106;11438.370889601068;11369;11370.374629395379;11425.31448113116;11435.5425782969;11440.256383335098;11357.943210283936;11427.771444571144;11432;11439.828402573969;11440.313581185113;11248.718782030433;11317.434128293635;11321.603743869007;11323.773524529463;11329.943350283278;47182.76408617935;47468.5253147471;47544.7622761884;47604;47613.28580071302;3930.3121953658283;3943.3493473576827;3995.3407687731487;4188.979055104705;4673.789679464432;36211.4568281476;36218.63781362205;36506;36791.26417471636;38083.4287485688;4753.928691069636;4772.2125849234835;4825.662203645747;4839.758012099381;4896.632752543538;406843;406934;408818;408946;410021;69716;69784.6510767443;69950.65024674844;70091.64954175298;70104.6494767523;41865.79067104645;42737.572624273984;42763;44202.778986105506;44396;20351.185952562028;20610;20653.793462065187;20654.896725516275;20677.379678609665;14969.775453368207;14997.475088372074;15016.624584385478;15043.398264069534;15066.472673456596;19140.521486962658;19541.902290488455;20548;21317.78682213189;21365.572688546454;7509.737159199511;7521.7743467696055;7581.355584775356;7628.122765881883;7706.998090248232;951.1249650321721;996.8454889492141;1103.3049179017328;1124.791913496006;1193.1349771415655;36020.74211336467;38547;39076.218475630325;39354;39975;1131.4173200801595;1189.2685998111272;1190.9702257443632;1211.8485189351331;1213.7936550786387;18711.625767484846;18713.345032923782;18829.623407531773;19025.14386852595;19058.714119288223;607957;612885;613941;617322;617518;65071.34928650653;65635;65644.34355656382;65806.36774528965;65832.34167658357;1284.531146131674;1286.5368467351745;1394.637394277495;1466.875315598186;1467.618419211013;33788.493172602924;37203.255934881694;37410;37507.81246093752;37585.24829503449;56671.716641416526;57314.42685573091;57375.71312143413;57518.42481575298;57593.42406575881;440040;441585;442375;443788;443913;31728.206794830316;32017.199570010005;32709.672903270664;32749;34570.308593828486;53457.7327113362;54529.72735136299;54620.72689636526;54674;54835;45104.548954510035;45935.54064459313;46656.533434665216;46669.53330466788;48066;46310.768446157555;46593.76703116463;46712;47765.04469910513;47780.2832957499;45375.31937020881;45540.772296138304;46732;46784;46823.29765053459;37960.240795184494;40296.3955540673;40326;41028.34935977328;41123.1775364497;30480.390392192476;30484.237894052825;30523.694763052084;30964.53553196749;30996.380072397973 05:42:26 INFO - PROCESS | 3849 | |2;jquery.html;391.5698481303778;413.95446500884896;424.3274410060055;431.8380607272273;442.3519543868234;14840.925795371022;21196.152153913845;21219.99999999998;21243.893780531114;22681.54636907261;21198.364049078515;21206.89396553019;21210.469738256514;21250.787492125073;21569.46076348088;5094.439611642716;5705.229793977812;6013.037913933749;6051.243594550681;6079.118527813464;5831.650100993933;6013.609115407507;6080;6098.176746139281;6102.267727872661;5980.342162362137;6009.57932944694;6016.548758843082;6021.909671354912;6023.126646636235;5890.911636325459;5936.584439089265;5951.256092988377;6000.309964354112;6001.129836173752;5240.633155679104;5357.464253574612;5360.892782144335;5362.812301569426;5365.973170134163;20325.593488130304;25373.11194108216;25516;25575.872120639277;25673.74326256732;2637.3933995181023;2694.8652567371555;2768.5847122931723;2970.960163942603;3120.7815452918303;7436.0333156689285;7444.9627751861435;7511.399088072995;8278.130796266365;12529.74940501194;2018.6467368210504;2039.7042428847883;2056.44475991483;2057.5267688431745;2100.19142630087;85352;85739.23979877056;86223.56888215581;87510.56244718736;91626.54186729023;10275.845862312071;10417.593624860698;10642.467876606293;10680;10931.56273749057;15488.457903973307;15533.378664853506;15533.766993495106;15550.76673849893;15551.066935983878;8797.29621630268;9005.594748236344;9023.368364214573;9059.68291109808;9179.173874351207;8993.190612844744;9012.684556040638;9035.141661542142;9199.126083022102;9257.67598134062;6795.762148324785;6842.6578671066245;6869.725210991604;6935.791926242221;6955.965220173968;1894.043508028437;1915.2913422033937;1940.5245714799682;1964.9312274070626;1979.3962841333532;895.6506962284714;1040.292601031306;1058.571278632155;1058.687687132298;1145.5990403358758;20949.37151885446;21402.57194856094;21569.892150539148;21986.45033874166;21997.560048798936;1086.9510872010778;1099.7855418193376;1134.9432528373547;1144.6165534546021;1165.6444784340688;11776.352300623548;11923.821142682868;12175.208611440228;12305.50777968889;12337.568185113478;427287;427623;450024;469677;472112;87183.56408218046;89307;89759.55120224357;92828.53585732164;104036.43945340825;1078.7464945737745;1083.2742062818088;1123.8819923908036;1125.1898632984403;1125.431657013203;17570.648587028445;18014.189361478504;18559.628807423505;18665.346712864986;18988;35150.12124696903;42795.14409711851;43857.56142438535;44487.77756111199;45125.32312015388;60501.09248361366;61196;62760;63160;63698.68150659217;8438.746837594881;8447.366447516584;8509.87235191485;8549.358798090043;8757.693480728143;9457.716268511957;12011.219270747373;12223.144379893321;12590.748185036431;13326.133801302884;11483.138764592522;11638.35989020612;11988.880111199125;12004.75990480203;12354.876451235372;10672.466376680979;10690.572377104829;11087.833682494607;11425.542978281108;11755.529778808752;8514.78713032179;8651.134886511298;9090.500022498562;11489;12258.570950016707;11600.767984640428;11748.823767643664;12301.876981230072;12318.384080795746;12511.749765004466;5826.679532625746;7286.96356518214;12056.336901470504;12499.812502812283;12814.359282036048 05:42:26 INFO - PROCESS | 3849 | |3;mootools.html;1400.7548678981175;1493.3354657177556;1553.4873491747724;1746.56335916021;1872.4101907899003;8029.07665618454;8593.91406085939;8969.327300452467;8976.551172441385;9236.95381523093;8995.460272383662;9011.774705632362;9073;9076.22852057576;9084.909150908488;2500.662410574572;2512.798976081909;2522.054229663876;2528.9241322760254;2531.0382054819233;2438.2563318187945;2528.671272734551;2536.3405514566252;2539.1240022192405;2541.872906354675;2431.659567660529;2436.2325867351815;2491.6262560615874;2503.173952595639;2505.473850491399;2478.776910078084;2499.7000359956833;2504.373906523369;2507.1976967370447;2515.811314151446;2472.8763561822016;2491.4145175883655;2500.249925022486;2504.4239824840206;2505.5114252720728;8768.342374321888;8923.598438070303;8968.50673212973;9017.954910225473;9044.41211321262;5415.675059496442;5593.608447408683;5599.216109744639;5603.719814009283;5606.439356064402;3442.345954268681;3461.2904354607244;3466.532018177539;3496.0735405117666;3508.9298214035575;1107.164091111214;1119.6920846767123;1125.5329038449033;1134.4951496584024;1156.6298784388998;12257.693557661041;12302.07734419922;12332.630021099381;12342.074344424203;12356.938215308955;4564.338170965191;4687.179743544866;4794.089123066607;4811;4819.518048195152;9945.751356216153;9949.502524873727;9953.651622193333;9956.502174891226;9956.551955162035;5311.521963023347;5318.73406329682;5322.840314790485;5326.041312563776;5329.760160792774;4656.41794775653;4827.106985207748;4945.7774400152075;4990.426100998359;5063.974680126576;4106.466159399319;4169.56219596944;4181.477315335583;4192.329227323614;4193.119884354703;1250.0437165568349;1258.4148371007525;1263.1599986009385;1267.4739982907174;1279.539365828301;837.388706244445;910.9089091090838;938.8685584018243;955.8231727130365;963.4728954606886;5913.098673966275;5993.940060599425;6106.4809491192855;6206.718909440358;6218.22272215973;486.92939523768854;490.394362961742;491.84015195061033;497.21688340863284;501.72655902533205;4211.663066954637;4316.719413238192;4340.891477713082;4358.978205108954;4359.520452750195;10952.835707464394;11278.830817537579;11354.375509347065;11363;11366.261193022427;10348.22388320879;10825.837612435662;11281.32312061279;11282.210245282751;11290.266132701512;8238.846561481398;8346.4157508975;8381.994160700633;8407.201315874981;8412.579371031547;6006.15913772081;6016.42843929826;6170.290416601969;6264.59280146789;6296.937030629635;9740.415575065519;9756.658516951873;9779.070988256104;9920;9931.404115753077;9911.207103431563;9927.602895884373;9975.251856110675;10062.547185376528;10159.815639637514;7246.202917679157;7479.775606731807;7879.172686867796;8187.672493100209;8331.625076871434;8865.157810008039;8973.28213742912;8978.416402933788;8978.551072446482;8985.326100542356;7834.72578459774;7845.411594130349;7901.367890568856;8139.4709343892455;8146.75559733209;7744.2642948919765;7777.494462859896;7813.4530582860925;7820.491668041559;7845.725399610987;7240.348368646847;7251.746188883364;7336.303051210127;7338.412926965937;7342.5227360221415;5335.973320133529;5408.864778380572;5472.72636368188;5479.561635069264;5537.36320323168;5349.705766182898;5375.247465354775;5430.891382172414;5435.157550579666;5525.889482210414 05:42:26 INFO - PROCESS | 3849 | |4;prototype.html;350.7755036776462;351.0766683622075;352.487131224069;355.1972542054955;359.250961744762;5620.606557540968;5681.687507187103;5690.231818704484;5692.601517893749;5712.914306285409;5530.5299049580735;5554.861253443045;5642.915356269659;5653.180288858123;5655.802046928358;3855.807209639521;4105.979470102645;4256.084941737526;4346.369776382423;4354.716943398669;4328.329108988096;4343.782810859444;4344.4786625604975;4349.760763158025;4367.847125350613;4335.56644335567;4337.175936572057;4342.717723347988;4343.109662519192;4345.196138714332;4056.1279324944985;4153.857689135481;4198.412222288882;4333.696641235115;4367.563243675639;3824.3880979043497;3836.0026393137987;3837.5992762641713;3852.8266228019806;3854.1135538825947;5433.538149257279;5437.809676661337;5442.183672449164;5452.764366609711;5470.91793623096;4820.39745031871;4968.87577810554;4991.401031876162;4993.501949415161;5000.174971129759;5805.622634528784;5810.680412577307;5811.506021988136;5852.209951656514;5852.707364631751;4218.11419601884;4235.110626768352;4241.34259189826;4247.723897946624;4249.235137675218;5658.519025882805;5708.743106560215;5882.588218824684;5971.940280597182;6068.878622427571;4129.525104612948;4245.8513952011535;4246.978765106216;4259.87220383389;4262.7016108872085;4306.4832220133785;4309.61213490781;4311.762853043113;4313.935290970638;4315.978420107879;4668.486466488684;4928.457869634337;4928.457869634337;4931.679440836406;4936.160852655057;5791.523161593766;5844.824655260349;5848.2689663792025;5850.5904586678525;5860.652050028517;1468.6034770611875;1483.7700156475764;1484.3765618440282;1487.8586534279227;1495.6186172525936;908.8216874371918;909.4225167018991;915.3546749541557;931.1573026411108;958.9952050239705;5007.3240112584335;5012.293361584776;5013.39839219296;5023.723695196799;5027.2207807789855;5249.501297376743;5265.157574788015;5272.736363181825;5273.472652734696;5275;5016.347874776256;5023.974880125649;5032.547070763576;5327;5748.511376532958;3178.904632861018;3208.1017315151794;3208.3743669984597;3222.4199644064292;3227.580414546141;6034.668093254825;6193.6903154842075;6203.875922481525;6204.286507051656;6249.312575616678;6137.759717703096;6144.341027922525;6168.259808822881;6179.783707570214;6184.628922264588;6190.41191086846;6200.038993955944;6221.440070393686;6221.844453888689;6223.751049957951;5798.275215598051;5944.910826337695;5986.700664966647;6001.7299221536005;6064.665773529817;5943.613665111754;5944.454441845065;5947.1079338099635;5961.37405572426;6016.157737916607;5911.142884281841;6012.278526576839;6091.695415229132;6159.29168145669;6177.177732568813;4174.791260436905;4183.811728472165;4192.7065105442325;4203.180379825904;4208.789560522023;5803.448672376117;6008.5493587981955;6013.699315034319;6048.157366037955;6061.545384096298;5802.767889284551;5843.1235314701435;5843.597536591273;5850.824475265749;5853.473187413153;5799.4780469757925;5855.414458554111;5858.096118761335;5876.941230587639;5883.970580147072;5608.635438696471;5837.737301821343;5852.063669812809;5878.676672782867;5893.233879595625;5921.03948025987;5921.911171332347;5932.762689492372;5936.732847021981;5937.406259374028;5580.832575022756;5765.94234057654;5780.797672081457;5805.82582522525;5812.622179558315 05:42:26 INFO - PROCESS | 3849 | |5;yui.html;843.4854738609444;848.2365870716354;866.7096522664903;868.9087645797191;932.6595792535725;16907.23917423716;17585.120743962783;17603.295868165256;17624.20691068902;18716.06419679014;17748.112594370294;18444.44666659999;18470.538236544093;18495.35266265681;18612.906935465304;729.4201110117469;748.554610007045;860.2515811244195;873.4148120759074;892.0455113029061;874.54960695242;891.754767438953;892.0722448653393;892.2505095719627;892.2638822971043;942.2509105261333;942.4769253064546;943.03810113684;943.575391074016;944.914957653811;754.9886751698701;775.3719487215374;896.7713233125577;935.8221937831825;935.9578818953163;676.100785954679;676.627854679929;677.474956908395;677.7526202935942;678.2471456683078;17116.82883171165;17117.486475405756;17134.886232394856;17135.65728685432;17138.57153571158;15429.22853857314;15515.146666933428;15619.297131629104;15669;15995.200239987955;14426.278686065654;14800.703985920221;14874.553763387115;14892.180930048842;15154.772678409832;4729.432468103815;4786.2581299898575;4924.285978533093;5027.296178535008;5037.823676171316;86170.56914715386;88041;88333.99999999936;89080.5545972266;90250.09749902482;12346;12422.937885310515;12489.562865299853;12523.499060037497;12583.811242831365;21733.239336623457;21751.891240543697;21790.564188716136;21796.34610961642;21805.236816711338;9281;9320.543565147855;9431;9443.90556094444;9693.127618514227;5119.138586318644;5180.533751962342;5188.7405629719115;5310.495502927216;5336.599755018389;3562.928741425157;3660.377735784923;3667.578228503703;3672.540932383452;4109.4246805447265;722.2813300765772;732.6556518436327;736.5727877830846;740.2227660956082;740.5815714121504;719.2412005334422;730.3901242462497;794.527256282511;832.5545832979327;833.4790755777639;10162.772836508566;10263.332883362558;10440.337713882911;10511;10820.513076911559;776.6233376812241;783.8432313537315;799.1089934722743;813.6663967773185;814;4986.4514903360605;5359.383670877821;5424.294841670558;5435.347758269033;5475.233467314579;129736;131516.34241828922;132255;133593.33203333922;139260.51848703864;113436.43281783539;114088.42955785168;118316.40841795909;119358.40320798515;119887;95628;98422.5078874601;99719;100128.49935750275;101945.99999999852;10176.491175441346;10383.688489345332;10460.163186944874;10527.263091583822;10584.312019718695;56688.716556416955;57596;57763;58516.414835851094;59059.40940590711;77884.61057694902;83904.32191356017;87836.56081719806;89667.55166224127;90596.54701726449;12396.938015309866;12536.686582835137;12987.155834870704;13045.282509461691;13057;15020.92489537589;15428.382864685287;16143.67712645717;16149.1925403727;16297.59256018609;13602.659933501742;13704.588862334145;14178.14931104137;14328.426862925366;14987.250637468302;13979.370928308048;14334.856651433352;14341;14440.711185776436;14529.709405812036;13383.53157639478;13389.263590502616;13686.52097176594;13830.930845345709;13996.510122145675;11642.24325418845;11722.94138529302;11830.645080647595;12320.137590368588;12417.503299867903;10718.35689858611;11863.169578129447;12237.326947018;12327.260364378166;12527.123101382816 05:42:26 INFO - PROCESS | 3849 | __end_tp_report 05:42:26 INFO - PROCESS | 3849 | __start_cc_report 05:42:26 INFO - PROCESS | 3849 | _x_x_mozilla_cycle_collect,891 05:42:26 INFO - PROCESS | 3849 | __end_cc_report 05:42:26 INFO - PROCESS | 3849 | __startTimestamp1475584946713__endTimestamp 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | ------- Summary: start ------- 05:42:26 INFO - PROCESS | 3849 | Number of tests: 6 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | [#0] dojo.html Cycles:165 Average:5596.60 Median:5824.07 stddev:646.47 (11.1%) stddev-sans-first:616.55 05:42:26 INFO - PROCESS | 3849 | Values: 3039.9 3125.9 3134.3 3156.0 3163.9 5617.7 5681.2 5683.2 5715.3 5749.0 5618.6 5688.7 5691.9 5698.1 5711.6 5122.9 5165.2 5178.6 5183.6 5231.2 5008.6 5038.4 5250.7 5254.8 5268.9 5076.8 5078.8 5116.5 5150.2 5203.5 5182.9 5194.4 5281.9 5283.0 5311.4 4937.6 5079.6 5156.7 5222.2 5239.9 5472.2 5475.2 5487.0 5519.4 5542.3 5869.8 5878.0 5967.9 5975.4 5975.9 5893.1 5998.2 6012.0 6024.2 6026.8 5507.3 5533.2 5554.4 5579.6 5600.1 6153.0 6160.2 6170.5 6176.3 6179.8 5730.0 5895.9 5908.1 5911.0 5917.7 5820.9 5936.9 5970.3 5993.5 6066.4 6017.0 6026.1 6027.3 6028.1 6039.2 6012.1 6067.2 6074.0 6077.8 6100.0 4481.8 4509.8 4565.5 4599.8 4614.6 4076.3 4083.4 4091.0 4110.8 4128.2 5722.2 5728.8 5730.4 5733.1 5743.2 5811.1 5858.2 6039.3 6097.0 6099.2 5821.9 5826.9 5842.8 5853.9 5858.5 5300.1 5306.7 5309.6 5313.7 5315.5 6011.5 6025.0 6262.4 6287.9 6328.8 5999.7 6017.4 6024.5 6100.8 6143.4 6218.3 6269.3 6276.6 6277.8 6278.3 5649.1 5983.3 5985.8 5987.8 6027.2 5881.0 5961.9 5993.5 6015.4 6096.2 5861.3 5908.0 5912.3 5918.1 5922.3 5892.1 5894.3 5896.3 5900.4 5906.4 5653.3 5802.9 5824.1 5839.1 5877.9 5952.1 5958.0 5961.6 5962.4 5994.1 5634.2 5806.3 5811.2 5813.4 5816.6 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | [#1] ext.html Cycles:180 Average:66755.95 Median:23463.28 stddev:131551.09 (560.7%) stddev-sans-first:131826.32 05:42:26 INFO - PROCESS | 3849 | Values: 590.6 609.8 629.5 659.1 663.3 35519.5 46884.8 48043.8 48144.5 48158.0 50545.7 51010.0 51023.0 51027.0 51079.5 8685.3 9525.3 9676.2 9681.8 9691.2 8519.8 8795.3 8796.8 11147.8 11438.4 11369.0 11370.4 11425.3 11435.5 11440.3 11357.9 11427.8 11432.0 11439.8 11440.3 11248.7 11317.4 11321.6 11323.8 11329.9 47182.8 47468.5 47544.8 47604.0 47613.3 3930.3 3943.3 3995.3 4189.0 4673.8 36211.5 36218.6 36506.0 36791.3 38083.4 4753.9 4772.2 4825.7 4839.8 4896.6 406843.0 406934.0 408818.0 408946.0 410021.0 69716.0 69784.7 69950.7 70091.6 70104.6 41865.8 42737.6 42763.0 44202.8 44396.0 20351.2 20610.0 20653.8 20654.9 20677.4 14969.8 14997.5 15016.6 15043.4 15066.5 19140.5 19541.9 20548.0 21317.8 21365.6 7509.7 7521.8 7581.4 7628.1 7707.0 951.1 996.8 1103.3 1124.8 1193.1 36020.7 38547.0 39076.2 39354.0 39975.0 1131.4 1189.3 1191.0 1211.8 1213.8 18711.6 18713.3 18829.6 19025.1 19058.7 607957.0 612885.0 613941.0 617322.0 617518.0 65071.3 65635.0 65644.3 65806.4 65832.3 1284.5 1286.5 1394.6 1466.9 1467.6 33788.5 37203.3 37410.0 37507.8 37585.2 56671.7 57314.4 57375.7 57518.4 57593.4 440040.0 441585.0 442375.0 443788.0 443913.0 31728.2 32017.2 32709.7 32749.0 34570.3 53457.7 54529.7 54620.7 54674.0 54835.0 45104.5 45935.5 46656.5 46669.5 48066.0 46310.8 46593.8 46712.0 47765.0 47780.3 45375.3 45540.8 46732.0 46784.0 46823.3 37960.2 40296.4 40326.0 41028.3 41123.2 30480.4 30484.2 30523.7 30964.5 30996.4 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | [#2] jquery.html Cycles:180 Average:28526.10 Median:2666.13 stddev:74558.76 (2796.5%) stddev-sans-first:74737.99 05:42:26 INFO - PROCESS | 3849 | Values: 391.6 414.0 424.3 431.8 442.4 14840.9 21196.2 21220.0 21243.9 22681.5 21198.4 21206.9 21210.5 21250.8 21569.5 5094.4 5705.2 6013.0 6051.2 6079.1 5831.7 6013.6 6080.0 6098.2 6102.3 5980.3 6009.6 6016.5 6021.9 6023.1 5890.9 5936.6 5951.3 6000.3 6001.1 5240.6 5357.5 5360.9 5362.8 5366.0 20325.6 25373.1 25516.0 25575.9 25673.7 2637.4 2694.9 2768.6 2971.0 3120.8 7436.0 7445.0 7511.4 8278.1 12529.7 2018.6 2039.7 2056.4 2057.5 2100.2 85352.0 85739.2 86223.6 87510.6 91626.5 10275.8 10417.6 10642.5 10680.0 10931.6 15488.5 15533.4 15533.8 15550.8 15551.1 8797.3 9005.6 9023.4 9059.7 9179.2 8993.2 9012.7 9035.1 9199.1 9257.7 6795.8 6842.7 6869.7 6935.8 6956.0 1894.0 1915.3 1940.5 1964.9 1979.4 895.7 1040.3 1058.6 1058.7 1145.6 20949.4 21402.6 21569.9 21986.5 21997.6 1087.0 1099.8 1134.9 1144.6 1165.6 11776.4 11923.8 12175.2 12305.5 12337.6 427287.0 427623.0 450024.0 469677.0 472112.0 87183.6 89307.0 89759.6 92828.5 104036.4 1078.7 1083.3 1123.9 1125.2 1125.4 17570.6 18014.2 18559.6 18665.3 18988.0 35150.1 42795.1 43857.6 44487.8 45125.3 60501.1 61196.0 62760.0 63160.0 63698.7 8438.7 8447.4 8509.9 8549.4 8757.7 9457.7 12011.2 12223.1 12590.7 13326.1 11483.1 11638.4 11988.9 12004.8 12354.9 10672.5 10690.6 11087.8 11425.5 11755.5 8514.8 8651.1 9090.5 11489.0 12258.6 11600.8 11748.8 12301.9 12318.4 12511.7 5826.7 7287.0 12056.3 12499.8 12814.4 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | [#3] mootools.html Cycles:180 Average:5912.61 Median:5327.90 stddev:3304.24 (62.0%) stddev-sans-first:3296.11 05:42:26 INFO - PROCESS | 3849 | Values: 1400.8 1493.3 1553.5 1746.6 1872.4 8029.1 8593.9 8969.3 8976.6 9237.0 8995.5 9011.8 9073.0 9076.2 9084.9 2500.7 2512.8 2522.1 2528.9 2531.0 2438.3 2528.7 2536.3 2539.1 2541.9 2431.7 2436.2 2491.6 2503.2 2505.5 2478.8 2499.7 2504.4 2507.2 2515.8 2472.9 2491.4 2500.2 2504.4 2505.5 8768.3 8923.6 8968.5 9018.0 9044.4 5415.7 5593.6 5599.2 5603.7 5606.4 3442.3 3461.3 3466.5 3496.1 3508.9 1107.2 1119.7 1125.5 1134.5 1156.6 12257.7 12302.1 12332.6 12342.1 12356.9 4564.3 4687.2 4794.1 4811.0 4819.5 9945.8 9949.5 9953.7 9956.5 9956.6 5311.5 5318.7 5322.8 5326.0 5329.8 4656.4 4827.1 4945.8 4990.4 5064.0 4106.5 4169.6 4181.5 4192.3 4193.1 1250.0 1258.4 1263.2 1267.5 1279.5 837.4 910.9 938.9 955.8 963.5 5913.1 5993.9 6106.5 6206.7 6218.2 486.9 490.4 491.8 497.2 501.7 4211.7 4316.7 4340.9 4359.0 4359.5 10952.8 11278.8 11354.4 11363.0 11366.3 10348.2 10825.8 11281.3 11282.2 11290.3 8238.8 8346.4 8382.0 8407.2 8412.6 6006.2 6016.4 6170.3 6264.6 6296.9 9740.4 9756.7 9779.1 9920.0 9931.4 9911.2 9927.6 9975.3 10062.5 10159.8 7246.2 7479.8 7879.2 8187.7 8331.6 8865.2 8973.3 8978.4 8978.6 8985.3 7834.7 7845.4 7901.4 8139.5 8146.8 7744.3 7777.5 7813.5 7820.5 7845.7 7240.3 7251.7 7336.3 7338.4 7342.5 5336.0 5408.9 5472.7 5479.6 5537.4 5349.7 5375.2 5430.9 5435.2 5525.9 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | [#4] prototype.html Cycles:180 Average:4866.68 Median:5567.85 stddev:1438.92 (25.8%) stddev-sans-first:1402.47 05:42:26 INFO - PROCESS | 3849 | Values: 350.8 351.1 352.5 355.2 359.3 5620.6 5681.7 5690.2 5692.6 5712.9 5530.5 5554.9 5642.9 5653.2 5655.8 3855.8 4106.0 4256.1 4346.4 4354.7 4328.3 4343.8 4344.5 4349.8 4367.8 4335.6 4337.2 4342.7 4343.1 4345.2 4056.1 4153.9 4198.4 4333.7 4367.6 3824.4 3836.0 3837.6 3852.8 3854.1 5433.5 5437.8 5442.2 5452.8 5470.9 4820.4 4968.9 4991.4 4993.5 5000.2 5805.6 5810.7 5811.5 5852.2 5852.7 4218.1 4235.1 4241.3 4247.7 4249.2 5658.5 5708.7 5882.6 5971.9 6068.9 4129.5 4245.9 4247.0 4259.9 4262.7 4306.5 4309.6 4311.8 4313.9 4316.0 4668.5 4928.5 4928.5 4931.7 4936.2 5791.5 5844.8 5848.3 5850.6 5860.7 1468.6 1483.8 1484.4 1487.9 1495.6 908.8 909.4 915.4 931.2 959.0 5007.3 5012.3 5013.4 5023.7 5027.2 5249.5 5265.2 5272.7 5273.5 5275.0 5016.3 5024.0 5032.5 5327.0 5748.5 3178.9 3208.1 3208.4 3222.4 3227.6 6034.7 6193.7 6203.9 6204.3 6249.3 6137.8 6144.3 6168.3 6179.8 6184.6 6190.4 6200.0 6221.4 6221.8 6223.8 5798.3 5944.9 5986.7 6001.7 6064.7 5943.6 5944.5 5947.1 5961.4 6016.2 5911.1 6012.3 6091.7 6159.3 6177.2 4174.8 4183.8 4192.7 4203.2 4208.8 5803.4 6008.5 6013.7 6048.2 6061.5 5802.8 5843.1 5843.6 5850.8 5853.5 5799.5 5855.4 5858.1 5876.9 5884.0 5608.6 5837.7 5852.1 5878.7 5893.2 5921.0 5921.9 5932.8 5936.7 5937.4 5580.8 5765.9 5780.8 5805.8 5812.6 05:42:26 INFO - PROCESS | 3849 | 05:42:26 INFO - PROCESS | 3849 | [#5] yui.html Cycles:180 Average:23643.92 Median:21800.79 stddev:34860.46 (159.9%) stddev-sans-first:34916.22 05:42:26 INFO - PROCESS | 3849 | Values: 843.5 848.2 866.7 868.9 932.7 16907.2 17585.1 17603.3 17624.2 18716.1 17748.1 18444.4 18470.5 18495.4 18612.9 729.4 748.6 860.3 873.4 892.0 874.5 891.8 892.1 892.3 892.3 942.3 942.5 943.0 943.6 944.9 755.0 775.4 896.8 935.8 936.0 676.1 676.6 677.5 677.8 678.2 17116.8 17117.5 17134.9 17135.7 17138.6 15429.2 15515.1 15619.3 15669.0 15995.2 14426.3 14800.7 14874.6 14892.2 15154.8 4729.4 4786.3 4924.3 5027.3 5037.8 86170.6 88041.0 88334.0 89080.6 90250.1 12346.0 12422.9 12489.6 12523.5 12583.8 21733.2 21751.9 21790.6 21796.3 21805.2 9281.0 9320.5 9431.0 9443.9 9693.1 5119.1 5180.5 5188.7 5310.5 5336.6 3562.9 3660.4 3667.6 3672.5 4109.4 722.3 732.7 736.6 740.2 740.6 719.2 730.4 794.5 832.6 833.5 10162.8 10263.3 10440.3 10511.0 10820.5 776.6 783.8 799.1 813.7 814.0 4986.5 5359.4 5424.3 5435.3 5475.2 129736.0 131516.3 132255.0 133593.3 139260.5 113436.4 114088.4 118316.4 119358.4 119887.0 95628.0 98422.5 99719.0 100128.5 101946.0 10176.5 10383.7 10460.2 10527.3 10584.3 56688.7 57596.0 57763.0 58516.4 59059.4 77884.6 83904.3 87836.6 89667.6 90596.5 12396.9 12536.7 12987.2 13045.3 13057.0 15020.9 15428.4 16143.7 16149.2 16297.6 13602.7 13704.6 14178.1 14328.4 14987.3 13979.4 14334.9 14341.0 14440.7 14529.7 13383.5 13389.3 13686.5 13830.9 13996.5 11642.2 11722.9 11830.6 12320.1 12417.5 10718.4 11863.2 12237.3 12327.3 12527.1 05:42:26 INFO - PROCESS | 3849 | -------- Summary: end -------- 05:42:26 INFO - PROCESS | 3849 | 05:42:27 INFO - TEST-INFO | 3849: exit 0 05:42:27 INFO - TEST-OK | dromaeo_css | took 1113414ms 05:42:27 INFO - TEST-START | kraken 05:42:27 INFO - operating with platform_type : linux_ 05:42:27 INFO - Initialising browser for kraken test... 05:42:27 INFO - TEST-INFO | started process 4168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXwLx42/profile http://localhost:46619/getInfo.html) 05:42:31 INFO - PROCESS | 4168 | __metrics Screen width/height:1600/1200 05:42:31 INFO - PROCESS | 4168 | colorDepth:24 05:42:31 INFO - PROCESS | 4168 | Browser inner width/height: 1024/697 05:42:31 INFO - PROCESS | 4168 | __metrics 05:42:31 INFO - PROCESS | 4168 | 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" 05:42:31 INFO - PROCESS | 4168 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:42:32 INFO - PROCESS | 4168 | console.error: PushService: 05:42:32 INFO - PROCESS | 4168 | stateChangeProcessEnqueue: Error transitioning state 05:42:32 INFO - PROCESS | 4168 | UnknownError 05:42:32 INFO - TEST-INFO | 4168: exit 0 05:42:32 INFO - Browser initialized. 05:42:32 INFO - Running cycle 1/1 for kraken test... 05:42:32 INFO - TEST-INFO | started process 4259 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXwLx42/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:44 INFO - PROCESS | 4259 | RSS: Main: 144592896 05:42:44 INFO - PROCESS | 4259 | 05:42:47 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46619/tests/kraken/driver.html?testName=audio-beat-detection) 05:42:48 INFO - PROCESS | 4259 | RSS: Main: 185880576 05:42:48 INFO - PROCESS | 4259 | 05:42:50 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46619/tests/kraken/driver.html?testName=audio-dft) 05:42:51 INFO - PROCESS | 4259 | RSS: Main: 175845376 05:42:51 INFO - PROCESS | 4259 | 05:42:54 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46619/tests/kraken/driver.html?testName=audio-fft) 05:42:54 INFO - PROCESS | 4259 | RSS: Main: 266592256 05:42:54 INFO - PROCESS | 4259 | 05:42:56 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46619/tests/kraken/driver.html?testName=audio-oscillator) 05:42:57 INFO - PROCESS | 4259 | RSS: Main: 172711936 05:42:57 INFO - PROCESS | 4259 | 05:42:59 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46619/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:42:59 INFO - PROCESS | 4259 | RSS: Main: 174530560 05:42:59 INFO - PROCESS | 4259 | 05:43:02 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46619/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:03 INFO - PROCESS | 4259 | RSS: Main: 210546688 05:43:03 INFO - PROCESS | 4259 | 05:43:06 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46619/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:07 INFO - PROCESS | 4259 | RSS: Main: 209498112 05:43:07 INFO - PROCESS | 4259 | 05:43:10 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46619/tests/kraken/driver.html?testName=json-parse-financial) 05:43:10 INFO - PROCESS | 4259 | RSS: Main: 209190912 05:43:10 INFO - PROCESS | 4259 | 05:43:12 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46619/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:12 INFO - PROCESS | 4259 | RSS: Main: 171438080 05:43:12 INFO - PROCESS | 4259 | 05:43:14 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:15 INFO - PROCESS | 4259 | RSS: Main: 179171328 05:43:15 INFO - PROCESS | 4259 | 05:43:17 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:17 INFO - PROCESS | 4259 | RSS: Main: 183369728 05:43:17 INFO - PROCESS | 4259 | 05:43:20 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:20 INFO - PROCESS | 4259 | RSS: Main: 184901632 05:43:20 INFO - PROCESS | 4259 | 05:43:23 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:43:24 INFO - PROCESS | 4259 | RSS: Main: 182542336 05:43:24 INFO - PROCESS | 4259 | 05:43:25 INFO - PROCESS | 4259 | Cycle 1(1): loaded http://localhost:46619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46619/tests/kraken/driver.html?testName=ai-astar) 05:43:26 INFO - PROCESS | 4259 | RSS: Main: 233828352 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | __start_tp_report 05:43:26 INFO - PROCESS | 4259 | _x_x_mozilla_page_load 05:43:26 INFO - PROCESS | 4259 | _x_x_mozilla_page_load_details 05:43:26 INFO - PROCESS | 4259 | |i|pagename|runs| 05:43:26 INFO - PROCESS | 4259 | |0;ai-astar;109;110;111;110;111;113;111;111;113;112 05:43:26 INFO - PROCESS | 4259 | |1;audio-beat-detection;138;121;116;147;116;118;115;121;153;115 05:43:26 INFO - PROCESS | 4259 | |2;audio-dft;160;161;160;160;162;159;160;160;159;159 05:43:26 INFO - PROCESS | 4259 | |3;audio-fft;75;75;75;110;75;77;76;78;99;76 05:43:26 INFO - PROCESS | 4259 | |4;audio-oscillator;81;80;110;81;81;82;103;83;82;124 05:43:26 INFO - PROCESS | 4259 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;118;117;117 05:43:26 INFO - PROCESS | 4259 | |6;imaging-darkroom;146;145;146;146;146;145;146;145;146;146 05:43:26 INFO - PROCESS | 4259 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 05:43:26 INFO - PROCESS | 4259 | |8;json-parse-financial;51;49;48;48;48;49;50;49;49;49 05:43:26 INFO - PROCESS | 4259 | |9;json-stringify-tinderbox;58;59;59;58;58;60;58;58;59;59 05:43:26 INFO - PROCESS | 4259 | |10;stanford-crypto-aes;74;74;73;74;74;74;79;73;77;74 05:43:26 INFO - PROCESS | 4259 | |11;stanford-crypto-ccm;125;122;127;119;119;120;127;122;162;134 05:43:26 INFO - PROCESS | 4259 | |12;stanford-crypto-pbkdf2;154;157;155;156;155;154;155;156;157;152 05:43:26 INFO - PROCESS | 4259 | |13;stanford-crypto-sha256-iterative;63;65;63;66;65;62;62;62;61;59 05:43:26 INFO - PROCESS | 4259 | __end_tp_report 05:43:26 INFO - PROCESS | 4259 | __start_cc_report 05:43:26 INFO - PROCESS | 4259 | _x_x_mozilla_cycle_collect,1573 05:43:26 INFO - PROCESS | 4259 | __end_cc_report 05:43:26 INFO - PROCESS | 4259 | __startTimestamp1475585006084__endTimestamp 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | ------- Summary: start ------- 05:43:26 INFO - PROCESS | 4259 | Number of tests: 14 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:1.29 (1.2%) stddev-sans-first:1.12 05:43:26 INFO - PROCESS | 4259 | Values: 109.0 110.0 111.0 110.0 111.0 113.0 111.0 111.0 113.0 112.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:121.00 stddev:14.41 (11.9%) stddev-sans-first:14.62 05:43:26 INFO - PROCESS | 4259 | Values: 138.0 121.0 116.0 147.0 116.0 118.0 115.0 121.0 153.0 115.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 05:43:26 INFO - PROCESS | 4259 | Values: 160.0 161.0 160.0 160.0 162.0 159.0 160.0 160.0 159.0 159.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:12.38 (16.3%) stddev-sans-first:12.90 05:43:26 INFO - PROCESS | 4259 | Values: 75.0 75.0 75.0 110.0 75.0 77.0 76.0 78.0 99.0 76.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:81.00 stddev:15.78 (19.5%) stddev-sans-first:16.34 05:43:26 INFO - PROCESS | 4259 | Values: 81.0 80.0 110.0 81.0 81.0 82.0 103.0 83.0 82.0 124.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:43:26 INFO - PROCESS | 4259 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 118.0 117.0 117.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 05:43:26 INFO - PROCESS | 4259 | Values: 146.0 145.0 146.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 05:43:26 INFO - PROCESS | 4259 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#8] json-parse-financial Cycles:10 Average:49.00 Median:49.00 stddev:0.94 (1.9%) stddev-sans-first:0.67 05:43:26 INFO - PROCESS | 4259 | Values: 51.0 49.0 48.0 48.0 48.0 49.0 50.0 49.0 49.0 49.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 05:43:26 INFO - PROCESS | 4259 | Values: 58.0 59.0 59.0 58.0 58.0 60.0 58.0 58.0 59.0 59.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.00 stddev:1.90 (2.6%) stddev-sans-first:2.00 05:43:26 INFO - PROCESS | 4259 | Values: 74.0 74.0 73.0 74.0 74.0 74.0 79.0 73.0 77.0 74.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:126.00 stddev:12.91 (10.2%) stddev-sans-first:13.66 05:43:26 INFO - PROCESS | 4259 | Values: 125.0 122.0 127.0 119.0 119.0 120.0 127.0 122.0 162.0 134.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.10 Median:155.50 stddev:1.52 (1.0%) stddev-sans-first:1.56 05:43:26 INFO - PROCESS | 4259 | Values: 154.0 157.0 155.0 156.0 155.0 154.0 155.0 156.0 157.0 152.0 05:43:26 INFO - PROCESS | 4259 | 05:43:26 INFO - PROCESS | 4259 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:2.10 (3.3%) stddev-sans-first:2.22 05:43:26 INFO - PROCESS | 4259 | Values: 63.0 65.0 63.0 66.0 65.0 62.0 62.0 62.0 61.0 59.0 05:43:26 INFO - PROCESS | 4259 | -------- Summary: end -------- 05:43:26 INFO - PROCESS | 4259 | 05:43:27 INFO - TEST-INFO | 4259: exit 0 05:43:27 INFO - TEST-OK | kraken | took 59793ms 05:43:27 INFO - SUITE-END | took 1173s 05:43:27 INFO - Completed test suite (00:19:33) 05:43:27 INFO - javascript benchmark 05:43:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3039.908802735918, 3125.8905938292164, 3134.326119884223, 3156.037408590379, 3163.873445062198, 5617.691026993519, 5681.176229446728, 5683.204351390808, 5715.2855893013375, 5748.971255143723, 5618.5786066045, 5688.744006519706, 5691.9146212806845, 5698.059820129684, 5711.628744131639, 5122.897542049148, 5165.173572228444, 5178.559822415099, 5183.637145399824, 5231.189165679326, 5008.649394542384, 5038.445770965209, 5250.6587071840395, 5254.7635356409055, 5268.868278293055, 5076.771545280453, 5078.822241221558, 5116.513931176532, 5150.227465880111, 5203.5056669616515, 5182.8704282393055, 5194.402643695986, 5281.86795330116, 5283.049051170788, 5311.415744268127, 4937.604991600663, 5079.568236699885, 5156.690598564098, 5222.190560463135, 5239.869003274911, 5472.151816468454, 5475.178723191513, 5487.0, 5519.448055194489, 5542.307211598551, 5869.823905282806, 5878.030125029408, 5967.940320596782, 5975.43233392827, 5975.940240597582, 5893.14549390336, 5998.1602575639445, 6012.008018676913, 6024.246969128859, 6026.819195424145, 5507.339119305669, 5533.225348451179, 5554.389017208104, 5579.609427340088, 5600.131979543177, 6152.969235153885, 6160.168377269101, 6170.5372097092895, 6176.258848938156, 6179.81460556184, 5729.9713501432225, 5895.911561326584, 5908.084246941731, 5910.970445147746, 5917.6745279010065, 5820.854478638068, 5936.851578710481, 5970.34326224115, 5993.460588547052, 6066.363031881677, 6017.0, 6026.0659597762415, 6027.336992930774, 6028.09578563219, 6039.245094363205, 6012.068129439944, 6067.180930574405, 6073.969630151821, 6077.817665470043, 6099.96950015256, 4481.75350355727, 4509.819607215674, 4565.474970378383, 4599.839005634787, 4614.630829533629, 4076.3477843544892, 4083.4283200351974, 4091.0386059276047, 4110.815013324408, 4128.174365126986, 5722.198892155102, 5728.77084916607, 5730.426957304237, 5733.0540460823495, 5743.167240750151, 5811.099279611667, 5858.150568167592, 6039.275286965592, 6097.024476083805, 6099.237595300588, 5821.88356232873, 5826.854328641818, 5842.795502157404, 5853.853653658607, 5858.53131749459, 5300.1254792958725, 5306.734663266821, 5309.5752339812725, 5313.734313284321, 5315.468453154654, 6011.489023433057, 6024.969875150771, 6262.373762623793, 6287.9056814147825, 6328.810135695844, 5999.70001499932, 6017.398260173947, 6024.548158888013, 6100.786472473442, 6143.416375444325, 6218.284897236876, 6269.310375858743, 6276.623402595676, 6277.843053923689, 6278.309385967448, 5649.067903795828, 5983.282006159115, 5985.760569577343, 5987.820365389045, 6027.21646185993, 5881.0, 5961.8807623846415, 5993.490553303018, 6015.39846015395, 6096.177016102769, 5861.296644402699, 5908.0, 5912.260967379078, 5918.082697181943, 5922.289325280994, 5892.086726557391, 5894.2926848778425, 5896.262967129109, 5900.380460051632, 5906.379830117776, 5653.293338332708, 5802.854928626818, 5824.068149096124, 5839.0949402842625, 5877.94122058774, 5952.136940143741, 5958.0, 5961.642301461926, 5962.373950735284, 5994.100884867305, 5634.154876768517, 5806.274215723035, 5811.186433899173, 5813.389594092729, 5816.592838501264], "value": 5551.140503653665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.630855715178, 609.7530500147441, 629.4932579273683, 659.0707102984792, 663.2538394306405, 35519.46720799187, 46884.76557617211, 48043.75978120113, 48144.51855481449, 48158.0, 50545.74727126368, 51010.0, 51023.0, 51027.0, 51079.48920510794, 8685.348598855111, 9525.333226674118, 9676.17752491039, 9681.806363872718, 9691.176250018756, 8519.787005324855, 8795.252403545706, 8796.824063518727, 11147.777044459106, 11438.370889601068, 11369.0, 11370.374629395379, 11425.31448113116, 11435.5425782969, 11440.256383335098, 11357.943210283936, 11427.771444571144, 11432.0, 11439.828402573969, 11440.313581185113, 11248.718782030433, 11317.434128293635, 11321.603743869007, 11323.773524529463, 11329.943350283278, 47182.76408617935, 47468.5253147471, 47544.7622761884, 47604.0, 47613.28580071302, 3930.3121953658283, 3943.3493473576827, 3995.3407687731487, 4188.979055104705, 4673.789679464432, 36211.4568281476, 36218.63781362205, 36506.0, 36791.26417471636, 38083.4287485688, 4753.928691069636, 4772.2125849234835, 4825.662203645747, 4839.758012099381, 4896.632752543538, 406843.0, 406934.0, 408818.0, 408946.0, 410021.0, 69716.0, 69784.6510767443, 69950.65024674844, 70091.64954175298, 70104.6494767523, 41865.79067104645, 42737.572624273984, 42763.0, 44202.778986105506, 44396.0, 20351.185952562028, 20610.0, 20653.793462065187, 20654.896725516275, 20677.379678609665, 14969.775453368207, 14997.475088372074, 15016.624584385478, 15043.398264069534, 15066.472673456596, 19140.521486962658, 19541.902290488455, 20548.0, 21317.78682213189, 21365.572688546454, 7509.737159199511, 7521.7743467696055, 7581.355584775356, 7628.122765881883, 7706.998090248232, 951.1249650321721, 996.8454889492141, 1103.3049179017328, 1124.791913496006, 1193.1349771415655, 36020.74211336467, 38547.0, 39076.218475630325, 39354.0, 39975.0, 1131.4173200801595, 1189.2685998111272, 1190.9702257443632, 1211.8485189351331, 1213.7936550786387, 18711.625767484846, 18713.345032923782, 18829.623407531773, 19025.14386852595, 19058.714119288223, 607957.0, 612885.0, 613941.0, 617322.0, 617518.0, 65071.34928650653, 65635.0, 65644.34355656382, 65806.36774528965, 65832.34167658357, 1284.531146131674, 1286.5368467351745, 1394.637394277495, 1466.875315598186, 1467.618419211013, 33788.493172602924, 37203.255934881694, 37410.0, 37507.81246093752, 37585.24829503449, 56671.716641416526, 57314.42685573091, 57375.71312143413, 57518.42481575298, 57593.42406575881, 440040.0, 441585.0, 442375.0, 443788.0, 443913.0, 31728.206794830316, 32017.199570010005, 32709.672903270664, 32749.0, 34570.308593828486, 53457.7327113362, 54529.72735136299, 54620.72689636526, 54674.0, 54835.0, 45104.548954510035, 45935.54064459313, 46656.533434665216, 46669.53330466788, 48066.0, 46310.768446157555, 46593.76703116463, 46712.0, 47765.04469910513, 47780.2832957499, 45375.31937020881, 45540.772296138304, 46732.0, 46784.0, 46823.29765053459, 37960.240795184494, 40296.3955540673, 40326.0, 41028.34935977328, 41123.1775364497, 30480.390392192476, 30484.237894052825, 30523.694763052084, 30964.53553196749, 30996.380072397973], "value": 22442.290725217736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.5698481303778, 413.95446500884896, 424.3274410060055, 431.8380607272273, 442.3519543868234, 14840.925795371022, 21196.152153913845, 21219.99999999998, 21243.893780531114, 22681.54636907261, 21198.364049078515, 21206.89396553019, 21210.469738256514, 21250.787492125073, 21569.46076348088, 5094.439611642716, 5705.229793977812, 6013.037913933749, 6051.243594550681, 6079.118527813464, 5831.650100993933, 6013.609115407507, 6080.0, 6098.176746139281, 6102.267727872661, 5980.342162362137, 6009.57932944694, 6016.548758843082, 6021.909671354912, 6023.126646636235, 5890.911636325459, 5936.584439089265, 5951.256092988377, 6000.309964354112, 6001.129836173752, 5240.633155679104, 5357.464253574612, 5360.892782144335, 5362.812301569426, 5365.973170134163, 20325.593488130304, 25373.11194108216, 25516.0, 25575.872120639277, 25673.74326256732, 2637.3933995181023, 2694.8652567371555, 2768.5847122931723, 2970.960163942603, 3120.7815452918303, 7436.0333156689285, 7444.9627751861435, 7511.399088072995, 8278.130796266365, 12529.74940501194, 2018.6467368210504, 2039.7042428847883, 2056.44475991483, 2057.5267688431745, 2100.19142630087, 85352.0, 85739.23979877056, 86223.56888215581, 87510.56244718736, 91626.54186729023, 10275.845862312071, 10417.593624860698, 10642.467876606293, 10680.0, 10931.56273749057, 15488.457903973307, 15533.378664853506, 15533.766993495106, 15550.76673849893, 15551.066935983878, 8797.29621630268, 9005.594748236344, 9023.368364214573, 9059.68291109808, 9179.173874351207, 8993.190612844744, 9012.684556040638, 9035.141661542142, 9199.126083022102, 9257.67598134062, 6795.762148324785, 6842.6578671066245, 6869.725210991604, 6935.791926242221, 6955.965220173968, 1894.043508028437, 1915.2913422033937, 1940.5245714799682, 1964.9312274070626, 1979.3962841333532, 895.6506962284714, 1040.292601031306, 1058.571278632155, 1058.687687132298, 1145.5990403358758, 20949.37151885446, 21402.57194856094, 21569.892150539148, 21986.45033874166, 21997.560048798936, 1086.9510872010778, 1099.7855418193376, 1134.9432528373547, 1144.6165534546021, 1165.6444784340688, 11776.352300623548, 11923.821142682868, 12175.208611440228, 12305.50777968889, 12337.568185113478, 427287.0, 427623.0, 450024.0, 469677.0, 472112.0, 87183.56408218046, 89307.0, 89759.55120224357, 92828.53585732164, 104036.43945340825, 1078.7464945737745, 1083.2742062818088, 1123.8819923908036, 1125.1898632984403, 1125.431657013203, 17570.648587028445, 18014.189361478504, 18559.628807423505, 18665.346712864986, 18988.0, 35150.12124696903, 42795.14409711851, 43857.56142438535, 44487.77756111199, 45125.32312015388, 60501.09248361366, 61196.0, 62760.0, 63160.0, 63698.68150659217, 8438.746837594881, 8447.366447516584, 8509.87235191485, 8549.358798090043, 8757.693480728143, 9457.716268511957, 12011.219270747373, 12223.144379893321, 12590.748185036431, 13326.133801302884, 11483.138764592522, 11638.35989020612, 11988.880111199125, 12004.75990480203, 12354.876451235372, 10672.466376680979, 10690.572377104829, 11087.833682494607, 11425.542978281108, 11755.529778808752, 8514.78713032179, 8651.134886511298, 9090.500022498562, 11489.0, 12258.570950016707, 11600.767984640428, 11748.823767643664, 12301.876981230072, 12318.384080795746, 12511.749765004466, 5826.679532625746, 7286.96356518214, 12056.336901470504, 12499.812502812283, 12814.359282036048], "value": 9636.827580825257, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.7548678981175, 1493.3354657177556, 1553.4873491747724, 1746.56335916021, 1872.4101907899003, 8029.07665618454, 8593.91406085939, 8969.327300452467, 8976.551172441385, 9236.95381523093, 8995.460272383662, 9011.774705632362, 9073.0, 9076.22852057576, 9084.909150908488, 2500.662410574572, 2512.798976081909, 2522.054229663876, 2528.9241322760254, 2531.0382054819233, 2438.2563318187945, 2528.671272734551, 2536.3405514566252, 2539.1240022192405, 2541.872906354675, 2431.659567660529, 2436.2325867351815, 2491.6262560615874, 2503.173952595639, 2505.473850491399, 2478.776910078084, 2499.7000359956833, 2504.373906523369, 2507.1976967370447, 2515.811314151446, 2472.8763561822016, 2491.4145175883655, 2500.249925022486, 2504.4239824840206, 2505.5114252720728, 8768.342374321888, 8923.598438070303, 8968.50673212973, 9017.954910225473, 9044.41211321262, 5415.675059496442, 5593.608447408683, 5599.216109744639, 5603.719814009283, 5606.439356064402, 3442.345954268681, 3461.2904354607244, 3466.532018177539, 3496.0735405117666, 3508.9298214035575, 1107.164091111214, 1119.6920846767123, 1125.5329038449033, 1134.4951496584024, 1156.6298784388998, 12257.693557661041, 12302.07734419922, 12332.630021099381, 12342.074344424203, 12356.938215308955, 4564.338170965191, 4687.179743544866, 4794.089123066607, 4811.0, 4819.518048195152, 9945.751356216153, 9949.502524873727, 9953.651622193333, 9956.502174891226, 9956.551955162035, 5311.521963023347, 5318.73406329682, 5322.840314790485, 5326.041312563776, 5329.760160792774, 4656.41794775653, 4827.106985207748, 4945.7774400152075, 4990.426100998359, 5063.974680126576, 4106.466159399319, 4169.56219596944, 4181.477315335583, 4192.329227323614, 4193.119884354703, 1250.0437165568349, 1258.4148371007525, 1263.1599986009385, 1267.4739982907174, 1279.539365828301, 837.388706244445, 910.9089091090838, 938.8685584018243, 955.8231727130365, 963.4728954606886, 5913.098673966275, 5993.940060599425, 6106.4809491192855, 6206.718909440358, 6218.22272215973, 486.92939523768854, 490.394362961742, 491.84015195061033, 497.21688340863284, 501.72655902533205, 4211.663066954637, 4316.719413238192, 4340.891477713082, 4358.978205108954, 4359.520452750195, 10952.835707464394, 11278.830817537579, 11354.375509347065, 11363.0, 11366.261193022427, 10348.22388320879, 10825.837612435662, 11281.32312061279, 11282.210245282751, 11290.266132701512, 8238.846561481398, 8346.4157508975, 8381.994160700633, 8407.201315874981, 8412.579371031547, 6006.15913772081, 6016.42843929826, 6170.290416601969, 6264.59280146789, 6296.937030629635, 9740.415575065519, 9756.658516951873, 9779.070988256104, 9920.0, 9931.404115753077, 9911.207103431563, 9927.602895884373, 9975.251856110675, 10062.547185376528, 10159.815639637514, 7246.202917679157, 7479.775606731807, 7879.172686867796, 8187.672493100209, 8331.625076871434, 8865.157810008039, 8973.28213742912, 8978.416402933788, 8978.551072446482, 8985.326100542356, 7834.72578459774, 7845.411594130349, 7901.367890568856, 8139.4709343892455, 8146.75559733209, 7744.2642948919765, 7777.494462859896, 7813.4530582860925, 7820.491668041559, 7845.725399610987, 7240.348368646847, 7251.746188883364, 7336.303051210127, 7338.412926965937, 7342.5227360221415, 5335.973320133529, 5408.864778380572, 5472.72636368188, 5479.561635069264, 5537.36320323168, 5349.705766182898, 5375.247465354775, 5430.891382172414, 5435.157550579666, 5525.889482210414], "value": 4682.912757415325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7755036776462, 351.0766683622075, 352.487131224069, 355.1972542054955, 359.250961744762, 5620.606557540968, 5681.687507187103, 5690.231818704484, 5692.601517893749, 5712.914306285409, 5530.5299049580735, 5554.861253443045, 5642.915356269659, 5653.180288858123, 5655.802046928358, 3855.807209639521, 4105.979470102645, 4256.084941737526, 4346.369776382423, 4354.716943398669, 4328.329108988096, 4343.782810859444, 4344.4786625604975, 4349.760763158025, 4367.847125350613, 4335.56644335567, 4337.175936572057, 4342.717723347988, 4343.109662519192, 4345.196138714332, 4056.1279324944985, 4153.857689135481, 4198.412222288882, 4333.696641235115, 4367.563243675639, 3824.3880979043497, 3836.0026393137987, 3837.5992762641713, 3852.8266228019806, 3854.1135538825947, 5433.538149257279, 5437.809676661337, 5442.183672449164, 5452.764366609711, 5470.91793623096, 4820.39745031871, 4968.87577810554, 4991.401031876162, 4993.501949415161, 5000.174971129759, 5805.622634528784, 5810.680412577307, 5811.506021988136, 5852.209951656514, 5852.707364631751, 4218.11419601884, 4235.110626768352, 4241.34259189826, 4247.723897946624, 4249.235137675218, 5658.519025882805, 5708.743106560215, 5882.588218824684, 5971.940280597182, 6068.878622427571, 4129.525104612948, 4245.8513952011535, 4246.978765106216, 4259.87220383389, 4262.7016108872085, 4306.4832220133785, 4309.61213490781, 4311.762853043113, 4313.935290970638, 4315.978420107879, 4668.486466488684, 4928.457869634337, 4928.457869634337, 4931.679440836406, 4936.160852655057, 5791.523161593766, 5844.824655260349, 5848.2689663792025, 5850.5904586678525, 5860.652050028517, 1468.6034770611875, 1483.7700156475764, 1484.3765618440282, 1487.8586534279227, 1495.6186172525936, 908.8216874371918, 909.4225167018991, 915.3546749541557, 931.1573026411108, 958.9952050239705, 5007.3240112584335, 5012.293361584776, 5013.39839219296, 5023.723695196799, 5027.2207807789855, 5249.501297376743, 5265.157574788015, 5272.736363181825, 5273.472652734696, 5275.0, 5016.347874776256, 5023.974880125649, 5032.547070763576, 5327.0, 5748.511376532958, 3178.904632861018, 3208.1017315151794, 3208.3743669984597, 3222.4199644064292, 3227.580414546141, 6034.668093254825, 6193.6903154842075, 6203.875922481525, 6204.286507051656, 6249.312575616678, 6137.759717703096, 6144.341027922525, 6168.259808822881, 6179.783707570214, 6184.628922264588, 6190.41191086846, 6200.038993955944, 6221.440070393686, 6221.844453888689, 6223.751049957951, 5798.275215598051, 5944.910826337695, 5986.700664966647, 6001.7299221536005, 6064.665773529817, 5943.613665111754, 5944.454441845065, 5947.1079338099635, 5961.37405572426, 6016.157737916607, 5911.142884281841, 6012.278526576839, 6091.695415229132, 6159.29168145669, 6177.177732568813, 4174.791260436905, 4183.811728472165, 4192.7065105442325, 4203.180379825904, 4208.789560522023, 5803.448672376117, 6008.5493587981955, 6013.699315034319, 6048.157366037955, 6061.545384096298, 5802.767889284551, 5843.1235314701435, 5843.597536591273, 5850.824475265749, 5853.473187413153, 5799.4780469757925, 5855.414458554111, 5858.096118761335, 5876.941230587639, 5883.970580147072, 5608.635438696471, 5837.737301821343, 5852.063669812809, 5878.676672782867, 5893.233879595625, 5921.03948025987, 5921.911171332347, 5932.762689492372, 5936.732847021981, 5937.406259374028, 5580.832575022756, 5765.94234057654, 5780.797672081457, 5805.82582522525, 5812.622179558315], "value": 4407.250836000198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [843.4854738609444, 848.2365870716354, 866.7096522664903, 868.9087645797191, 932.6595792535725, 16907.23917423716, 17585.120743962783, 17603.295868165256, 17624.20691068902, 18716.06419679014, 17748.112594370294, 18444.44666659999, 18470.538236544093, 18495.35266265681, 18612.906935465304, 729.4201110117469, 748.554610007045, 860.2515811244195, 873.4148120759074, 892.0455113029061, 874.54960695242, 891.754767438953, 892.0722448653393, 892.2505095719627, 892.2638822971043, 942.2509105261333, 942.4769253064546, 943.03810113684, 943.575391074016, 944.914957653811, 754.9886751698701, 775.3719487215374, 896.7713233125577, 935.8221937831825, 935.9578818953163, 676.100785954679, 676.627854679929, 677.474956908395, 677.7526202935942, 678.2471456683078, 17116.82883171165, 17117.486475405756, 17134.886232394856, 17135.65728685432, 17138.57153571158, 15429.22853857314, 15515.146666933428, 15619.297131629104, 15669.0, 15995.200239987955, 14426.278686065654, 14800.703985920221, 14874.553763387115, 14892.180930048842, 15154.772678409832, 4729.432468103815, 4786.2581299898575, 4924.285978533093, 5027.296178535008, 5037.823676171316, 86170.56914715386, 88041.0, 88333.99999999936, 89080.5545972266, 90250.09749902482, 12346.0, 12422.937885310515, 12489.562865299853, 12523.499060037497, 12583.811242831365, 21733.239336623457, 21751.891240543697, 21790.564188716136, 21796.34610961642, 21805.236816711338, 9281.0, 9320.543565147855, 9431.0, 9443.90556094444, 9693.127618514227, 5119.138586318644, 5180.533751962342, 5188.7405629719115, 5310.495502927216, 5336.599755018389, 3562.928741425157, 3660.377735784923, 3667.578228503703, 3672.540932383452, 4109.4246805447265, 722.2813300765772, 732.6556518436327, 736.5727877830846, 740.2227660956082, 740.5815714121504, 719.2412005334422, 730.3901242462497, 794.527256282511, 832.5545832979327, 833.4790755777639, 10162.772836508566, 10263.332883362558, 10440.337713882911, 10511.0, 10820.513076911559, 776.6233376812241, 783.8432313537315, 799.1089934722743, 813.6663967773185, 814.0, 4986.4514903360605, 5359.383670877821, 5424.294841670558, 5435.347758269033, 5475.233467314579, 129736.0, 131516.34241828922, 132255.0, 133593.33203333922, 139260.51848703864, 113436.43281783539, 114088.42955785168, 118316.40841795909, 119358.40320798515, 119887.0, 95628.0, 98422.5078874601, 99719.0, 100128.49935750275, 101945.99999999852, 10176.491175441346, 10383.688489345332, 10460.163186944874, 10527.263091583822, 10584.312019718695, 56688.716556416955, 57596.0, 57763.0, 58516.414835851094, 59059.40940590711, 77884.61057694902, 83904.32191356017, 87836.56081719806, 89667.55166224127, 90596.54701726449, 12396.938015309866, 12536.686582835137, 12987.155834870704, 13045.282509461691, 13057.0, 15020.92489537589, 15428.382864685287, 16143.67712645717, 16149.1925403727, 16297.59256018609, 13602.659933501742, 13704.588862334145, 14178.14931104137, 14328.426862925366, 14987.250637468302, 13979.370928308048, 14334.856651433352, 14341.0, 14440.711185776436, 14529.709405812036, 13383.53157639478, 13389.263590502616, 13686.52097176594, 13830.930845345709, 13996.510122145675, 11642.24325418845, 11722.94138529302, 11830.645080647595, 12320.137590368588, 12417.503299867903, 10718.35689858611, 11863.169578129447, 12237.326947018, 12327.260364378166, 12527.123101382816], "value": 8365.28250948382, "unit": "score"}], "name": "dromaeo_css", "value": 7693.06101850644, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 111.0, 110.0, 111.0, 113.0, 111.0, 111.0, 113.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 121.0, 116.0, 147.0, 116.0, 118.0, 115.0, 121.0, 153.0, 115.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 162.0, 159.0, 160.0, 160.0, 159.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 110.0, 75.0, 77.0, 76.0, 78.0, 99.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 110.0, 81.0, 81.0, 82.0, 103.0, 83.0, 82.0, 124.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 48.0, 48.0, 48.0, 49.0, 50.0, 49.0, 49.0, 49.0], "value": 49.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 58.0, 58.0, 60.0, 58.0, 58.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 74.0, 74.0, 79.0, 73.0, 77.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 122.0, 127.0, 119.0, 119.0, 120.0, 127.0, 122.0, 162.0, 134.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 155.0, 156.0, 155.0, 154.0, 155.0, 156.0, 157.0, 152.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 66.0, 65.0, 62.0, 62.0, 62.0, 61.0, 59.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1454.7999999999997, "alertThreshold": 2.0}]} 05:43:27 INFO - Return code: 0 05:43:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:43:27 INFO - # TBPL SUCCESS # 05:43:27 INFO - Running post-action listener: _resource_record_post_action 05:43:27 INFO - [mozharness: 2016-10-04 12:43:27.899253Z] Finished run-tests step (success) 05:43:27 INFO - Running post-run listener: _resource_record_post_run 05:43:27 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2134016; Write bytes: 368812032; Read time: 684; Write time: 199564 05:43:27 INFO - TinderboxPrint: CPU usage
12.9% 05:43:27 INFO - TinderboxPrint: I/O read bytes / time
2,134,016 / 684 05:43:27 INFO - TinderboxPrint: I/O write bytes / time
368,812,032 / 199,564 05:43:27 INFO - TinderboxPrint: CPU idle
8,266.7 (87.0%) 05:43:27 INFO - TinderboxPrint: CPU user
1,186.2 (12.5%) 05:43:27 INFO - TinderboxPrint: Swap in / out
0 / 0 05:43:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118681600; Read time: 0; Write time: 59376 05:43:28 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 250130432; Read time: 76; Write time: 140188 05:43:28 INFO - Running post-run listener: _upload_blobber_files 05:43:28 INFO - Blob upload gear active. 05:43:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:28 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 05:43:28 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:43:28 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:43:28 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:43:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:29 INFO - (blobuploader) - INFO - Done attempting. 05:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:30 INFO - (blobuploader) - INFO - Done attempting. 05:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:43:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:33 INFO - (blobuploader) - INFO - Done attempting. 05:43:33 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:33 INFO - Return code: 0 05:43:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dad5d94d49eb63342dc2e219f86d8851319fea115383bb8725ce26b7a6279fd6ac0e439586e64889cc2b61961135c6b941d0fe4b35684e9d29c8a41d96b063fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be0fae63091e6a5fbe9faa525fb4b76a17be3edd0a9ed7774862c460025def7e31e49cf60597d78fab747cef7c21e231cc208fbbc79738aaee2ccdb5363bde78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2f066cf449d77acfc66eafa477c2ce993104afd8c2c064e474b080ab72090a88c0768af0a31d8a5c2f393f7788327dfef52f1b0847128aa7032aee90f65027c"} 05:43:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:33 INFO - Contents: 05:43:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dad5d94d49eb63342dc2e219f86d8851319fea115383bb8725ce26b7a6279fd6ac0e439586e64889cc2b61961135c6b941d0fe4b35684e9d29c8a41d96b063fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be0fae63091e6a5fbe9faa525fb4b76a17be3edd0a9ed7774862c460025def7e31e49cf60597d78fab747cef7c21e231cc208fbbc79738aaee2ccdb5363bde78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2f066cf449d77acfc66eafa477c2ce993104afd8c2c064e474b080ab72090a88c0768af0a31d8a5c2f393f7788327dfef52f1b0847128aa7032aee90f65027c"} 05:43:33 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:33 INFO - Copying logs to upload dir... 05:43:33 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.451042 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-10-04 05:43:33.346328) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 05:43:33.349656) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994 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-Aurora/sha512/dad5d94d49eb63342dc2e219f86d8851319fea115383bb8725ce26b7a6279fd6ac0e439586e64889cc2b61961135c6b941d0fe4b35684e9d29c8a41d96b063fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be0fae63091e6a5fbe9faa525fb4b76a17be3edd0a9ed7774862c460025def7e31e49cf60597d78fab747cef7c21e231cc208fbbc79738aaee2ccdb5363bde78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2f066cf449d77acfc66eafa477c2ce993104afd8c2c064e474b080ab72090a88c0768af0a31d8a5c2f393f7788327dfef52f1b0847128aa7032aee90f65027c"} build_url:https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/A6MC8R5wRPOvWQnc2XlTpw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dad5d94d49eb63342dc2e219f86d8851319fea115383bb8725ce26b7a6279fd6ac0e439586e64889cc2b61961135c6b941d0fe4b35684e9d29c8a41d96b063fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be0fae63091e6a5fbe9faa525fb4b76a17be3edd0a9ed7774862c460025def7e31e49cf60597d78fab747cef7c21e231cc208fbbc79738aaee2ccdb5363bde78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2f066cf449d77acfc66eafa477c2ce993104afd8c2c064e474b080ab72090a88c0768af0a31d8a5c2f393f7788327dfef52f1b0847128aa7032aee90f65027c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 05:43:33.375811) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:43:33.376165) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475581237.624486-1423799994 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004563 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 05:43:33.461660) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 05:43:33.462082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 05:43:33.462500) ========= ========= Total master_lag: 0.21 =========