builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-041 starttime: 1482817616.09 results: success (0) buildid: 20161226205936 builduid: defa6e964a894aa096dbd479828fbf6e revision: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.096848) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.097630) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.098162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.142290) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.142751) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-26 21:46:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-12-26 21:46:56 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.192754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.355074) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.355472) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.076614 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.445885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:56.446263) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a --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=8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-26 21:46:56,615 truncating revision to first 12 chars 2016-12-26 21:46:56,615 Setting DEBUG logging. 2016-12-26 21:46:56,615 attempt 1/10 2016-12-26 21:46:56,615 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4a1f36ebd21c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 21:46:56,937 unpacking tar archive at: mozilla-central-4a1f36ebd21c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.638619 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:57.106568) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:57.106954) ========= script_repo_revision: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:57.107402) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:57.107716) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 21:46:57.121213) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-12-26 21:46:57.121535) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:46:57 INFO - MultiFileLogger online at 20161226 21:46:57 in /builds/slave/test 21:46:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:46:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:46:57 INFO - {'append_to_log': False, 21:46:57 INFO - 'base_work_dir': '/builds/slave/test', 21:46:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:46:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:46:57 INFO - 'branch': 'Firefox-Non-PGO', 21:46:57 INFO - 'buildbot_json_path': 'buildprops.json', 21:46:57 INFO - 'config_files': ('talos/linux_config.py',), 21:46:57 INFO - 'default_actions': ('clobber', 21:46:57 INFO - 'read-buildbot-config', 21:46:57 INFO - 'download-and-extract', 21:46:57 INFO - 'populate-webroot', 21:46:57 INFO - 'create-virtualenv', 21:46:57 INFO - 'install', 21:46:57 INFO - 'run-tests'), 21:46:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:46:57 INFO - 'download_minidump_stackwalk': True, 21:46:57 INFO - 'download_symbols': 'ondemand', 21:46:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:46:57 INFO - 'tooltool.py': '/tools/tooltool.py', 21:46:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:46:57 INFO - '/tools/misc-python/virtualenv.py')}, 21:46:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:46:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:46:57 INFO - 'installer_path': 'installer.exe', 21:46:57 INFO - 'log_level': 'info', 21:46:57 INFO - 'log_name': 'talos', 21:46:57 INFO - 'log_to_console': True, 21:46:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:46:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:46:57 INFO - 'opt_config_files': (), 21:46:57 INFO - 'pip_index': False, 21:46:57 INFO - 'sps_profile': False, 21:46:57 INFO - 'sps_profile_interval': 0, 21:46:57 INFO - 'suite': 'dromaeojs', 21:46:57 INFO - 'system_bits': '32', 21:46:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:46:57 INFO - 'title': 'talos-linux64-ix-041', 21:46:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:46:57 INFO - 'use_talos_json': True, 21:46:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:46:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:46:57 INFO - 'work_dir': 'build'} 21:46:57 INFO - [mozharness: 2016-12-27 05:46:57.518454Z] Running clobber step. 21:46:57 INFO - Running pre-action listener: _resource_record_pre_action 21:46:57 INFO - Running main action method: clobber 21:46:57 INFO - rmtree: /builds/slave/test/build 21:46:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:47:01 INFO - Running post-action listener: _resource_record_post_action 21:47:01 INFO - [mozharness: 2016-12-27 05:47:01.538757Z] Finished clobber step (success) 21:47:01 INFO - [mozharness: 2016-12-27 05:47:01.538961Z] Running read-buildbot-config step. 21:47:01 INFO - Running pre-action listener: _resource_record_pre_action 21:47:01 INFO - Running main action method: read_buildbot_config 21:47:01 INFO - Using buildbot properties: 21:47:01 INFO - { 21:47:01 INFO - "project": "", 21:47:01 INFO - "product": "firefox", 21:47:01 INFO - "script_repo_revision": "production", 21:47:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:47:01 INFO - "repository": "", 21:47:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 21:47:01 INFO - "buildid": "20161226205936", 21:47:01 INFO - "pgo_build": "False", 21:47:01 INFO - "basedir": "/builds/slave/test", 21:47:01 INFO - "buildnumber": 753, 21:47:01 INFO - "slavename": "talos-linux64-ix-041", 21:47:01 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:47:01 INFO - "platform": "ubuntu64_hw", 21:47:01 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 21:47:01 INFO - "branch": "mozilla-central", 21:47:01 INFO - "repo_path": "mozilla-central", 21:47:01 INFO - "revision": "4a1f36ebd21cbe090cd5d0ce6458af3d883a868a", 21:47:01 INFO - "stage_platform": "linux64", 21:47:01 INFO - "builduid": "defa6e964a894aa096dbd479828fbf6e", 21:47:01 INFO - "slavebuilddir": "test" 21:47:01 INFO - } 21:47:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:47:01 INFO - Running post-action listener: _resource_record_post_action 21:47:01 INFO - [mozharness: 2016-12-27 05:47:01.542529Z] Finished read-buildbot-config step (success) 21:47:01 INFO - [mozharness: 2016-12-27 05:47:01.542655Z] Running download-and-extract step. 21:47:01 INFO - Running pre-action listener: _resource_record_pre_action 21:47:01 INFO - Running main action method: download_and_extract 21:47:01 INFO - mkdir: /builds/slave/test/build/tests 21:47: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')]} 21:47:01 INFO - trying https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:47:01 INFO - Downloading https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:47:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:47:02 INFO - Downloaded 1361 bytes. 21:47:02 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:47:02 INFO - Using the following test package requirements: 21:47:02 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:47:02 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:47:02 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:47:02 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'jsshell-linux-x86_64.zip'], 21:47:02 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:47:02 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:47:02 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:47:02 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:47:02 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:47:02 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:47:02 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:47:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:47:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:47:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:47:02 INFO - Fetch https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:47:02 INFO - Expected file size: 20241340 21:47:02 INFO - Obtained file size: 20241340 21:47:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:47:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:47:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:47:06 INFO - Fetch https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:47:07 INFO - Expected file size: 20241340 21:47:07 INFO - Obtained file size: 20241340 21:47:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:47:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:47:12 INFO - Fetch https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:47:12 INFO - Expected file size: 14409776 21:47:12 INFO - Obtained file size: 14409776 21:47: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')]} 21:47:13 INFO - trying https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:47:13 INFO - Downloading https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:47:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:47:14 INFO - Downloaded 55898912 bytes. 21:47:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:47:14 INFO - mkdir: /builds/slave/test/properties 21:47:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:47:14 INFO - Writing to file /builds/slave/test/properties/build_url 21:47:14 INFO - Contents: 21:47:14 INFO - build_url:https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:47:14 INFO - Running post-action listener: _resource_record_post_action 21:47:14 INFO - Running post-action listener: set_extra_try_arguments 21:47:14 INFO - [mozharness: 2016-12-27 05:47:14.861300Z] Finished download-and-extract step (success) 21:47:14 INFO - [mozharness: 2016-12-27 05:47:14.861527Z] Running populate-webroot step. 21:47:14 INFO - Running pre-action listener: _resource_record_pre_action 21:47:14 INFO - Running main action method: populate_webroot 21:47:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:47:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:47:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:47:14 INFO - '--tppagecycles', 21:47:14 INFO - '7'], 21:47:14 INFO - 'tests': ['tsvgm']}}, 21:47:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:47:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:47:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:47:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:47:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:47:14 INFO - 'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:47:14 INFO - 'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['damp', 'tps']}, 21:47:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:47:14 INFO - 'tests': ['damp', 'tps']}, 21:47:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['dromaeo_dom']}, 21:47:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:47:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['basic_compositor_video']}, 21:47:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:47:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['a11yr', 21:47:14 INFO - 'ts_paint', 21:47:14 INFO - 'tpaint', 21:47:14 INFO - 'sessionrestore', 21:47:14 INFO - 'sessionrestore_no_auto_restore', 21:47:14 INFO - 'tabpaint']}, 21:47:14 INFO - 'other-e10s': {'tests': ['a11yr', 21:47:14 INFO - 'ts_paint', 21:47:14 INFO - 'tpaint', 21:47:14 INFO - 'sessionrestore', 21:47:14 INFO - 'sessionrestore_no_auto_restore', 21:47:14 INFO - 'tabpaint']}, 21:47:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:47:14 INFO - 'ts_paint', 21:47:14 INFO - 'tpaint', 21:47:14 INFO - 'sessionrestore', 21:47:14 INFO - 'sessionrestore_no_auto_restore', 21:47:14 INFO - 'tabpaint']}, 21:47:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:47:14 INFO - 'ts_paint', 21:47:14 INFO - 'tpaint', 21:47:14 INFO - 'sessionrestore', 21:47:14 INFO - 'sessionrestore_no_auto_restore', 21:47:14 INFO - 'tabpaint']}, 21:47:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['a11yr', 21:47:14 INFO - 'ts_paint', 21:47:14 INFO - 'tpaint', 21:47:14 INFO - 'sessionrestore', 21:47:14 INFO - 'sessionrestore_no_auto_restore', 21:47:14 INFO - 'tabpaint']}, 21:47:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['a11yr', 21:47:14 INFO - 'ts_paint', 21:47:14 INFO - 'tpaint', 21:47:14 INFO - 'sessionrestore', 21:47:14 INFO - 'sessionrestore_no_auto_restore', 21:47:14 INFO - 'tabpaint']}, 21:47:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['tsvgx', 21:47:14 INFO - 'tsvgr_opacity', 21:47:14 INFO - 'tart', 21:47:14 INFO - 'tscrollx', 21:47:14 INFO - 'cart', 21:47:14 INFO - 'tsvg_static']}, 21:47:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:47:14 INFO - 'tsvgr_opacity', 21:47:14 INFO - 'tart', 21:47:14 INFO - 'tscrollx', 21:47:14 INFO - 'cart', 21:47:14 INFO - 'tsvg_static']}, 21:47:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:47:14 INFO - 'talos_options': ['--disable-e10s'], 21:47:14 INFO - 'tests': ['tp5o']}, 21:47:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:47:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:47:14 INFO - 'talos_options': ['--disable-e10s', 21:47:14 INFO - '--xperf_path', 21:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:14 INFO - 'tests': ['tp5n']}, 21:47:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:47:14 INFO - 'talos_options': ['--xperf_path', 21:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:14 INFO - 'tests': ['tp5n']}}, 21:47:14 INFO - 'talos.zip': {'path': '', 21:47:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:47:14 INFO - Running post-action listener: _resource_record_post_action 21:47:14 INFO - [mozharness: 2016-12-27 05:47:14.885647Z] Finished populate-webroot step (success) 21:47:14 INFO - [mozharness: 2016-12-27 05:47:14.885735Z] Running create-virtualenv step. 21:47:14 INFO - Running pre-action listener: _resource_record_pre_action 21:47:14 INFO - Running main action method: create_virtualenv 21:47:14 INFO - Creating virtualenv /builds/slave/test/build/venv 21:47:14 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 21:47:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:47:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:47:15 INFO - Using real prefix '/usr' 21:47:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:47:15 INFO - Installing distribute.............................................................................................................................................................................................done. 21:47:17 INFO - Installing pip.................done. 21:47:17 INFO - Return code: 0 21:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:47:17 INFO - Reading from file tmpfile_stdout 21:47:17 INFO - Output received: 21:47:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:47:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:17 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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:17 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 21:47:17 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 21:47:17 INFO - Using env: {'DISPLAY': ':0', 21:47:17 INFO - 'HOME': '/home/cltbld', 21:47:17 INFO - 'LANG': 'en_US.UTF-8', 21:47:17 INFO - 'LANGUAGE': 'en_US:en', 21:47:17 INFO - 'LOGNAME': 'cltbld', 21:47:17 INFO - 'MAIL': '/var/mail/cltbld', 21:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:17 INFO - 'MOZ_NO_REMOTE': '1', 21:47:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:17 INFO - 'NO_EM_RESTART': '1', 21:47:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:17 INFO - 'PWD': '/builds/slave/test', 21:47:17 INFO - 'SHELL': '/bin/bash', 21:47:17 INFO - 'SHLVL': '1', 21:47:17 INFO - 'TERM': 'linux', 21:47:17 INFO - 'TMOUT': '86400', 21:47:17 INFO - 'USER': 'cltbld', 21:47:17 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:17 INFO - '_': '/tools/buildbot/bin/python'} 21:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47: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 21:47:18 INFO - Cleaning up... 21:47:18 INFO - Return code: 0 21:47:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47: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 21:47: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 21:47:18 INFO - Using env: {'DISPLAY': ':0', 21:47:18 INFO - 'HOME': '/home/cltbld', 21:47:18 INFO - 'LANG': 'en_US.UTF-8', 21:47:18 INFO - 'LANGUAGE': 'en_US:en', 21:47:18 INFO - 'LOGNAME': 'cltbld', 21:47:18 INFO - 'MAIL': '/var/mail/cltbld', 21:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:18 INFO - 'MOZ_NO_REMOTE': '1', 21:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:18 INFO - 'NO_EM_RESTART': '1', 21:47:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:18 INFO - 'PWD': '/builds/slave/test', 21:47:18 INFO - 'SHELL': '/bin/bash', 21:47:18 INFO - 'SHLVL': '1', 21:47:18 INFO - 'TERM': 'linux', 21:47:18 INFO - 'TMOUT': '86400', 21:47:18 INFO - 'USER': 'cltbld', 21:47:18 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:18 INFO - '_': '/tools/buildbot/bin/python'} 21:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:18 INFO - Downloading/unpacking psutil>=3.1.1 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:47:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:20 INFO - Installing collected packages: psutil 21:47:20 INFO - Running setup.py install for psutil 21:47:20 INFO - building 'psutil._psutil_linux' extension 21:47: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 21:47: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:47:21 INFO - building 'psutil._psutil_posix' extension 21:47:21 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 21:47:22 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 21:47:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:22 INFO - Successfully installed psutil 21:47:22 INFO - Cleaning up... 21:47:22 INFO - Return code: 0 21:47:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:47:22 INFO - Using env: {'DISPLAY': ':0', 21:47:22 INFO - 'HOME': '/home/cltbld', 21:47:22 INFO - 'LANG': 'en_US.UTF-8', 21:47:22 INFO - 'LANGUAGE': 'en_US:en', 21:47:22 INFO - 'LOGNAME': 'cltbld', 21:47:22 INFO - 'MAIL': '/var/mail/cltbld', 21:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:22 INFO - 'MOZ_NO_REMOTE': '1', 21:47:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:22 INFO - 'NO_EM_RESTART': '1', 21:47:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:22 INFO - 'PWD': '/builds/slave/test', 21:47:22 INFO - 'SHELL': '/bin/bash', 21:47:22 INFO - 'SHLVL': '1', 21:47:22 INFO - 'TERM': 'linux', 21:47:22 INFO - 'TMOUT': '86400', 21:47:22 INFO - 'USER': 'cltbld', 21:47:22 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:22 INFO - '_': '/tools/buildbot/bin/python'} 21:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:47:22 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 21:47:22 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 21:47:22 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 21:47:22 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 21:47:22 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 21:47:22 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 21:47:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:47:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:47:24 INFO - Installing collected packages: mozsystemmonitor 21:47:24 INFO - Running setup.py install for mozsystemmonitor 21:47:24 INFO - Successfully installed mozsystemmonitor 21:47:24 INFO - Cleaning up... 21:47:24 INFO - Return code: 0 21:47:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:47:24 INFO - Using env: {'DISPLAY': ':0', 21:47:24 INFO - 'HOME': '/home/cltbld', 21:47:24 INFO - 'LANG': 'en_US.UTF-8', 21:47:24 INFO - 'LANGUAGE': 'en_US:en', 21:47:24 INFO - 'LOGNAME': 'cltbld', 21:47:24 INFO - 'MAIL': '/var/mail/cltbld', 21:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:24 INFO - 'MOZ_NO_REMOTE': '1', 21:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:24 INFO - 'NO_EM_RESTART': '1', 21:47:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:24 INFO - 'PWD': '/builds/slave/test', 21:47:24 INFO - 'SHELL': '/bin/bash', 21:47:24 INFO - 'SHLVL': '1', 21:47:24 INFO - 'TERM': 'linux', 21:47:24 INFO - 'TMOUT': '86400', 21:47:24 INFO - 'USER': 'cltbld', 21:47:24 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:24 INFO - '_': '/tools/buildbot/bin/python'} 21:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:25 INFO - Downloading/unpacking jsonschema==2.5.1 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:47:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:27 INFO - Downloading functools32-3.2.3-2.tar.gz 21:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:47:27 INFO - warning: no files found matching '*.txt' 21:47:27 INFO - no previously-included directories found matching 'build' 21:47:27 INFO - no previously-included directories found matching 'dist' 21:47:27 INFO - no previously-included directories found matching '.git*' 21:47:27 INFO - Installing collected packages: jsonschema, functools32 21:47:27 INFO - Running setup.py install for functools32 21:47:27 INFO - warning: no files found matching '*.txt' 21:47:27 INFO - no previously-included directories found matching 'build' 21:47:27 INFO - no previously-included directories found matching 'dist' 21:47:27 INFO - no previously-included directories found matching '.git*' 21:47:27 INFO - Successfully installed jsonschema functools32 21:47:27 INFO - Cleaning up... 21:47:27 INFO - Return code: 0 21:47:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:47:27 INFO - Using env: {'DISPLAY': ':0', 21:47:27 INFO - 'HOME': '/home/cltbld', 21:47:27 INFO - 'LANG': 'en_US.UTF-8', 21:47:27 INFO - 'LANGUAGE': 'en_US:en', 21:47:27 INFO - 'LOGNAME': 'cltbld', 21:47:27 INFO - 'MAIL': '/var/mail/cltbld', 21:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:27 INFO - 'MOZ_NO_REMOTE': '1', 21:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:27 INFO - 'NO_EM_RESTART': '1', 21:47:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:27 INFO - 'PWD': '/builds/slave/test', 21:47:27 INFO - 'SHELL': '/bin/bash', 21:47:27 INFO - 'SHLVL': '1', 21:47:27 INFO - 'TERM': 'linux', 21:47:27 INFO - 'TMOUT': '86400', 21:47:27 INFO - 'USER': 'cltbld', 21:47:27 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:27 INFO - '_': '/tools/buildbot/bin/python'} 21:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:47:27 INFO - Cleaning up... 21:47:27 INFO - Return code: 0 21:47:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:47:27 INFO - Using env: {'DISPLAY': ':0', 21:47:27 INFO - 'HOME': '/home/cltbld', 21:47:27 INFO - 'LANG': 'en_US.UTF-8', 21:47:27 INFO - 'LANGUAGE': 'en_US:en', 21:47:27 INFO - 'LOGNAME': 'cltbld', 21:47:27 INFO - 'MAIL': '/var/mail/cltbld', 21:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:27 INFO - 'MOZ_NO_REMOTE': '1', 21:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:27 INFO - 'NO_EM_RESTART': '1', 21:47:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:27 INFO - 'PWD': '/builds/slave/test', 21:47:27 INFO - 'SHELL': '/bin/bash', 21:47:27 INFO - 'SHLVL': '1', 21:47:27 INFO - 'TERM': 'linux', 21:47:27 INFO - 'TMOUT': '86400', 21:47:27 INFO - 'USER': 'cltbld', 21:47:27 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:27 INFO - '_': '/tools/buildbot/bin/python'} 21:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:27 INFO - Downloading/unpacking blobuploader==1.2.4 21:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:29 INFO - Downloading blobuploader-1.2.4.tar.gz 21:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:47:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:47:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 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 21:47:30 INFO - Downloading docopt-0.6.1.tar.gz 21:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:47:30 INFO - Installing collected packages: blobuploader, requests, docopt 21:47:30 INFO - Running setup.py install for blobuploader 21:47:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:47:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:47:30 INFO - Running setup.py install for requests 21:47:31 INFO - Running setup.py install for docopt 21:47:31 INFO - Successfully installed blobuploader requests docopt 21:47:31 INFO - Cleaning up... 21:47:31 INFO - Return code: 0 21:47:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:31 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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:31 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 21:47:31 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 21:47:31 INFO - Using env: {'DISPLAY': ':0', 21:47:31 INFO - 'HOME': '/home/cltbld', 21:47:31 INFO - 'LANG': 'en_US.UTF-8', 21:47:31 INFO - 'LANGUAGE': 'en_US:en', 21:47:31 INFO - 'LOGNAME': 'cltbld', 21:47:31 INFO - 'MAIL': '/var/mail/cltbld', 21:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:31 INFO - 'MOZ_NO_REMOTE': '1', 21:47:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:31 INFO - 'NO_EM_RESTART': '1', 21:47:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:31 INFO - 'PWD': '/builds/slave/test', 21:47:31 INFO - 'SHELL': '/bin/bash', 21:47:31 INFO - 'SHLVL': '1', 21:47:31 INFO - 'TERM': 'linux', 21:47:31 INFO - 'TMOUT': '86400', 21:47:31 INFO - 'USER': 'cltbld', 21:47:31 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:31 INFO - '_': '/tools/buildbot/bin/python'} 21:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:31 INFO - Running setup.py (path:/tmp/pip-aN9s35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:31 INFO - Running setup.py (path:/tmp/pip-M9nDAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:31 INFO - Running setup.py (path:/tmp/pip-PEewIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:31 INFO - Running setup.py (path:/tmp/pip-sG1v4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:31 INFO - Running setup.py (path:/tmp/pip-ffC7br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:31 INFO - Running setup.py (path:/tmp/pip-v0xU0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:32 INFO - Running setup.py (path:/tmp/pip-HZW8Mh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:32 INFO - Running setup.py (path:/tmp/pip-h_LamJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:32 INFO - Running setup.py (path:/tmp/pip-Ryfjjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:32 INFO - Running setup.py (path:/tmp/pip-IIgyQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:32 INFO - Running setup.py (path:/tmp/pip-j8fOBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:32 INFO - Running setup.py (path:/tmp/pip-6Wgvcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:32 INFO - Running setup.py (path:/tmp/pip-4y6KD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:32 INFO - Running setup.py (path:/tmp/pip-OyumuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:32 INFO - Running setup.py (path:/tmp/pip-kyGamk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:32 INFO - Running setup.py (path:/tmp/pip-G6DRpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:32 INFO - Running setup.py (path:/tmp/pip-ngTxTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:47:32 INFO - Running setup.py install for manifestparser 21:47:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:47:32 INFO - Running setup.py install for mozcrash 21:47:33 INFO - Running setup.py install for mozdebug 21:47:33 INFO - Running setup.py install for mozdevice 21:47:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Running setup.py install for mozfile 21:47:33 INFO - Running setup.py install for mozhttpd 21:47:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Running setup.py install for mozinfo 21:47:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Running setup.py install for mozInstall 21:47:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Running setup.py install for mozleak 21:47:33 INFO - Running setup.py install for mozlog 21:47:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Running setup.py install for moznetwork 21:47:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:47:33 INFO - Running setup.py install for mozprocess 21:47:34 INFO - Running setup.py install for mozprofile 21:47:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:47:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:47:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:47:34 INFO - Running setup.py install for mozrunner 21:47:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:47:34 INFO - Running setup.py install for mozscreenshot 21:47:34 INFO - Running setup.py install for moztest 21:47:34 INFO - Running setup.py install for mozversion 21:47:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:47:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:47:34 INFO - Cleaning up... 21:47:34 INFO - Return code: 0 21:47:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:34 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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:34 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 21:47:34 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 21:47:34 INFO - Using env: {'DISPLAY': ':0', 21:47:34 INFO - 'HOME': '/home/cltbld', 21:47:34 INFO - 'LANG': 'en_US.UTF-8', 21:47:34 INFO - 'LANGUAGE': 'en_US:en', 21:47:34 INFO - 'LOGNAME': 'cltbld', 21:47:34 INFO - 'MAIL': '/var/mail/cltbld', 21:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:34 INFO - 'MOZ_NO_REMOTE': '1', 21:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:34 INFO - 'NO_EM_RESTART': '1', 21:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:34 INFO - 'PWD': '/builds/slave/test', 21:47:34 INFO - 'SHELL': '/bin/bash', 21:47:34 INFO - 'SHLVL': '1', 21:47:34 INFO - 'TERM': 'linux', 21:47:34 INFO - 'TMOUT': '86400', 21:47:34 INFO - 'USER': 'cltbld', 21:47:34 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:34 INFO - '_': '/tools/buildbot/bin/python'} 21:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:34 INFO - Running setup.py (path:/tmp/pip-YYP_rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:34 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)) 21:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:34 INFO - Running setup.py (path:/tmp/pip-hF4VX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:35 INFO - Running setup.py (path:/tmp/pip-N5Rqur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:35 INFO - Running setup.py (path:/tmp/pip-FX3srf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:35 INFO - Running setup.py (path:/tmp/pip-Xn2Fh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:35 INFO - Running setup.py (path:/tmp/pip-oFkJyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:35 INFO - Running setup.py (path:/tmp/pip-ALZJqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:35 INFO - Running setup.py (path:/tmp/pip-ZqmoGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:35 INFO - Running setup.py (path:/tmp/pip-Zr9ylU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:35 INFO - Running setup.py (path:/tmp/pip-MwDZ82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:35 INFO - Running setup.py (path:/tmp/pip-XkEMLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:35 INFO - Running setup.py (path:/tmp/pip-fX4HJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:35 INFO - Running setup.py (path:/tmp/pip-x6DmQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:35 INFO - Running setup.py (path:/tmp/pip-ykVd1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:35 INFO - Running setup.py (path:/tmp/pip-qMfp3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:35 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)) 21:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:35 INFO - Running setup.py (path:/tmp/pip-dPtAtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:36 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)) 21:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:36 INFO - Running setup.py (path:/tmp/pip-D5XEKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:36 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)) 21:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:47:36 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)) 21:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:47:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:38 INFO - Downloading blessings-1.6.tar.gz 21:47:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:47:38 INFO - Installing collected packages: blessings 21:47:38 INFO - Running setup.py install for blessings 21:47:38 INFO - Successfully installed blessings 21:47:38 INFO - Cleaning up... 21:47:38 INFO - Return code: 0 21:47:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:38 INFO - Reading from file tmpfile_stdout 21:47:38 INFO - Current package versions: 21:47:38 INFO - argparse == 1.2.1 21:47:38 INFO - blessings == 1.6 21:47:38 INFO - blobuploader == 1.2.4 21:47:38 INFO - docopt == 0.6.1 21:47:38 INFO - functools32 == 3.2.3-2 21:47:38 INFO - jsonschema == 2.5.1 21:47:38 INFO - manifestparser == 1.1 21:47:38 INFO - mozInstall == 1.12 21:47:38 INFO - mozcrash == 1.0 21:47:38 INFO - mozdebug == 0.1 21:47:38 INFO - mozdevice == 0.49 21:47:38 INFO - mozfile == 1.2 21:47:38 INFO - mozhttpd == 0.7 21:47:38 INFO - mozinfo == 0.9 21:47:38 INFO - mozleak == 0.1 21:47:38 INFO - mozlog == 3.4 21:47:38 INFO - moznetwork == 0.27 21:47:38 INFO - mozprocess == 0.23 21:47:38 INFO - mozprofile == 0.28 21:47:38 INFO - mozrunner == 6.13 21:47:38 INFO - mozscreenshot == 0.1 21:47:38 INFO - mozsystemmonitor == 0.3 21:47:38 INFO - moztest == 0.7 21:47:38 INFO - mozversion == 1.4 21:47:38 INFO - psutil == 3.1.1 21:47:38 INFO - requests == 1.2.3 21:47:38 INFO - wsgiref == 0.1.2 21:47:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:38 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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:38 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 21:47:38 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 21:47:38 INFO - Using env: {'DISPLAY': ':0', 21:47:38 INFO - 'HOME': '/home/cltbld', 21:47:38 INFO - 'LANG': 'en_US.UTF-8', 21:47:38 INFO - 'LANGUAGE': 'en_US:en', 21:47:38 INFO - 'LOGNAME': 'cltbld', 21:47:38 INFO - 'MAIL': '/var/mail/cltbld', 21:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:38 INFO - 'MOZ_NO_REMOTE': '1', 21:47:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:38 INFO - 'NO_EM_RESTART': '1', 21:47:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:38 INFO - 'PWD': '/builds/slave/test', 21:47:38 INFO - 'SHELL': '/bin/bash', 21:47:38 INFO - 'SHLVL': '1', 21:47:38 INFO - 'TERM': 'linux', 21:47:38 INFO - 'TMOUT': '86400', 21:47:38 INFO - 'USER': 'cltbld', 21:47:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:38 INFO - '_': '/tools/buildbot/bin/python'} 21:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 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)) 21:47:38 INFO - Cleaning up... 21:47:38 INFO - Return code: 0 21:47:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:38 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 0xc4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfc0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', '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 21:47:38 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 21:47:38 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 21:47:38 INFO - Using env: {'DISPLAY': ':0', 21:47:38 INFO - 'HOME': '/home/cltbld', 21:47:38 INFO - 'LANG': 'en_US.UTF-8', 21:47:38 INFO - 'LANGUAGE': 'en_US:en', 21:47:38 INFO - 'LOGNAME': 'cltbld', 21:47:38 INFO - 'MAIL': '/var/mail/cltbld', 21:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:38 INFO - 'MOZ_NO_REMOTE': '1', 21:47:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:38 INFO - 'NO_EM_RESTART': '1', 21:47:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:38 INFO - 'PWD': '/builds/slave/test', 21:47:38 INFO - 'SHELL': '/bin/bash', 21:47:38 INFO - 'SHLVL': '1', 21:47:38 INFO - 'TERM': 'linux', 21:47:38 INFO - 'TMOUT': '86400', 21:47:38 INFO - 'USER': 'cltbld', 21:47:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:38 INFO - '_': '/tools/buildbot/bin/python'} 21:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:47:39 INFO - Cleaning up... 21:47:39 INFO - Return code: 0 21:47:39 INFO - Running post-action listener: _resource_record_post_action 21:47:39 INFO - Running post-action listener: _start_resource_monitoring 21:47:39 INFO - Starting resource monitoring. 21:47:39 INFO - [mozharness: 2016-12-27 05:47:39.113201Z] Finished create-virtualenv step (success) 21:47:39 INFO - [mozharness: 2016-12-27 05:47:39.113667Z] Running install step. 21:47:39 INFO - Running pre-action listener: _resource_record_pre_action 21:47:39 INFO - Running main action method: install 21:47:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:39 INFO - Reading from file tmpfile_stdout 21:47:39 INFO - Detecting whether we're running mozinstall >=1.0... 21:47:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:47:39 INFO - Reading from file tmpfile_stdout 21:47:39 INFO - Output received: 21:47:39 INFO - Usage: mozinstall [options] installer 21:47:39 INFO - Options: 21:47:39 INFO - -h, --help show this help message and exit 21:47:39 INFO - -d DEST, --destination=DEST 21:47:39 INFO - Directory to install application into. [default: 21:47:39 INFO - "/builds/slave/test"] 21:47:39 INFO - --app=APP Application being installed. [default: firefox] 21:47:39 INFO - mkdir: /builds/slave/test/build/application 21:47:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:47:55 INFO - Reading from file tmpfile_stdout 21:47:55 INFO - Output received: 21:47:55 INFO - /builds/slave/test/build/application/firefox/firefox 21:47:55 INFO - Running post-action listener: _resource_record_post_action 21:47:55 INFO - [mozharness: 2016-12-27 05:47:55.491002Z] Finished install step (success) 21:47:55 INFO - [mozharness: 2016-12-27 05:47:55.491250Z] Running run-tests step. 21:47:55 INFO - Running pre-action listener: _resource_record_pre_action 21:47:55 INFO - Running main action method: run_tests 21:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:47:55 INFO - Python 2.7.3 21:47:55 INFO - Return code: 0 21:47:55 INFO - grabbing minidump binary from tooltool 21:47:55 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')]} 21:47:55 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 0x1049850>, '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 0xfbe6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfbeb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:47:55 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 21:47:55 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 21:47:55 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 21:47:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:47:55 INFO - Return code: 0 21:47:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:47:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:47:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:47:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:47:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 21:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 21:47:55 INFO - Using env: {'DISPLAY': ':0', 21:47:55 INFO - 'HOME': '/home/cltbld', 21:47:55 INFO - 'LANG': 'en_US.UTF-8', 21:47:55 INFO - 'LANGUAGE': 'en_US:en', 21:47:55 INFO - 'LOGNAME': 'cltbld', 21:47:55 INFO - 'MAIL': '/var/mail/cltbld', 21:47:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:47:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:55 INFO - 'MOZ_NO_REMOTE': '1', 21:47:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:47:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:55 INFO - 'NO_EM_RESTART': '1', 21:47:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:55 INFO - 'PWD': '/builds/slave/test', 21:47:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:47:55 INFO - 'SHELL': '/bin/bash', 21:47:55 INFO - 'SHLVL': '1', 21:47:55 INFO - 'TERM': 'linux', 21:47:55 INFO - 'TMOUT': '86400', 21:47:55 INFO - 'USER': 'cltbld', 21:47:55 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668', 21:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:55 INFO - '_': '/tools/buildbot/bin/python'} 21:47:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 21:47:55 INFO - mozversion application_buildid: 20161226205936 21:47:55 INFO - mozversion application_changeset: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a 21:47:55 INFO - mozversion application_display_name: Nightly 21:47:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:47:55 INFO - mozversion application_name: Firefox 21:47:55 INFO - mozversion application_remotingname: firefox 21:47:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:47:55 INFO - mozversion application_vendor: Mozilla 21:47:55 INFO - mozversion application_version: 53.0a1 21:47:55 INFO - mozversion platform_buildid: 20161226205936 21:47:55 INFO - mozversion platform_changeset: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a 21:47:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:47:55 INFO - mozversion platform_version: 53.0a1 21:47:55 INFO - using testdate: 1482817675 21:47:55 INFO - actual date: 1482817675 21:47:55 INFO - starting webserver on 'localhost:38416' 21:47:55 INFO - SUITE-START | Running 2 tests 21:47:55 INFO - TEST-START | dromaeo_css 21:47:55 INFO - operating with platform_type : linux_ 21:47:55 INFO - Initialising browser for dromaeo_css test... 21:47:55 INFO - TEST-INFO | started process 3340 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCw37UW/profile http://localhost:38416/getInfo.html) 21:47:56 INFO - PROCESS | 3340 | 1482817676579 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 21:48:01 INFO - PROCESS | 3340 | __metrics Screen width/height:1600/1200 21:48:01 INFO - PROCESS | 3340 | colorDepth:24 21:48:01 INFO - PROCESS | 3340 | Browser inner width/height: 1024/697 21:48:01 INFO - PROCESS | 3340 | __metrics 21:48:02 INFO - PROCESS | 3340 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:48:02 INFO - PROCESS | 3340 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:48:03 INFO - PROCESS | 3340 | console.error: PushService: 21:48:03 INFO - PROCESS | 3340 | stateChangeProcessEnqueue: Error transitioning state 21:48:03 INFO - PROCESS | 3340 | UnknownError 21:48:04 INFO - TEST-INFO | 3340: exit 0 21:48:04 INFO - Browser initialized. 21:48:04 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:04 INFO - TEST-INFO | started process 3422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCw37UW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:16 INFO - PROCESS | 3422 | RSS: Main: 155701248 21:48:16 INFO - PROCESS | 3422 | 21:51:21 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:38416/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38416/tests/dromaeo/cssquery-ext.html) 21:51:23 INFO - PROCESS | 3422 | RSS: Main: 269897728 21:51:23 INFO - PROCESS | 3422 | 21:53:05 INFO - PROCESS | 3422 | ************************* 21:53:05 INFO - PROCESS | 3422 | A coding exception was thrown and uncaught in a Task. 21:53:05 INFO - PROCESS | 3422 | 21:53:05 INFO - PROCESS | 3422 | Full message: ReferenceError: fetch is not defined 21:53:05 INFO - PROCESS | 3422 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:53:05 INFO - PROCESS | 3422 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:53:05 INFO - PROCESS | 3422 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:53:05 INFO - PROCESS | 3422 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:53:05 INFO - PROCESS | 3422 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:53:05 INFO - PROCESS | 3422 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:53:05 INFO - PROCESS | 3422 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:53:05 INFO - PROCESS | 3422 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:53:05 INFO - PROCESS | 3422 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:53:05 INFO - PROCESS | 3422 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:53:05 INFO - PROCESS | 3422 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:53:05 INFO - PROCESS | 3422 | 21:53:05 INFO - PROCESS | 3422 | ************************* 21:54:25 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:38416/tests/dromaeo/cssquery-ext.html (next: http://localhost:38416/tests/dromaeo/cssquery-jquery.html) 21:54:26 INFO - PROCESS | 3422 | RSS: Main: 242147328 21:54:26 INFO - PROCESS | 3422 | 21:57:28 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:38416/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38416/tests/dromaeo/cssquery-mootools.html) 21:57:29 INFO - PROCESS | 3422 | RSS: Main: 217313280 21:57:29 INFO - PROCESS | 3422 | 22:00:31 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:38416/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38416/tests/dromaeo/cssquery-prototype.html) 22:00:32 INFO - PROCESS | 3422 | RSS: Main: 203870208 22:00:32 INFO - PROCESS | 3422 | 22:03:35 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:38416/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38416/tests/dromaeo/cssquery-yui.html) 22:03:35 INFO - PROCESS | 3422 | RSS: Main: 188874752 22:03:35 INFO - PROCESS | 3422 | 22:06:38 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:38416/tests/dromaeo/cssquery-yui.html (next: http://localhost:38416/tests/dromaeo/cssquery-dojo.html) 22:06:39 INFO - PROCESS | 3422 | RSS: Main: 281686016 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | __start_tp_report 22:06:39 INFO - PROCESS | 3422 | _x_x_mozilla_page_load 22:06:39 INFO - PROCESS | 3422 | _x_x_mozilla_page_load_details 22:06:39 INFO - PROCESS | 3422 | |i|pagename|runs| 22:06:39 INFO - PROCESS | 3422 | |0;dojo.html;2564.7178810330865;2583.728708485609;2593.286846117316;2609.152025591683;2618.8297760645555;4636.1191373638985;4746.027064451788;4746.3117847912035;4762.690425122369;4766.523347665236;4733.455652599952;4738.336632871401;4746.501617330183;4765;4793.952060479394;4281.165172791307;4366.4105345778235;4381.211381951248;4427.88930276744;4477.92529792849;4074.8166332515107;4394.736315821045;4460.353248778925;4491.640668746492;4539.364488971547;4312.676549258818;4347.391365208873;4372.234858899712;4419.734815911055;4430.867073987785;4377.7592232427205;4395.4725432947935;4396.648268138541;4397.032652816407;4399.780010999437;4348.412964249817;4371.322445021026;4372.715773474714;4390.692651514395;4390.736555806629;4715.575598196178;4799.760011999386;4812.037592481518;4814.951850481485;4824.927626085611;5089.898202035939;5097.541221290102;5099.133147364957;5141.691498510101;5151.66514176581;5100.668456550312;5119.59043276537;5121.617247989629;5164.535191832753;5190.71451070191;4653.092646933849;4684.5549672781035;4710.175719249118;4753.191957367256;4832.782524786393;5216.713080780593;5219.566177528731;5227.895442091137;5229.058769421465;5262.394824595144;4973.751312434364;5000.374953130859;5025.874353141201;5029.371328583927;5071.746412679351;4926.088673595396;4942.456329803718;4947.431045429749;5090.312807770978;5100.48995100487;5019.42276638184;5047.4447810740785;5050.545450909436;5103.413107492612;5113.897722045538;5060.089183946851;5060.443351231362;5072.416672082684;5072.492750724898;5124.077666020152;4371.125774845043;4376.059147283349;4393.20922234001;4393.406890069864;4442;3689.612590677994;3714.3685573452576;3727.7390582658954;3745.3445647011663;3765.548134223911;4320.092780516064;4323.048929235563;4337.479502459725;4353.9129217415475;4366.519682834885;4993.675411098267;5016.197408414636;5022.723750193702;5033.974830125899;5067.8986420271385;4280.6789490788315;4295.570442955679;4296.5273819879785;4311.913761724748;4351.108022855307;4565.726056436558;4584.1977653910435;4590.977045114753;4641.698289611165;4680.157571637138;4783.7129772213775;4788.640851936049;4788.760561971957;4796.352492413549;4845.394325709286;4825.420949486084;5080.745962701924;5127.923081153711;5133.153029750049;5184.326037615086;4533.750643714496;4710.4347478302825;4728.38530990969;4729.976350118227;4806.951930480651;4924.113659541317;5017.648764586444;5026.472220416804;5033.446320904624;5033.748312584283;5029.924551131663;5042.571381432619;5050.77271522775;5060.063888180698;5105.897882042264;4833.299171620166;4893.608511319015;4927.852164435073;4946.431160416454;4946.802127914843;4885.218365061573;4911.778969946433;4922.72924989129;4941.629377796751;4944.431390390151;4857.295692124693;4857.489963553776;4867.318575399518;4897.191963326011;4943.505649435027;5032.370953630796;5037.319961805109;5039;5040.521150490697;5077.746112694276;4923.335349727741;4923.655344125877;4924.335214745964;4929.334539837219;4929.975350123226 22:06:39 INFO - PROCESS | 3422 | |1;ext.html;522.997385013075;529.2696079410414;532.2734467451929;540.4649397096871;560.8401605542422;28532.857335713317;36792;38340.42489362658;38446.80776596113;42559.574404255945;39988;40435;40436.59563404365;40447.19105617886;40513.392299115534;7504.737334193304;7527.698892044312;7609.048868891388;7671.808204794869;7673.501222420553;7529.397648188159;9777.413355198676;10021.745107350824;10035.69892903214;10345.586176552966;9925.255605829592;10011.949940250288;10022.348547344436;10022.749431264241;10027.949860250688;9901.009899010078;9979.700608981742;9989.800203995952;9999.800003999952;10001.399916005026;9766.169875560587;9838.704838854846;9861.704148875546;9861.753456163582;9867.753306167404;37503;37648.811755941046;37684.24631507354;37806.62193378059;37838.810805945795;3522.982385088084;3596.2088340565033;3596.730245231592;3617.6744093031753;3625.6193099724414;28434.289142771388;28833.42333153346;29260.414791704046;29276.560851587245;29456.11631651054;3424.7945123292684;3469.444888817777;3590;3630.2195028068827;3671.94492082619;246380;247250;248401;248447;248585;51335.74332128315;51786.74106629518;51832.22251666228;52092.47907520952;52310.73844630752;55073.724631376586;55283.170752438746;55288;55325;55328;11859.703507412383;12163.087768417405;12166.635000949986;12169.2698438094;12171.0871684624;8433.451825631315;8687.218150366498;8687.739367818975;8695.08701586337;9031.412958157698;15165.393384264506;15622;15646.139462329453;15706.685866282838;15844.52466426009;5702.914456283159;5926.436988486087;5946.929552680473;5979.5814292999075;6007.219061521974;699.9745373028537;703.2756261051126;708.4904582333513;709.7941596936934;713.7965679781238;24865.751342486343;26171.738282617313;26318.21045368642;26376.60435093475;26456;898.5148020069089;930.4138392812484;942.4627962061696;952.842780941137;956.5073986896754;11814.822777658343;12805.615831525069;12892.677683057811;12941.805872911913;12964;337240;386372;392819;395200;555980;41772.37341439881;42227.36658950118;42311;42385.36421953673;42431.36352954708;1193.7314104326458;1209.4134344842744;1211.2429731417865;1213.690508920255;1217.7503611759569;24601;25029.624555631315;25490.617640735032;25811.483770324114;25847.741522585286;37301.253974920895;37496.81251593724;37662.81168594139;37669.62330376771;37740.2451950965;295462;297077;297242;297578;297599;20706.58586828285;21273.78726212718;21945.890270548545;22571.322860314216;22585.774142259026;34569.30861382708;34798.47802283018;34880;34891.82554087213;35078.12304692301;29876;30183.849080754455;30411.54382684306;30983.845080774452;31383.372332552764;30043.849780750956;30087.247818804706;30475.542866857457;31078.22304442407;31174.68825311718;29470.557941631323;30200.24499387533;30221.546676799426;30408;30435;26214.60678089792;26926;27142.864285678446;27446;27810.5828412578;21136.365909022756;21213.681794772783;21236.25673101434;21520.56958860783;21534.676979845 22:06:39 INFO - PROCESS | 3422 | |2;jquery.html;353.7064236683553;361.8805794087953;377.4357335783004;380.47114510829954;388.1906225519791;12700;15467.922660386695;15768.763468547966;15813.84186158141;16705.248263828125;15387.461438849643;16445.67108657829;16665.41671041514;16791.412300569453;16809.83190168098;4963.975180124095;5076.796928122889;5183.714895680736;5318.574514038867;5349.598780091489;3619.6199399063116;3793.108619474421;5335.5198032177095;5367.221752845835;5373.059714549958;5119.1809310510325;5326.813561525348;5373.167159090347;5507.5593952483905;5509.173623956399;5088.618353623493;5341.092014357569;5497.038018346793;5507.531859791922;5509.393966663643;5264.105102132608;5282.102042652758;5285.418603953562;5286.709230992294;5288.524032837025;11947.103967202407;15266;18996.43010709681;19531.511712207168;19675.212991480323;1953.1308567687386;1962.617289628523;1977.2782934229047;1998.5403357227774;2161.2651698422455;8851.911480885174;9058.864117038249;9120.8631870522;9477.715668529956;9657.758556036086;1662.3766087717106;1716.519374575121;1733.74860645207;1733.913304334778;1769.9203535840788;45863.770681146714;57649.13526297109;62486;63126;65703.3429665702;9294.395864268801;9799.216062714966;9819.950900245596;9824.164945979528;10058.446785426726;13594.592162235149;13616.38726257301;13620.046596738133;13624.455021799215;13627.092207091015;6321.52588555851;6379.936200638027;6384.7126879290545;6577.80266592003;6582.341765823379;5737.3688894221605;5794.913076303858;5916.26046744157;5920.73356698941;5929.080992446092;4056.290149223874;4088.9795551022053;4181.91636167281;4381.452318460192;4493.752843593568;1821.2077746180453;1878.7088001359812;1883.1619929131405;1886.3680666976716;1903.4194570656082;728.3372131360436;757.7688804914554;759.070139079623;780.8711562592222;783.7492002559127;14249.931255155905;14929;15217.695646087017;15708;15713.057216567042;746.2872956326803;748.655618415524;753.8454616803542;762.8207371267748;767.0986590755952;5736.942630573724;5787.276590426197;5823.679697616672;5856.882862342814;5905.350411454737;348734;349344;354235;354483;354492;52250.47749522532;72708.90936635954;72806;72825.6358718203;73366.63316683382;419.4630872483226;427.2906974422444;428.06467867709154;428.66992415839627;428.7727504422661;9137.634494620406;9154.816903662022;9248.537573121452;9623.374480658735;9857.35927164732;33476;33650.49524257187;34695.13262168466;34982.12544686403;35733.64266357303;44946.550534494236;45161.77419112883;45638.31542526931;45711.08577828491;48502;7516.3986881048295;7607.809804754926;7656.578888161204;7718.266764657348;7905.920940790518;8915.0193478716;11831.290122592673;12066.577669781516;12082.214656047328;12233.816492752794;10146.492675366055;10214.489275536045;10224.795504089729;10721.892781072089;10818.783624327627;9852.162566181954;10184.963007398697;10371.807242167248;10464.476776161313;10745.355278683304;10211.897881021094;10309.432981186106;10333.225008124271;10431;10683.465826708789;7708.152103268524;7925.167857375123;7964.960175199087;7966.203379661987;7971.92028079735;9783.559739811582;9853.605855765689;10135.594576216869;10215.13171380441;10319.94840025795 22:06:39 INFO - PROCESS | 3422 | |3;mootools.html;1325.933703314834;1326.847412547557;1339.604816579109;1340.0150889096512;1340.845802732686;6238.594491358059;6849.8287542811395;7249.130104387467;7252.20225775165;7259.927400725991;6259.467945224661;6455.935440645592;6465.579737317083;6467.967660161693;6831.1119554457855;2077.7610574783903;2136.1028368085363;2140.2616097446435;2141.6894550290276;2143.8499305048654;2122.1935664447487;2154.2675490333286;2161.135545781684;2165.231342873281;2168.3494951514563;1848.2606957217085;1917.7986311437237;2060.8763474191524;2079.2826474866147;2131.509752756859;2092.550101728128;2115.122224276923;2122.225387733466;2130.52063285761;2131.1688441507827;2062.32974283357;2077.8441616878795;2079.5944790765807;2082.6355387807075;2089.352300786761;7070;7100.502964792467;7146.035285236479;7146.749863754796;7164.749233776845;4323.524412314643;4436.622887054604;4446.199684056868;4450.087732014929;4450.643948484114;2973.814246528821;3039.149038269288;3053.206166396753;3106;3117.6570577236484;1117.8826223246524;1160.216853623809;1194.677437091989;1196.78457877582;1197.9880201197964;9612.51937403127;9643.324967252294;9654.27592930533;9665.90334096657;9668.854967175497;3924.3917192834583;4181.853635122817;4230;4319.913601728011;4331.133773245364;8263.297619702273;8269;8271.503709777433;8272.214139656722;8278.793030174294;4333.891652708708;4357.0196705740955;4411.647068234533;4412.514623391425;4415.867523974286;3441.5525981622227;3693.3905905525826;3998.7200895937003;4004.4994375703036;4043.0700938783657;3491.546099007113;3514.297140571896;3623.293457775761;3635.309291234657;3641.7814931104217;1204.211745557846;1215.9635210943686;1218.214251807579;1218.5186851193728;1244.6826059354808;794.2653045932605;817.4155478832663;818.9754307370788;827.1067247372823;828.9679349210272;4532.501424843264;4562.840300589463;4612.5156858530045;4737.076270127292;4856.271559266138;510.8007876928001;513.2762804445714;515.3868391933225;515.4355980201673;522.6602708239675;2967.4806908791306;2980.0761763853275;3023.7429818465675;3045.573619693245;3082.5222090576;7975.8404831903035;8288.129746376664;8529;8876.822463550692;8883.156100170474;8343.374246931504;8711.34664900135;8714.302855771522;8744.650213991496;8756;6632.502562307846;6788.287229840796;6791.320867913268;6793.286704896013;6799.11611490503;4598.26427771555;4709.623230141649;4756.644356358422;4817;4895.388076490439;8029.357651387992;8075.757727268191;8121.878171827546;8152.796180095545;8172.305354044973;7644;7906.011748531434;7923.603819809102;8179.100298967228;8193.426460147732;6147.415995480422;6329;6750.696218670269;6908.965455172692;6912.377885990084;6630.635315057718;6720.596764194164;6819.011243369783;6970.616616086164;7002.334778196063;6138.570300078952;6242.906356404748;6289.779857704958;6367.458766004756;6370.713317900798;5921.733521991435;5952.791652292322;5953.9404605955115;5957.642541447353;6007.699615019319;5617.578681598979;5716.199732037435;5731.5128214100605;5732.082866741302;5732.369439361751;4200.9159816802885;4217.599328063829;4234.682398820039;4244.511881133586;4364.345348197796;4122.257993561067;4174.728642638219;4176.60322269384;4259.488861336536;4320.848770293024 22:06:39 INFO - PROCESS | 3422 | |4;prototype.html;357.13395017083565;364.36054723959455;367.8841165033014;371.61723424872355;375.50058422298343;4767.404074490689;4783.593394561462;4793.424789025322;4812.614990800736;4820.421549414071;4501.572350626685;4561.415777143974;4647.512011238813;4719.740414277213;4735.337052812609;3829.157585331223;3845.9230815383676;3854.1906199698096;3856.86500972466;3862.2275544890995;3856.9614303856883;3864.9227015459674;3865.5747867734526;3876.6704830089475;3877.9806100969595;3540.415831387818;3645.1798345372345;3852.4028775539837;3863.555691095518;3869.535655721317;3821.3503704370323;3831.2720583089263;3835.4822099016556;3841.442990766351;3853.903652408684;3470.9479357809655;3473.930521389558;3474.722022238215;3495.370833250014;3500.824958752052;4685.344051832746;4690.530946905316;4691.554302341272;4697.4832768395445;4713.787879545394;4017.6384125428854;4165.625093741578;4202.85290014848;4207.810648520794;4209.684273679486;4952.182889823175;4954.455009948902;4960.479149689303;5011.749412529395;5045.64680472367;3591.8024508652015;3607.0801945503727;3612.584552776412;3619.7828130312;3622.1850083731215;5096.566791822699;5156.747495654868;5192.71440070796;5227.895442091137;5253.001929633358;3671.2106897016733;3678.6873115784924;3684.871029513954;3686.170611612366;3688.3729765939856;3709.8516059357326;3716.2381711749026;3716.9814150929615;3731.440283957428;3734.3568829714836;4214.346776249687;4227.6406505447385;4232.238197124486;4245.150969806051;4246.40550322955;4724.291356296583;4986.678530189502;5010.3737032870895;5014.623903207274;5021.899562008813;1221.9083568732203;1222.7737868494357;1226.7485165541043;1228.1341654133814;1232.3345393487596;816.7508909782455;835.7826964989147;844.7634662294568;869.2177040663454;877.7279043496536;4200.579942005775;4227.87316380509;4233.70364074518;4241.724287921275;4263.957360426418;4922.384701912261;4943.5797957174045;4963.5780958618925;4978.303037574742;5020.497950205024;4172.165566886635;4230.471191101113;4235.470566179228;4252.829886804555;4277.593628605277;2655.3494393873625;2660.467906418724;2666.186813022047;2668.8790707902726;2669.7864170866283;4671.205894997858;4686.851721328228;4691.437027556715;4705.717656940595;4718.787654555554;4935.284383764406;5085.593152547861;5105.732331307966;5130.127878260705;5133.768980395891;4648.465426475931;4648.535146485392;4679.953200467951;4686.64850136248;4696.718196908197;4962.727050012284;4970.204767237225;5010.173321401928;5011.949880501148;5030.949690503048;4701.002074118418;4922.212446008622;4937.382827146606;4950.579200767975;4959.851204463872;4670.416197975253;4676.134915040592;4676.205045142266;4679.953200467951;4690.599315496238;3568.3576956148145;3577.5706915170344;3592.6227746086283;3604.7837129772297;3617.764845285048;4866.690650357791;5039.168537191323;5048.495150484922;5060.418051924076;5068.543831055223;4836.839158601981;4848.739327774874;4863.586595139453;4873.390826146731;4887.804487820589;4847;4858.659893807399;4878.243872199815;4890.793917231249;4893.975530122327;4686.109639168547;4770.570648641639;4794;4794.808207671794;4813.350197723263;4908.680935739166;4941.431735350337;4944.629152813482;4954.355933728592;4958.479359667327;4792.7363994980615;4795.976020119877;4804.8798780030775;4826.32431459603;4838 22:06:39 INFO - PROCESS | 3422 | |5;yui.html;664.4717449627545;678.9883074219412;720.8882623193407;733.3583114774573;746.08231874794;13074.803877941826;13702.931485342559;13736.381862816184;13762.31188440579;13847.238401887906;12381.938090309559;13211.537596184135;13323.666908327297;13537.12008719435;13552.661183470418;778.7663700889725;791.3827214772487;799.664141060753;800.2837460472873;800.7998000499875;799.1768478467188;801.907780605232;802.7110240313483;802.7591722483261;802.8394321135767;844.8986121665408;845.7251393297172;846.9110743371949;847.4237518487427;847.8389106069858;832.4422636833276;841.1294310388779;841.3941961787534;841.6338892581716;842.2756429470651;619.3342157181004;619.8791235709039;619.8977168767148;620.4106099205727;620.7175735040546;10398.896011039944;10416.79166416675;10535.78928421435;13221.73556528864;13676.452941882313;11469.942650286695;13037.217766934014;13052.869471305356;13250.204987700768;13391.263480508567;10470.842937355947;10536.683899483029;10552.736181595445;10658.946705266502;11000.944995275053;3160.8893688721014;3169.413658473167;3275.606927168755;3314.138324035744;3317.2204531934976;53422.19866702003;57001.42998570003;57047.429525704625;63399.36600634027;65391;10406.5837366506;10448.32085914413;10605;10621.097206737359;10658.360498370123;17268;18101.818981810015;18741.625167496575;18744.718829217574;18928.905355473136;5916.822495325147;5996.730147143389;6222.564420490522;6223.315435302113;6501.869962600722;3312.966870331314;3333.2000319923527;3379.121428428626;3393.270446853938;3429.5713035870517;3019.8037127587077;3100.488419410817;3108.782385233012;3110.2690867646083;3116.423461659555;504.6417043898873;506.6326912988128;513.3659929986463;523.4163907243473;527.952484276417;554.694917795211;580.2138102870549;588.141313682029;588.9381614930371;591.2815928646714;7453;7778.727744529027;7921.168277330912;8110.63502142405;8153.8776918346275;468.80778880658767;470.36265859760215;472.24676640757934;472.90541891621814;480.4882799818252;3552.2717842842158;3779.4519794629623;3879.6702280305926;3931.4692516510477;3988.4216788565495;61186.69406652938;70267.29732702607;70314.29685703077;70827.6458617714;71174.28825711677;47359;54490.72754636202;57221;57287.427125729046;59492.40507594955;53046.89052255086;53248;53396.73301633545;55013;55078.44921550733;7198.136223653195;7257.310555497219;7429.442791790745;7513.62431878393;7693.269139431745;37411;37563.24873502569;37985;39002.41496377614;39188.6081139185;35961;36090;40512.79743601263;40566.59433405628;42023;11042.668719938414;11089.667309980714;11090.611828585961;11201.60794372193;11441.713957151138;11749.88250117488;12079.51681932748;12287.631371058882;12499.56251531192;13075.934620326838;10885.782284354427;10974.506147223234;11135.443227838738;11203.271787333797;11595.710107247387;10888.51001704941;10964.890351096386;11188.608398706006;11748.530058797553;11755.941220294186;11484.310941343547;11746.29522228669;11997.64007079789;12020.879791201976;12290.385480726107;9642.566084526074;10054.849177262493;10088.899111008795;10151.441670708182;10304.587816487257;8644.481331120152;9793.804123917624;9903.900960990586;9968.900310996798;9997.800043999225 22:06:39 INFO - PROCESS | 3422 | __end_tp_report 22:06:39 INFO - PROCESS | 3422 | __start_cc_report 22:06:39 INFO - PROCESS | 3422 | _x_x_mozilla_cycle_collect,1265 22:06:39 INFO - PROCESS | 3422 | __end_cc_report 22:06:39 INFO - PROCESS | 3422 | __startTimestamp1482818799155__endTimestamp 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | ------- Summary: start ------- 22:06:39 INFO - PROCESS | 3422 | Number of tests: 6 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | [#0] dojo.html Cycles:165 Average:4694.33 Median:4824.93 stddev:501.67 (10.4%) stddev-sans-first:474.58 22:06:39 INFO - PROCESS | 3422 | Values: 2564.7 2583.7 2593.3 2609.2 2618.8 4636.1 4746.0 4746.3 4762.7 4766.5 4733.5 4738.3 4746.5 4765.0 4794.0 4281.2 4366.4 4381.2 4427.9 4477.9 4074.8 4394.7 4460.4 4491.6 4539.4 4312.7 4347.4 4372.2 4419.7 4430.9 4377.8 4395.5 4396.6 4397.0 4399.8 4348.4 4371.3 4372.7 4390.7 4390.7 4715.6 4799.8 4812.0 4815.0 4824.9 5089.9 5097.5 5099.1 5141.7 5151.7 5100.7 5119.6 5121.6 5164.5 5190.7 4653.1 4684.6 4710.2 4753.2 4832.8 5216.7 5219.6 5227.9 5229.1 5262.4 4973.8 5000.4 5025.9 5029.4 5071.7 4926.1 4942.5 4947.4 5090.3 5100.5 5019.4 5047.4 5050.5 5103.4 5113.9 5060.1 5060.4 5072.4 5072.5 5124.1 4371.1 4376.1 4393.2 4393.4 4442.0 3689.6 3714.4 3727.7 3745.3 3765.5 4320.1 4323.0 4337.5 4353.9 4366.5 4993.7 5016.2 5022.7 5034.0 5067.9 4280.7 4295.6 4296.5 4311.9 4351.1 4565.7 4584.2 4591.0 4641.7 4680.2 4783.7 4788.6 4788.8 4796.4 4845.4 4825.4 5080.7 5127.9 5133.2 5184.3 4533.8 4710.4 4728.4 4730.0 4807.0 4924.1 5017.6 5026.5 5033.4 5033.7 5029.9 5042.6 5050.8 5060.1 5105.9 4833.3 4893.6 4927.9 4946.4 4946.8 4885.2 4911.8 4922.7 4941.6 4944.4 4857.3 4857.5 4867.3 4897.2 4943.5 5032.4 5037.3 5039.0 5040.5 5077.7 4923.3 4923.7 4924.3 4929.3 4930.0 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | [#1] ext.html Cycles:180 Average:45821.69 Median:34984.97 stddev:87307.64 (249.6%) stddev-sans-first:87486.31 22:06:39 INFO - PROCESS | 3422 | Values: 523.0 529.3 532.3 540.5 560.8 28532.9 36792.0 38340.4 38446.8 42559.6 39988.0 40435.0 40436.6 40447.2 40513.4 7504.7 7527.7 7609.0 7671.8 7673.5 7529.4 9777.4 10021.7 10035.7 10345.6 9925.3 10011.9 10022.3 10022.7 10027.9 9901.0 9979.7 9989.8 9999.8 10001.4 9766.2 9838.7 9861.7 9861.8 9867.8 37503.0 37648.8 37684.2 37806.6 37838.8 3523.0 3596.2 3596.7 3617.7 3625.6 28434.3 28833.4 29260.4 29276.6 29456.1 3424.8 3469.4 3590.0 3630.2 3671.9 246380.0 247250.0 248401.0 248447.0 248585.0 51335.7 51786.7 51832.2 52092.5 52310.7 55073.7 55283.2 55288.0 55325.0 55328.0 11859.7 12163.1 12166.6 12169.3 12171.1 8433.5 8687.2 8687.7 8695.1 9031.4 15165.4 15622.0 15646.1 15706.7 15844.5 5702.9 5926.4 5946.9 5979.6 6007.2 700.0 703.3 708.5 709.8 713.8 24865.8 26171.7 26318.2 26376.6 26456.0 898.5 930.4 942.5 952.8 956.5 11814.8 12805.6 12892.7 12941.8 12964.0 337240.0 386372.0 392819.0 395200.0 555980.0 41772.4 42227.4 42311.0 42385.4 42431.4 1193.7 1209.4 1211.2 1213.7 1217.8 24601.0 25029.6 25490.6 25811.5 25847.7 37301.3 37496.8 37662.8 37669.6 37740.2 295462.0 297077.0 297242.0 297578.0 297599.0 20706.6 21273.8 21945.9 22571.3 22585.8 34569.3 34798.5 34880.0 34891.8 35078.1 29876.0 30183.8 30411.5 30983.8 31383.4 30043.8 30087.2 30475.5 31078.2 31174.7 29470.6 30200.2 30221.5 30408.0 30435.0 26214.6 26926.0 27142.9 27446.0 27810.6 21136.4 21213.7 21236.3 21520.6 21534.7 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | [#2] jquery.html Cycles:180 Average:21969.83 Median:45400.04 stddev:58088.02 (127.9%) stddev-sans-first:58228.29 22:06:39 INFO - PROCESS | 3422 | Values: 353.7 361.9 377.4 380.5 388.2 12700.0 15467.9 15768.8 15813.8 16705.2 15387.5 16445.7 16665.4 16791.4 16809.8 4964.0 5076.8 5183.7 5318.6 5349.6 3619.6 3793.1 5335.5 5367.2 5373.1 5119.2 5326.8 5373.2 5507.6 5509.2 5088.6 5341.1 5497.0 5507.5 5509.4 5264.1 5282.1 5285.4 5286.7 5288.5 11947.1 15266.0 18996.4 19531.5 19675.2 1953.1 1962.6 1977.3 1998.5 2161.3 8851.9 9058.9 9120.9 9477.7 9657.8 1662.4 1716.5 1733.7 1733.9 1769.9 45863.8 57649.1 62486.0 63126.0 65703.3 9294.4 9799.2 9820.0 9824.2 10058.4 13594.6 13616.4 13620.0 13624.5 13627.1 6321.5 6379.9 6384.7 6577.8 6582.3 5737.4 5794.9 5916.3 5920.7 5929.1 4056.3 4089.0 4181.9 4381.5 4493.8 1821.2 1878.7 1883.2 1886.4 1903.4 728.3 757.8 759.1 780.9 783.7 14249.9 14929.0 15217.7 15708.0 15713.1 746.3 748.7 753.8 762.8 767.1 5736.9 5787.3 5823.7 5856.9 5905.4 348734.0 349344.0 354235.0 354483.0 354492.0 52250.5 72708.9 72806.0 72825.6 73366.6 419.5 427.3 428.1 428.7 428.8 9137.6 9154.8 9248.5 9623.4 9857.4 33476.0 33650.5 34695.1 34982.1 35733.6 44946.6 45161.8 45638.3 45711.1 48502.0 7516.4 7607.8 7656.6 7718.3 7905.9 8915.0 11831.3 12066.6 12082.2 12233.8 10146.5 10214.5 10224.8 10721.9 10818.8 9852.2 10185.0 10371.8 10464.5 10745.4 10211.9 10309.4 10333.2 10431.0 10683.5 7708.2 7925.2 7965.0 7966.2 7971.9 9783.6 9853.6 10135.6 10215.1 10319.9 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | [#3] mootools.html Cycles:180 Average:4717.46 Median:4746.86 stddev:2550.46 (53.7%) stddev-sans-first:2544.88 22:06:39 INFO - PROCESS | 3422 | Values: 1325.9 1326.8 1339.6 1340.0 1340.8 6238.6 6849.8 7249.1 7252.2 7259.9 6259.5 6455.9 6465.6 6468.0 6831.1 2077.8 2136.1 2140.3 2141.7 2143.8 2122.2 2154.3 2161.1 2165.2 2168.3 1848.3 1917.8 2060.9 2079.3 2131.5 2092.6 2115.1 2122.2 2130.5 2131.2 2062.3 2077.8 2079.6 2082.6 2089.4 7070.0 7100.5 7146.0 7146.7 7164.7 4323.5 4436.6 4446.2 4450.1 4450.6 2973.8 3039.1 3053.2 3106.0 3117.7 1117.9 1160.2 1194.7 1196.8 1198.0 9612.5 9643.3 9654.3 9665.9 9668.9 3924.4 4181.9 4230.0 4319.9 4331.1 8263.3 8269.0 8271.5 8272.2 8278.8 4333.9 4357.0 4411.6 4412.5 4415.9 3441.6 3693.4 3998.7 4004.5 4043.1 3491.5 3514.3 3623.3 3635.3 3641.8 1204.2 1216.0 1218.2 1218.5 1244.7 794.3 817.4 819.0 827.1 829.0 4532.5 4562.8 4612.5 4737.1 4856.3 510.8 513.3 515.4 515.4 522.7 2967.5 2980.1 3023.7 3045.6 3082.5 7975.8 8288.1 8529.0 8876.8 8883.2 8343.4 8711.3 8714.3 8744.7 8756.0 6632.5 6788.3 6791.3 6793.3 6799.1 4598.3 4709.6 4756.6 4817.0 4895.4 8029.4 8075.8 8121.9 8152.8 8172.3 7644.0 7906.0 7923.6 8179.1 8193.4 6147.4 6329.0 6750.7 6909.0 6912.4 6630.6 6720.6 6819.0 6970.6 7002.3 6138.6 6242.9 6289.8 6367.5 6370.7 5921.7 5952.8 5953.9 5957.6 6007.7 5617.6 5716.2 5731.5 5732.1 5732.4 4200.9 4217.6 4234.7 4244.5 4364.3 4122.3 4174.7 4176.6 4259.5 4320.8 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | [#4] prototype.html Cycles:180 Average:4107.52 Median:4682.65 stddev:1159.25 (24.8%) stddev-sans-first:1127.81 22:06:39 INFO - PROCESS | 3422 | Values: 357.1 364.4 367.9 371.6 375.5 4767.4 4783.6 4793.4 4812.6 4820.4 4501.6 4561.4 4647.5 4719.7 4735.3 3829.2 3845.9 3854.2 3856.9 3862.2 3857.0 3864.9 3865.6 3876.7 3878.0 3540.4 3645.2 3852.4 3863.6 3869.5 3821.4 3831.3 3835.5 3841.4 3853.9 3470.9 3473.9 3474.7 3495.4 3500.8 4685.3 4690.5 4691.6 4697.5 4713.8 4017.6 4165.6 4202.9 4207.8 4209.7 4952.2 4954.5 4960.5 5011.7 5045.6 3591.8 3607.1 3612.6 3619.8 3622.2 5096.6 5156.7 5192.7 5227.9 5253.0 3671.2 3678.7 3684.9 3686.2 3688.4 3709.9 3716.2 3717.0 3731.4 3734.4 4214.3 4227.6 4232.2 4245.2 4246.4 4724.3 4986.7 5010.4 5014.6 5021.9 1221.9 1222.8 1226.7 1228.1 1232.3 816.8 835.8 844.8 869.2 877.7 4200.6 4227.9 4233.7 4241.7 4264.0 4922.4 4943.6 4963.6 4978.3 5020.5 4172.2 4230.5 4235.5 4252.8 4277.6 2655.3 2660.5 2666.2 2668.9 2669.8 4671.2 4686.9 4691.4 4705.7 4718.8 4935.3 5085.6 5105.7 5130.1 5133.8 4648.5 4648.5 4680.0 4686.6 4696.7 4962.7 4970.2 5010.2 5011.9 5030.9 4701.0 4922.2 4937.4 4950.6 4959.9 4670.4 4676.1 4676.2 4680.0 4690.6 3568.4 3577.6 3592.6 3604.8 3617.8 4866.7 5039.2 5048.5 5060.4 5068.5 4836.8 4848.7 4863.6 4873.4 4887.8 4847.0 4858.7 4878.2 4890.8 4894.0 4686.1 4770.6 4794.0 4794.8 4813.4 4908.7 4941.4 4944.6 4954.4 4958.5 4792.7 4796.0 4804.9 4826.3 4838.0 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - PROCESS | 3422 | [#5] yui.html Cycles:180 Average:14556.83 Median:22250.61 stddev:18237.17 (82.0%) stddev-sans-first:18258.50 22:06:39 INFO - PROCESS | 3422 | Values: 664.5 679.0 720.9 733.4 746.1 13074.8 13702.9 13736.4 13762.3 13847.2 12381.9 13211.5 13323.7 13537.1 13552.7 778.8 791.4 799.7 800.3 800.8 799.2 801.9 802.7 802.8 802.8 844.9 845.7 846.9 847.4 847.8 832.4 841.1 841.4 841.6 842.3 619.3 619.9 619.9 620.4 620.7 10398.9 10416.8 10535.8 13221.7 13676.5 11469.9 13037.2 13052.9 13250.2 13391.3 10470.8 10536.7 10552.7 10658.9 11000.9 3160.9 3169.4 3275.6 3314.1 3317.2 53422.2 57001.4 57047.4 63399.4 65391.0 10406.6 10448.3 10605.0 10621.1 10658.4 17268.0 18101.8 18741.6 18744.7 18928.9 5916.8 5996.7 6222.6 6223.3 6501.9 3313.0 3333.2 3379.1 3393.3 3429.6 3019.8 3100.5 3108.8 3110.3 3116.4 504.6 506.6 513.4 523.4 528.0 554.7 580.2 588.1 588.9 591.3 7453.0 7778.7 7921.2 8110.6 8153.9 468.8 470.4 472.2 472.9 480.5 3552.3 3779.5 3879.7 3931.5 3988.4 61186.7 70267.3 70314.3 70827.6 71174.3 47359.0 54490.7 57221.0 57287.4 59492.4 53046.9 53248.0 53396.7 55013.0 55078.4 7198.1 7257.3 7429.4 7513.6 7693.3 37411.0 37563.2 37985.0 39002.4 39188.6 35961.0 36090.0 40512.8 40566.6 42023.0 11042.7 11089.7 11090.6 11201.6 11441.7 11749.9 12079.5 12287.6 12499.6 13075.9 10885.8 10974.5 11135.4 11203.3 11595.7 10888.5 10964.9 11188.6 11748.5 11755.9 11484.3 11746.3 11997.6 12020.9 12290.4 9642.6 10054.8 10088.9 10151.4 10304.6 8644.5 9793.8 9903.9 9968.9 9997.8 22:06:39 INFO - PROCESS | 3422 | -------- Summary: end -------- 22:06:39 INFO - PROCESS | 3422 | 22:06:39 INFO - TEST-INFO | 3422: exit 0 22:06:39 INFO - TEST-OK | dromaeo_css | took 1124084ms 22:06:39 INFO - TEST-START | kraken 22:06:39 INFO - operating with platform_type : linux_ 22:06:39 INFO - Initialising browser for kraken test... 22:06:39 INFO - TEST-INFO | started process 3720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAFjMTS/profile http://localhost:38416/getInfo.html) 22:06:40 INFO - PROCESS | 3720 | 1482818800452 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 22:06:44 INFO - PROCESS | 3720 | __metrics Screen width/height:1600/1200 22:06:44 INFO - PROCESS | 3720 | colorDepth:24 22:06:44 INFO - PROCESS | 3720 | Browser inner width/height: 1024/697 22:06:44 INFO - PROCESS | 3720 | __metrics 22:06:44 INFO - PROCESS | 3720 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:06:44 INFO - PROCESS | 3720 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:06:45 INFO - PROCESS | 3720 | console.error: PushService: 22:06:45 INFO - PROCESS | 3720 | stateChangeProcessEnqueue: Error transitioning state 22:06:45 INFO - PROCESS | 3720 | UnknownError 22:06:46 INFO - TEST-INFO | 3720: exit 0 22:06:46 INFO - Browser initialized. 22:06:46 INFO - Running cycle 1/1 for kraken test... 22:06:46 INFO - TEST-INFO | started process 3801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAFjMTS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:58 INFO - PROCESS | 3801 | RSS: Main: 151109632 22:06:58 INFO - PROCESS | 3801 | 22:07:00 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38416/tests/kraken/driver.html?testName=audio-beat-detection) 22:07:01 INFO - PROCESS | 3801 | RSS: Main: 205037568 22:07:01 INFO - PROCESS | 3801 | 22:07:04 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38416/tests/kraken/driver.html?testName=audio-dft) 22:07:05 INFO - PROCESS | 3801 | RSS: Main: 184963072 22:07:05 INFO - PROCESS | 3801 | 22:07:08 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38416/tests/kraken/driver.html?testName=audio-fft) 22:07:08 INFO - PROCESS | 3801 | RSS: Main: 267726848 22:07:08 INFO - PROCESS | 3801 | 22:07:11 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38416/tests/kraken/driver.html?testName=audio-oscillator) 22:07:11 INFO - PROCESS | 3801 | RSS: Main: 179187712 22:07:11 INFO - PROCESS | 3801 | 22:07:13 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38416/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:14 INFO - PROCESS | 3801 | RSS: Main: 182263808 22:07:14 INFO - PROCESS | 3801 | 22:07:17 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38416/tests/kraken/driver.html?testName=imaging-darkroom) 22:07:18 INFO - PROCESS | 3801 | RSS: Main: 221650944 22:07:18 INFO - PROCESS | 3801 | 22:07:21 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38416/tests/kraken/driver.html?testName=imaging-desaturate) 22:07:22 INFO - PROCESS | 3801 | RSS: Main: 221192192 22:07:22 INFO - PROCESS | 3801 | 22:07:25 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38416/tests/kraken/driver.html?testName=json-parse-financial) 22:07:25 INFO - PROCESS | 3801 | RSS: Main: 219402240 22:07:25 INFO - PROCESS | 3801 | 22:07:27 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38416/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:07:27 INFO - PROCESS | 3801 | RSS: Main: 182386688 22:07:27 INFO - PROCESS | 3801 | 22:07:29 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:07:30 INFO - PROCESS | 3801 | RSS: Main: 190472192 22:07:30 INFO - PROCESS | 3801 | 22:07:32 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:07:33 INFO - PROCESS | 3801 | RSS: Main: 197799936 22:07:33 INFO - PROCESS | 3801 | 22:07:36 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:07:36 INFO - PROCESS | 3801 | RSS: Main: 207888384 22:07:36 INFO - PROCESS | 3801 | 22:07:40 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:07:40 INFO - PROCESS | 3801 | RSS: Main: 203677696 22:07:40 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | Cycle 1(1): loaded http://localhost:38416/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38416/tests/kraken/driver.html?testName=ai-astar) 22:07:42 INFO - PROCESS | 3801 | RSS: Main: 254431232 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | __start_tp_report 22:07:42 INFO - PROCESS | 3801 | _x_x_mozilla_page_load 22:07:42 INFO - PROCESS | 3801 | _x_x_mozilla_page_load_details 22:07:42 INFO - PROCESS | 3801 | |i|pagename|runs| 22:07:42 INFO - PROCESS | 3801 | |0;ai-astar;106;116;115;115;116;116;115;116;114;115 22:07:42 INFO - PROCESS | 3801 | |1;audio-beat-detection;150;125;125;165;133;125;126;127;157;134 22:07:42 INFO - PROCESS | 3801 | |2;audio-dft;161;161;160;163;165;161;162;163;161;162 22:07:42 INFO - PROCESS | 3801 | |3;audio-fft;75;76;77;113;76;76;77;76;91;76 22:07:42 INFO - PROCESS | 3801 | |4;audio-oscillator;84;84;105;86;85;86;98;84;84;108 22:07:42 INFO - PROCESS | 3801 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;118;117 22:07:42 INFO - PROCESS | 3801 | |6;imaging-darkroom;150;148;148;149;149;148;148;148;148;148 22:07:42 INFO - PROCESS | 3801 | |7;imaging-desaturate;95;96;95;95;96;96;95;95;95;94 22:07:42 INFO - PROCESS | 3801 | |8;json-parse-financial;61;62;61;61;61;63;62;63;62;63 22:07:42 INFO - PROCESS | 3801 | |9;json-stringify-tinderbox;67;67;67;68;68;67;67;69;68;67 22:07:42 INFO - PROCESS | 3801 | |10;stanford-crypto-aes;84;83;90;85;84;84;85;84;85;86 22:07:42 INFO - PROCESS | 3801 | |11;stanford-crypto-ccm;137;134;133;135;145;141;138;132;172;155 22:07:42 INFO - PROCESS | 3801 | |12;stanford-crypto-pbkdf2;180;183;185;179;194;182;184;179;183;180 22:07:42 INFO - PROCESS | 3801 | |13;stanford-crypto-sha256-iterative;70;68;68;69;69;69;71;70;69;69 22:07:42 INFO - PROCESS | 3801 | __end_tp_report 22:07:42 INFO - PROCESS | 3801 | __start_cc_report 22:07:42 INFO - PROCESS | 3801 | _x_x_mozilla_cycle_collect,1864 22:07:42 INFO - PROCESS | 3801 | __end_cc_report 22:07:42 INFO - PROCESS | 3801 | __startTimestamp1482818862642__endTimestamp 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | ------- Summary: start ------- 22:07:42 INFO - PROCESS | 3801 | Number of tests: 14 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.50 stddev:3.03 (2.6%) stddev-sans-first:0.71 22:07:42 INFO - PROCESS | 3801 | Values: 106.0 116.0 115.0 115.0 116.0 116.0 115.0 116.0 114.0 115.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:133.50 stddev:15.02 (11.3%) stddev-sans-first:15.14 22:07:42 INFO - PROCESS | 3801 | Values: 150.0 125.0 125.0 165.0 133.0 125.0 126.0 127.0 157.0 134.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.45 (0.9%) stddev-sans-first:1.50 22:07:42 INFO - PROCESS | 3801 | Values: 161.0 161.0 160.0 163.0 165.0 161.0 162.0 163.0 161.0 162.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:12.09 (15.9%) stddev-sans-first:12.61 22:07:42 INFO - PROCESS | 3801 | Values: 75.0 76.0 77.0 113.0 76.0 76.0 77.0 76.0 91.0 76.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:84.50 stddev:9.50 (11.2%) stddev-sans-first:9.79 22:07:42 INFO - PROCESS | 3801 | Values: 84.0 84.0 105.0 86.0 85.0 86.0 98.0 84.0 84.0 108.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:07:42 INFO - PROCESS | 3801 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.44 22:07:42 INFO - PROCESS | 3801 | Values: 150.0 148.0 148.0 149.0 149.0 148.0 148.0 148.0 148.0 148.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 22:07:42 INFO - PROCESS | 3801 | Values: 95.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 94.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:0.88 (1.4%) stddev-sans-first:0.87 22:07:42 INFO - PROCESS | 3801 | Values: 61.0 62.0 61.0 61.0 61.0 63.0 62.0 63.0 62.0 63.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:67.50 stddev:0.71 (1.0%) stddev-sans-first:0.73 22:07:42 INFO - PROCESS | 3801 | Values: 67.0 67.0 67.0 68.0 68.0 67.0 67.0 69.0 68.0 67.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:1.94 (2.3%) stddev-sans-first:2.03 22:07:42 INFO - PROCESS | 3801 | Values: 84.0 83.0 90.0 85.0 84.0 84.0 85.0 84.0 85.0 86.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:139.50 stddev:12.53 (9.0%) stddev-sans-first:13.15 22:07:42 INFO - PROCESS | 3801 | Values: 137.0 134.0 133.0 135.0 145.0 141.0 138.0 132.0 172.0 155.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:183.00 stddev:4.43 (2.4%) stddev-sans-first:4.58 22:07:42 INFO - PROCESS | 3801 | Values: 180.0 183.0 185.0 179.0 194.0 182.0 184.0 179.0 183.0 180.0 22:07:42 INFO - PROCESS | 3801 | 22:07:42 INFO - PROCESS | 3801 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:0.92 (1.3%) stddev-sans-first:0.93 22:07:42 INFO - PROCESS | 3801 | Values: 70.0 68.0 68.0 69.0 69.0 69.0 71.0 70.0 69.0 69.0 22:07:42 INFO - PROCESS | 3801 | -------- Summary: end -------- 22:07:42 INFO - PROCESS | 3801 | 22:07:43 INFO - TEST-INFO | 3801: exit 0 22:07:43 INFO - TEST-OK | kraken | took 63882ms 22:07:43 INFO - SUITE-END | took 1187s 22:07:44 INFO - Completed test suite (00:19:48) 22:07:44 INFO - javascript benchmark 22:07:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2564.7178810330865, 2583.728708485609, 2593.286846117316, 2609.152025591683, 2618.8297760645555, 4636.1191373638985, 4746.027064451788, 4746.3117847912035, 4762.690425122369, 4766.523347665236, 4733.455652599952, 4738.336632871401, 4746.501617330183, 4765.0, 4793.952060479394, 4281.165172791307, 4366.4105345778235, 4381.211381951248, 4427.88930276744, 4477.92529792849, 4074.8166332515107, 4394.736315821045, 4460.353248778925, 4491.640668746492, 4539.364488971547, 4312.676549258818, 4347.391365208873, 4372.234858899712, 4419.734815911055, 4430.867073987785, 4377.7592232427205, 4395.4725432947935, 4396.648268138541, 4397.032652816407, 4399.780010999437, 4348.412964249817, 4371.322445021026, 4372.715773474714, 4390.692651514395, 4390.736555806629, 4715.575598196178, 4799.760011999386, 4812.037592481518, 4814.951850481485, 4824.927626085611, 5089.898202035939, 5097.541221290102, 5099.133147364957, 5141.691498510101, 5151.66514176581, 5100.668456550312, 5119.59043276537, 5121.617247989629, 5164.535191832753, 5190.71451070191, 4653.092646933849, 4684.5549672781035, 4710.175719249118, 4753.191957367256, 4832.782524786393, 5216.713080780593, 5219.566177528731, 5227.895442091137, 5229.058769421465, 5262.394824595144, 4973.751312434364, 5000.374953130859, 5025.874353141201, 5029.371328583927, 5071.746412679351, 4926.088673595396, 4942.456329803718, 4947.431045429749, 5090.312807770978, 5100.48995100487, 5019.42276638184, 5047.4447810740785, 5050.545450909436, 5103.413107492612, 5113.897722045538, 5060.089183946851, 5060.443351231362, 5072.416672082684, 5072.492750724898, 5124.077666020152, 4371.125774845043, 4376.059147283349, 4393.20922234001, 4393.406890069864, 4442.0, 3689.612590677994, 3714.3685573452576, 3727.7390582658954, 3745.3445647011663, 3765.548134223911, 4320.092780516064, 4323.048929235563, 4337.479502459725, 4353.9129217415475, 4366.519682834885, 4993.675411098267, 5016.197408414636, 5022.723750193702, 5033.974830125899, 5067.8986420271385, 4280.6789490788315, 4295.570442955679, 4296.5273819879785, 4311.913761724748, 4351.108022855307, 4565.726056436558, 4584.1977653910435, 4590.977045114753, 4641.698289611165, 4680.157571637138, 4783.7129772213775, 4788.640851936049, 4788.760561971957, 4796.352492413549, 4845.394325709286, 4825.420949486084, 5080.745962701924, 5127.923081153711, 5133.153029750049, 5184.326037615086, 4533.750643714496, 4710.4347478302825, 4728.38530990969, 4729.976350118227, 4806.951930480651, 4924.113659541317, 5017.648764586444, 5026.472220416804, 5033.446320904624, 5033.748312584283, 5029.924551131663, 5042.571381432619, 5050.77271522775, 5060.063888180698, 5105.897882042264, 4833.299171620166, 4893.608511319015, 4927.852164435073, 4946.431160416454, 4946.802127914843, 4885.218365061573, 4911.778969946433, 4922.72924989129, 4941.629377796751, 4944.431390390151, 4857.295692124693, 4857.489963553776, 4867.318575399518, 4897.191963326011, 4943.505649435027, 5032.370953630796, 5037.319961805109, 5039.0, 5040.521150490697, 5077.746112694276, 4923.335349727741, 4923.655344125877, 4924.335214745964, 4929.334539837219, 4929.975350123226], "value": 4661.304104771609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.997385013075, 529.2696079410414, 532.2734467451929, 540.4649397096871, 560.8401605542422, 28532.857335713317, 36792.0, 38340.42489362658, 38446.80776596113, 42559.574404255945, 39988.0, 40435.0, 40436.59563404365, 40447.19105617886, 40513.392299115534, 7504.737334193304, 7527.698892044312, 7609.048868891388, 7671.808204794869, 7673.501222420553, 7529.397648188159, 9777.413355198676, 10021.745107350824, 10035.69892903214, 10345.586176552966, 9925.255605829592, 10011.949940250288, 10022.348547344436, 10022.749431264241, 10027.949860250688, 9901.009899010078, 9979.700608981742, 9989.800203995952, 9999.800003999952, 10001.399916005026, 9766.169875560587, 9838.704838854846, 9861.704148875546, 9861.753456163582, 9867.753306167404, 37503.0, 37648.811755941046, 37684.24631507354, 37806.62193378059, 37838.810805945795, 3522.982385088084, 3596.2088340565033, 3596.730245231592, 3617.6744093031753, 3625.6193099724414, 28434.289142771388, 28833.42333153346, 29260.414791704046, 29276.560851587245, 29456.11631651054, 3424.7945123292684, 3469.444888817777, 3590.0, 3630.2195028068827, 3671.94492082619, 246380.0, 247250.0, 248401.0, 248447.0, 248585.0, 51335.74332128315, 51786.74106629518, 51832.22251666228, 52092.47907520952, 52310.73844630752, 55073.724631376586, 55283.170752438746, 55288.0, 55325.0, 55328.0, 11859.703507412383, 12163.087768417405, 12166.635000949986, 12169.2698438094, 12171.0871684624, 8433.451825631315, 8687.218150366498, 8687.739367818975, 8695.08701586337, 9031.412958157698, 15165.393384264506, 15622.0, 15646.139462329453, 15706.685866282838, 15844.52466426009, 5702.914456283159, 5926.436988486087, 5946.929552680473, 5979.5814292999075, 6007.219061521974, 699.9745373028537, 703.2756261051126, 708.4904582333513, 709.7941596936934, 713.7965679781238, 24865.751342486343, 26171.738282617313, 26318.21045368642, 26376.60435093475, 26456.0, 898.5148020069089, 930.4138392812484, 942.4627962061696, 952.842780941137, 956.5073986896754, 11814.822777658343, 12805.615831525069, 12892.677683057811, 12941.805872911913, 12964.0, 337240.0, 386372.0, 392819.0, 395200.0, 555980.0, 41772.37341439881, 42227.36658950118, 42311.0, 42385.36421953673, 42431.36352954708, 1193.7314104326458, 1209.4134344842744, 1211.2429731417865, 1213.690508920255, 1217.7503611759569, 24601.0, 25029.624555631315, 25490.617640735032, 25811.483770324114, 25847.741522585286, 37301.253974920895, 37496.81251593724, 37662.81168594139, 37669.62330376771, 37740.2451950965, 295462.0, 297077.0, 297242.0, 297578.0, 297599.0, 20706.58586828285, 21273.78726212718, 21945.890270548545, 22571.322860314216, 22585.774142259026, 34569.30861382708, 34798.47802283018, 34880.0, 34891.82554087213, 35078.12304692301, 29876.0, 30183.849080754455, 30411.54382684306, 30983.845080774452, 31383.372332552764, 30043.849780750956, 30087.247818804706, 30475.542866857457, 31078.22304442407, 31174.68825311718, 29470.557941631323, 30200.24499387533, 30221.546676799426, 30408.0, 30435.0, 26214.60678089792, 26926.0, 27142.864285678446, 27446.0, 27810.5828412578, 21136.365909022756, 21213.681794772783, 21236.25673101434, 21520.56958860783, 21534.676979845], "value": 16629.652980586678, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.7064236683553, 361.8805794087953, 377.4357335783004, 380.47114510829954, 388.1906225519791, 12700.0, 15467.922660386695, 15768.763468547966, 15813.84186158141, 16705.248263828125, 15387.461438849643, 16445.67108657829, 16665.41671041514, 16791.412300569453, 16809.83190168098, 4963.975180124095, 5076.796928122889, 5183.714895680736, 5318.574514038867, 5349.598780091489, 3619.6199399063116, 3793.108619474421, 5335.5198032177095, 5367.221752845835, 5373.059714549958, 5119.1809310510325, 5326.813561525348, 5373.167159090347, 5507.5593952483905, 5509.173623956399, 5088.618353623493, 5341.092014357569, 5497.038018346793, 5507.531859791922, 5509.393966663643, 5264.105102132608, 5282.102042652758, 5285.418603953562, 5286.709230992294, 5288.524032837025, 11947.103967202407, 15266.0, 18996.43010709681, 19531.511712207168, 19675.212991480323, 1953.1308567687386, 1962.617289628523, 1977.2782934229047, 1998.5403357227774, 2161.2651698422455, 8851.911480885174, 9058.864117038249, 9120.8631870522, 9477.715668529956, 9657.758556036086, 1662.3766087717106, 1716.519374575121, 1733.74860645207, 1733.913304334778, 1769.9203535840788, 45863.770681146714, 57649.13526297109, 62486.0, 63126.0, 65703.3429665702, 9294.395864268801, 9799.216062714966, 9819.950900245596, 9824.164945979528, 10058.446785426726, 13594.592162235149, 13616.38726257301, 13620.046596738133, 13624.455021799215, 13627.092207091015, 6321.52588555851, 6379.936200638027, 6384.7126879290545, 6577.80266592003, 6582.341765823379, 5737.3688894221605, 5794.913076303858, 5916.26046744157, 5920.73356698941, 5929.080992446092, 4056.290149223874, 4088.9795551022053, 4181.91636167281, 4381.452318460192, 4493.752843593568, 1821.2077746180453, 1878.7088001359812, 1883.1619929131405, 1886.3680666976716, 1903.4194570656082, 728.3372131360436, 757.7688804914554, 759.070139079623, 780.8711562592222, 783.7492002559127, 14249.931255155905, 14929.0, 15217.695646087017, 15708.0, 15713.057216567042, 746.2872956326803, 748.655618415524, 753.8454616803542, 762.8207371267748, 767.0986590755952, 5736.942630573724, 5787.276590426197, 5823.679697616672, 5856.882862342814, 5905.350411454737, 348734.0, 349344.0, 354235.0, 354483.0, 354492.0, 52250.47749522532, 72708.90936635954, 72806.0, 72825.6358718203, 73366.63316683382, 419.4630872483226, 427.2906974422444, 428.06467867709154, 428.66992415839627, 428.7727504422661, 9137.634494620406, 9154.816903662022, 9248.537573121452, 9623.374480658735, 9857.35927164732, 33476.0, 33650.49524257187, 34695.13262168466, 34982.12544686403, 35733.64266357303, 44946.550534494236, 45161.77419112883, 45638.31542526931, 45711.08577828491, 48502.0, 7516.3986881048295, 7607.809804754926, 7656.578888161204, 7718.266764657348, 7905.920940790518, 8915.0193478716, 11831.290122592673, 12066.577669781516, 12082.214656047328, 12233.816492752794, 10146.492675366055, 10214.489275536045, 10224.795504089729, 10721.892781072089, 10818.783624327627, 9852.162566181954, 10184.963007398697, 10371.807242167248, 10464.476776161313, 10745.355278683304, 10211.897881021094, 10309.432981186106, 10333.225008124271, 10431.0, 10683.465826708789, 7708.152103268524, 7925.167857375123, 7964.960175199087, 7966.203379661987, 7971.92028079735, 9783.559739811582, 9853.605855765689, 10135.594576216869, 10215.13171380441, 10319.94840025795], "value": 7470.4979962595735, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.933703314834, 1326.847412547557, 1339.604816579109, 1340.0150889096512, 1340.845802732686, 6238.594491358059, 6849.8287542811395, 7249.130104387467, 7252.20225775165, 7259.927400725991, 6259.467945224661, 6455.935440645592, 6465.579737317083, 6467.967660161693, 6831.1119554457855, 2077.7610574783903, 2136.1028368085363, 2140.2616097446435, 2141.6894550290276, 2143.8499305048654, 2122.1935664447487, 2154.2675490333286, 2161.135545781684, 2165.231342873281, 2168.3494951514563, 1848.2606957217085, 1917.7986311437237, 2060.8763474191524, 2079.2826474866147, 2131.509752756859, 2092.550101728128, 2115.122224276923, 2122.225387733466, 2130.52063285761, 2131.1688441507827, 2062.32974283357, 2077.8441616878795, 2079.5944790765807, 2082.6355387807075, 2089.352300786761, 7070.0, 7100.502964792467, 7146.035285236479, 7146.749863754796, 7164.749233776845, 4323.524412314643, 4436.622887054604, 4446.199684056868, 4450.087732014929, 4450.643948484114, 2973.814246528821, 3039.149038269288, 3053.206166396753, 3106.0, 3117.6570577236484, 1117.8826223246524, 1160.216853623809, 1194.677437091989, 1196.78457877582, 1197.9880201197964, 9612.51937403127, 9643.324967252294, 9654.27592930533, 9665.90334096657, 9668.854967175497, 3924.3917192834583, 4181.853635122817, 4230.0, 4319.913601728011, 4331.133773245364, 8263.297619702273, 8269.0, 8271.503709777433, 8272.214139656722, 8278.793030174294, 4333.891652708708, 4357.0196705740955, 4411.647068234533, 4412.514623391425, 4415.867523974286, 3441.5525981622227, 3693.3905905525826, 3998.7200895937003, 4004.4994375703036, 4043.0700938783657, 3491.546099007113, 3514.297140571896, 3623.293457775761, 3635.309291234657, 3641.7814931104217, 1204.211745557846, 1215.9635210943686, 1218.214251807579, 1218.5186851193728, 1244.6826059354808, 794.2653045932605, 817.4155478832663, 818.9754307370788, 827.1067247372823, 828.9679349210272, 4532.501424843264, 4562.840300589463, 4612.5156858530045, 4737.076270127292, 4856.271559266138, 510.8007876928001, 513.2762804445714, 515.3868391933225, 515.4355980201673, 522.6602708239675, 2967.4806908791306, 2980.0761763853275, 3023.7429818465675, 3045.573619693245, 3082.5222090576, 7975.8404831903035, 8288.129746376664, 8529.0, 8876.822463550692, 8883.156100170474, 8343.374246931504, 8711.34664900135, 8714.302855771522, 8744.650213991496, 8756.0, 6632.502562307846, 6788.287229840796, 6791.320867913268, 6793.286704896013, 6799.11611490503, 4598.26427771555, 4709.623230141649, 4756.644356358422, 4817.0, 4895.388076490439, 8029.357651387992, 8075.757727268191, 8121.878171827546, 8152.796180095545, 8172.305354044973, 7644.0, 7906.011748531434, 7923.603819809102, 8179.100298967228, 8193.426460147732, 6147.415995480422, 6329.0, 6750.696218670269, 6908.965455172692, 6912.377885990084, 6630.635315057718, 6720.596764194164, 6819.011243369783, 6970.616616086164, 7002.334778196063, 6138.570300078952, 6242.906356404748, 6289.779857704958, 6367.458766004756, 6370.713317900798, 5921.733521991435, 5952.791652292322, 5953.9404605955115, 5957.642541447353, 6007.699615019319, 5617.578681598979, 5716.199732037435, 5731.5128214100605, 5732.082866741302, 5732.369439361751, 4200.9159816802885, 4217.599328063829, 4234.682398820039, 4244.511881133586, 4364.345348197796, 4122.257993561067, 4174.728642638219, 4176.60322269384, 4259.488861336536, 4320.848770293024], "value": 3838.5835593541456, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.13395017083565, 364.36054723959455, 367.8841165033014, 371.61723424872355, 375.50058422298343, 4767.404074490689, 4783.593394561462, 4793.424789025322, 4812.614990800736, 4820.421549414071, 4501.572350626685, 4561.415777143974, 4647.512011238813, 4719.740414277213, 4735.337052812609, 3829.157585331223, 3845.9230815383676, 3854.1906199698096, 3856.86500972466, 3862.2275544890995, 3856.9614303856883, 3864.9227015459674, 3865.5747867734526, 3876.6704830089475, 3877.9806100969595, 3540.415831387818, 3645.1798345372345, 3852.4028775539837, 3863.555691095518, 3869.535655721317, 3821.3503704370323, 3831.2720583089263, 3835.4822099016556, 3841.442990766351, 3853.903652408684, 3470.9479357809655, 3473.930521389558, 3474.722022238215, 3495.370833250014, 3500.824958752052, 4685.344051832746, 4690.530946905316, 4691.554302341272, 4697.4832768395445, 4713.787879545394, 4017.6384125428854, 4165.625093741578, 4202.85290014848, 4207.810648520794, 4209.684273679486, 4952.182889823175, 4954.455009948902, 4960.479149689303, 5011.749412529395, 5045.64680472367, 3591.8024508652015, 3607.0801945503727, 3612.584552776412, 3619.7828130312, 3622.1850083731215, 5096.566791822699, 5156.747495654868, 5192.71440070796, 5227.895442091137, 5253.001929633358, 3671.2106897016733, 3678.6873115784924, 3684.871029513954, 3686.170611612366, 3688.3729765939856, 3709.8516059357326, 3716.2381711749026, 3716.9814150929615, 3731.440283957428, 3734.3568829714836, 4214.346776249687, 4227.6406505447385, 4232.238197124486, 4245.150969806051, 4246.40550322955, 4724.291356296583, 4986.678530189502, 5010.3737032870895, 5014.623903207274, 5021.899562008813, 1221.9083568732203, 1222.7737868494357, 1226.7485165541043, 1228.1341654133814, 1232.3345393487596, 816.7508909782455, 835.7826964989147, 844.7634662294568, 869.2177040663454, 877.7279043496536, 4200.579942005775, 4227.87316380509, 4233.70364074518, 4241.724287921275, 4263.957360426418, 4922.384701912261, 4943.5797957174045, 4963.5780958618925, 4978.303037574742, 5020.497950205024, 4172.165566886635, 4230.471191101113, 4235.470566179228, 4252.829886804555, 4277.593628605277, 2655.3494393873625, 2660.467906418724, 2666.186813022047, 2668.8790707902726, 2669.7864170866283, 4671.205894997858, 4686.851721328228, 4691.437027556715, 4705.717656940595, 4718.787654555554, 4935.284383764406, 5085.593152547861, 5105.732331307966, 5130.127878260705, 5133.768980395891, 4648.465426475931, 4648.535146485392, 4679.953200467951, 4686.64850136248, 4696.718196908197, 4962.727050012284, 4970.204767237225, 5010.173321401928, 5011.949880501148, 5030.949690503048, 4701.002074118418, 4922.212446008622, 4937.382827146606, 4950.579200767975, 4959.851204463872, 4670.416197975253, 4676.134915040592, 4676.205045142266, 4679.953200467951, 4690.599315496238, 3568.3576956148145, 3577.5706915170344, 3592.6227746086283, 3604.7837129772297, 3617.764845285048, 4866.690650357791, 5039.168537191323, 5048.495150484922, 5060.418051924076, 5068.543831055223, 4836.839158601981, 4848.739327774874, 4863.586595139453, 4873.390826146731, 4887.804487820589, 4847.0, 4858.659893807399, 4878.243872199815, 4890.793917231249, 4893.975530122327, 4686.109639168547, 4770.570648641639, 4794.0, 4794.808207671794, 4813.350197723263, 4908.680935739166, 4941.431735350337, 4944.629152813482, 4954.355933728592, 4958.479359667327, 4792.7363994980615, 4795.976020119877, 4804.8798780030775, 4826.32431459603, 4838.0], "value": 3755.6180552052656, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.4717449627545, 678.9883074219412, 720.8882623193407, 733.3583114774573, 746.08231874794, 13074.803877941826, 13702.931485342559, 13736.381862816184, 13762.31188440579, 13847.238401887906, 12381.938090309559, 13211.537596184135, 13323.666908327297, 13537.12008719435, 13552.661183470418, 778.7663700889725, 791.3827214772487, 799.664141060753, 800.2837460472873, 800.7998000499875, 799.1768478467188, 801.907780605232, 802.7110240313483, 802.7591722483261, 802.8394321135767, 844.8986121665408, 845.7251393297172, 846.9110743371949, 847.4237518487427, 847.8389106069858, 832.4422636833276, 841.1294310388779, 841.3941961787534, 841.6338892581716, 842.2756429470651, 619.3342157181004, 619.8791235709039, 619.8977168767148, 620.4106099205727, 620.7175735040546, 10398.896011039944, 10416.79166416675, 10535.78928421435, 13221.73556528864, 13676.452941882313, 11469.942650286695, 13037.217766934014, 13052.869471305356, 13250.204987700768, 13391.263480508567, 10470.842937355947, 10536.683899483029, 10552.736181595445, 10658.946705266502, 11000.944995275053, 3160.8893688721014, 3169.413658473167, 3275.606927168755, 3314.138324035744, 3317.2204531934976, 53422.19866702003, 57001.42998570003, 57047.429525704625, 63399.36600634027, 65391.0, 10406.5837366506, 10448.32085914413, 10605.0, 10621.097206737359, 10658.360498370123, 17268.0, 18101.818981810015, 18741.625167496575, 18744.718829217574, 18928.905355473136, 5916.822495325147, 5996.730147143389, 6222.564420490522, 6223.315435302113, 6501.869962600722, 3312.966870331314, 3333.2000319923527, 3379.121428428626, 3393.270446853938, 3429.5713035870517, 3019.8037127587077, 3100.488419410817, 3108.782385233012, 3110.2690867646083, 3116.423461659555, 504.6417043898873, 506.6326912988128, 513.3659929986463, 523.4163907243473, 527.952484276417, 554.694917795211, 580.2138102870549, 588.141313682029, 588.9381614930371, 591.2815928646714, 7453.0, 7778.727744529027, 7921.168277330912, 8110.63502142405, 8153.8776918346275, 468.80778880658767, 470.36265859760215, 472.24676640757934, 472.90541891621814, 480.4882799818252, 3552.2717842842158, 3779.4519794629623, 3879.6702280305926, 3931.4692516510477, 3988.4216788565495, 61186.69406652938, 70267.29732702607, 70314.29685703077, 70827.6458617714, 71174.28825711677, 47359.0, 54490.72754636202, 57221.0, 57287.427125729046, 59492.40507594955, 53046.89052255086, 53248.0, 53396.73301633545, 55013.0, 55078.44921550733, 7198.136223653195, 7257.310555497219, 7429.442791790745, 7513.62431878393, 7693.269139431745, 37411.0, 37563.24873502569, 37985.0, 39002.41496377614, 39188.6081139185, 35961.0, 36090.0, 40512.79743601263, 40566.59433405628, 42023.0, 11042.668719938414, 11089.667309980714, 11090.611828585961, 11201.60794372193, 11441.713957151138, 11749.88250117488, 12079.51681932748, 12287.631371058882, 12499.56251531192, 13075.934620326838, 10885.782284354427, 10974.506147223234, 11135.443227838738, 11203.271787333797, 11595.710107247387, 10888.51001704941, 10964.890351096386, 11188.608398706006, 11748.530058797553, 11755.941220294186, 11484.310941343547, 11746.29522228669, 11997.64007079789, 12020.879791201976, 12290.385480726107, 9642.566084526074, 10054.849177262493, 10088.899111008795, 10151.441670708182, 10304.587816487257, 8644.481331120152, 9793.804123917624, 9903.900960990586, 9968.900310996798, 9997.800043999225], "value": 6161.4658292208915, "unit": "score"}], "name": "dromaeo_css", "value": 6098.471579785188, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 115.0, 115.0, 116.0, 116.0, 115.0, 116.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 125.0, 125.0, 165.0, 133.0, 125.0, 126.0, 127.0, 157.0, 134.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 163.0, 165.0, 161.0, 162.0, 163.0, 161.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 113.0, 76.0, 76.0, 77.0, 76.0, 91.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 105.0, 86.0, 85.0, 86.0, 98.0, 84.0, 84.0, 108.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 149.0, 149.0, 148.0, 148.0, 148.0, 148.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 61.0, 63.0, 62.0, 63.0, 62.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 69.0, 68.0, 67.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 90.0, 85.0, 84.0, 84.0, 85.0, 84.0, 85.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 133.0, 135.0, 145.0, 141.0, 138.0, 132.0, 172.0, 155.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 185.0, 179.0, 194.0, 182.0, 184.0, 179.0, 183.0, 180.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 69.0, 69.0, 69.0, 71.0, 70.0, 69.0, 69.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1554.2000000000003, "alertThreshold": 2.0}]} 22:07:44 INFO - Return code: 0 22:07:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:07:44 INFO - # TBPL SUCCESS # 22:07:44 INFO - Running post-action listener: _resource_record_post_action 22:07:44 INFO - [mozharness: 2016-12-27 06:07:44.445092Z] Finished run-tests step (success) 22:07:44 INFO - Running post-run listener: _resource_record_post_run 22:07:44 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 7843840; Write bytes: 420851712; Read time: 2208; Write time: 342512 22:07:44 INFO - TinderboxPrint: CPU usage
13.1% 22:07:44 INFO - TinderboxPrint: I/O read bytes / time
7,843,840 / 2,208 22:07:44 INFO - TinderboxPrint: I/O write bytes / time
420,851,712 / 342,512 22:07:44 INFO - TinderboxPrint: CPU idle
8,354.2 (86.9%) 22:07:44 INFO - TinderboxPrint: CPU user
1,215.3 (12.6%) 22:07:44 INFO - TinderboxPrint: Swap in / out
0 / 0 22:07:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 4673536; Read time: 0; Write time: 308 22:07:44 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 5632000; Write bytes: 416178176; Read time: 2060; Write time: 342204 22:07:45 INFO - Running post-run listener: _upload_blobber_files 22:07:45 INFO - Blob upload gear active. 22:07:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:07:45 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 22:07:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:07:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:07:45 INFO - (blobuploader) - INFO - Open directory for files ... 22:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:07:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:48 INFO - (blobuploader) - INFO - Done attempting. 22:07:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:07:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:07:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:49 INFO - (blobuploader) - INFO - Done attempting. 22:07:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:07:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:07:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:50 INFO - (blobuploader) - INFO - Done attempting. 22:07:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:07:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:07:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:51 INFO - (blobuploader) - INFO - Done attempting. 22:07:51 INFO - (blobuploader) - INFO - Iteration through files over. 22:07:51 INFO - Return code: 0 22:07:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:07:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:07:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7d497df15cdc9cddb744e8dd00f3ff27a8ccac953c760b6a6539295b8e2ac4eb73adc7c0a41e0db36db7993de3ec1b2d2220d498a4d3ce3196a20c8dc1b8041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/569cae655bca04c58a8f803044090c7d635e4cc1f58f737c82e55b5fd55e7c541d16301506063f378668433df75fc7a415b1ac7bbca116e76e92a01422585528", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82a30d5bb183ee8aa02d25fbd67f7a5d2443a60b0f523bfc92ec64f4647c1747eb277bddac09dd23b8fad0d946555fe1f688d50b976dbbd70544d2bad841d3fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afc2f64d3b546268117cdf1c8307a6f67069266d612b8396369a732fcc8bd9f9700c550edf5f450464a1c9203f1b6d7d84897ab3f92c76e6db91544fab1220b8"} 22:07:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:07:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:07:51 INFO - Contents: 22:07:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7d497df15cdc9cddb744e8dd00f3ff27a8ccac953c760b6a6539295b8e2ac4eb73adc7c0a41e0db36db7993de3ec1b2d2220d498a4d3ce3196a20c8dc1b8041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/569cae655bca04c58a8f803044090c7d635e4cc1f58f737c82e55b5fd55e7c541d16301506063f378668433df75fc7a415b1ac7bbca116e76e92a01422585528", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82a30d5bb183ee8aa02d25fbd67f7a5d2443a60b0f523bfc92ec64f4647c1747eb277bddac09dd23b8fad0d946555fe1f688d50b976dbbd70544d2bad841d3fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afc2f64d3b546268117cdf1c8307a6f67069266d612b8396369a732fcc8bd9f9700c550edf5f450464a1c9203f1b6d7d84897ab3f92c76e6db91544fab1220b8"} 22:07:51 INFO - Running post-run listener: copy_logs_to_upload_dir 22:07:51 INFO - Copying logs to upload dir... 22:07:51 INFO - mkdir: /builds/slave/test/build/upload/logs 22:07:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.014073 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-12-26 22:07:51.156852) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 22:07:51.160450) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7d497df15cdc9cddb744e8dd00f3ff27a8ccac953c760b6a6539295b8e2ac4eb73adc7c0a41e0db36db7993de3ec1b2d2220d498a4d3ce3196a20c8dc1b8041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/569cae655bca04c58a8f803044090c7d635e4cc1f58f737c82e55b5fd55e7c541d16301506063f378668433df75fc7a415b1ac7bbca116e76e92a01422585528", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82a30d5bb183ee8aa02d25fbd67f7a5d2443a60b0f523bfc92ec64f4647c1747eb277bddac09dd23b8fad0d946555fe1f688d50b976dbbd70544d2bad841d3fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afc2f64d3b546268117cdf1c8307a6f67069266d612b8396369a732fcc8bd9f9700c550edf5f450464a1c9203f1b6d7d84897ab3f92c76e6db91544fab1220b8"} build_url:https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/OrX8kRK9SNuYGNb9xH_vkg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7d497df15cdc9cddb744e8dd00f3ff27a8ccac953c760b6a6539295b8e2ac4eb73adc7c0a41e0db36db7993de3ec1b2d2220d498a4d3ce3196a20c8dc1b8041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/569cae655bca04c58a8f803044090c7d635e4cc1f58f737c82e55b5fd55e7c541d16301506063f378668433df75fc7a415b1ac7bbca116e76e92a01422585528", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/82a30d5bb183ee8aa02d25fbd67f7a5d2443a60b0f523bfc92ec64f4647c1747eb277bddac09dd23b8fad0d946555fe1f688d50b976dbbd70544d2bad841d3fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afc2f64d3b546268117cdf1c8307a6f67069266d612b8396369a732fcc8bd9f9700c550edf5f450464a1c9203f1b6d7d84897ab3f92c76e6db91544fab1220b8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 22:07:51.185908) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:07:51.186389) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482806336.673926-1259511668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:07:51.271433) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 22:07:51.271810) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 22:07:51.272153) ========= ========= Total master_lag: 0.17 =========