builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1483103698.67 results: success (0) buildid: 20161230030205 builduid: eb605938827f4c86a740a44961665a7b revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.667307) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.667852) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.668145) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.736242) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.736549) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-30 05:14:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 22.1M=0.001s 2016-12-30 05:14:59 (22.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075911 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.825605) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.825896) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020024 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 05:14:58.877381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 05:14:58.877731) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a --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 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a --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=20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-30 05:14:59,230 truncating revision to first 12 chars 2016-12-30 05:14:59,230 Setting DEBUG logging. 2016-12-30 05:14:59,230 attempt 1/10 2016-12-30 05:14:59,230 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f63f95e28ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 05:15:00,241 unpacking tar archive at: mozilla-central-6f63f95e28ff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.306408 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 05:15:00.198373) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 05:15:00.198696) ========= script_repo_revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 05:15:00.199092) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 05:15:00.199360) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 05:15:00.209594) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-30 05:15:00.209910) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:15:00 INFO - MultiFileLogger online at 20161230 05:15:00 in /builds/slave/test 05:15:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:15:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:00 INFO - {'append_to_log': False, 05:15:00 INFO - 'base_work_dir': '/builds/slave/test', 05:15:00 INFO - 'blob_upload_branch': 'Firefox', 05:15:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:00 INFO - 'branch': 'Firefox', 05:15:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:00 INFO - 'config_files': ('talos/linux_config.py',), 05:15:00 INFO - 'default_actions': ('clobber', 05:15:00 INFO - 'read-buildbot-config', 05:15:00 INFO - 'download-and-extract', 05:15:00 INFO - 'populate-webroot', 05:15:00 INFO - 'create-virtualenv', 05:15:00 INFO - 'install', 05:15:00 INFO - 'run-tests'), 05:15:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:00 INFO - 'download_minidump_stackwalk': True, 05:15:00 INFO - 'download_symbols': 'ondemand', 05:15:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:15:00 INFO - 'tooltool.py': '/tools/tooltool.py', 05:15:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:15:00 INFO - '/tools/misc-python/virtualenv.py')}, 05:15:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:00 INFO - 'installer_path': 'installer.exe', 05:15:00 INFO - 'log_level': 'info', 05:15:00 INFO - 'log_name': 'talos', 05:15:00 INFO - 'log_to_console': True, 05:15:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:15:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:15:00 INFO - 'opt_config_files': (), 05:15:00 INFO - 'pip_index': False, 05:15:00 INFO - 'sps_profile': False, 05:15:00 INFO - 'sps_profile_interval': 0, 05:15:00 INFO - 'suite': 'dromaeojs', 05:15:00 INFO - 'system_bits': '32', 05:15:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:00 INFO - 'title': 'talos-linux64-ix-015', 05:15:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:00 INFO - 'use_talos_json': True, 05:15:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:00 INFO - 'work_dir': 'build'} 05:15:00 INFO - [mozharness: 2016-12-30 13:15:00.656814Z] Running clobber step. 05:15:00 INFO - Running pre-action listener: _resource_record_pre_action 05:15:00 INFO - Running main action method: clobber 05:15:00 INFO - rmtree: /builds/slave/test/build 05:15:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:01 INFO - Running post-action listener: _resource_record_post_action 05:15:01 INFO - [mozharness: 2016-12-30 13:15:01.117261Z] Finished clobber step (success) 05:15:01 INFO - [mozharness: 2016-12-30 13:15:01.117361Z] Running read-buildbot-config step. 05:15:01 INFO - Running pre-action listener: _resource_record_pre_action 05:15:01 INFO - Running main action method: read_buildbot_config 05:15:01 INFO - Using buildbot properties: 05:15:01 INFO - { 05:15:01 INFO - "project": "", 05:15:01 INFO - "product": "firefox", 05:15:01 INFO - "script_repo_revision": "production", 05:15:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:15:01 INFO - "repository": "", 05:15:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:15:01 INFO - "buildid": "20161230030205", 05:15:01 INFO - "stage_platform": "linux64-pgo", 05:15:01 INFO - "basedir": "/builds/slave/test", 05:15:01 INFO - "nightly_build": "True", 05:15:01 INFO - "buildnumber": 18, 05:15:01 INFO - "slavename": "talos-linux64-ix-015", 05:15:01 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:15:01 INFO - "platform": "ubuntu64_hw", 05:15:01 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:15:01 INFO - "branch": "mozilla-central", 05:15:01 INFO - "repo_path": "mozilla-central", 05:15:01 INFO - "revision": "6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a", 05:15:01 INFO - "pgo_build": "True", 05:15:01 INFO - "builduid": "eb605938827f4c86a740a44961665a7b", 05:15:01 INFO - "slavebuilddir": "test" 05:15:01 INFO - } 05:15:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 05:15:01 INFO - Running post-action listener: _resource_record_post_action 05:15:01 INFO - [mozharness: 2016-12-30 13:15:01.119848Z] Finished read-buildbot-config step (success) 05:15:01 INFO - [mozharness: 2016-12-30 13:15:01.119937Z] Running download-and-extract step. 05:15:01 INFO - Running pre-action listener: _resource_record_pre_action 05:15:01 INFO - Running main action method: download_and_extract 05:15:01 INFO - mkdir: /builds/slave/test/build/tests 05:15:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:01 INFO - trying https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:15:01 INFO - Downloading https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/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 05:15:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/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 05:15:01 INFO - Downloaded 1361 bytes. 05:15:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:15:01 INFO - Using the following test package requirements: 05:15:01 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:15:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:15:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:15:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'jsshell-linux-x86_64.zip'], 05:15:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:15:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:15:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:15:01 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:15:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:15:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:15:01 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:15:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:15:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:15:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:15:01 INFO - Fetch https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:15:02 INFO - Expected file size: 21835950 05:15:02 INFO - Obtained file size: 21835950 05:15: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 05:15:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:15:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:15:06 INFO - Fetch https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:15:07 INFO - Expected file size: 21835950 05:15:07 INFO - Obtained file size: 21835950 05:15:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 05:15:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:15:11 INFO - Fetch https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:15:12 INFO - Expected file size: 14409776 05:15:12 INFO - Obtained file size: 14409776 05:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:12 INFO - trying https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:15:12 INFO - Downloading https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/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 05:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/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 05:15:14 INFO - Downloaded 61396536 bytes. 05:15:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:15:14 INFO - mkdir: /builds/slave/test/properties 05:15:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:15:14 INFO - Writing to file /builds/slave/test/properties/build_url 05:15:14 INFO - Contents: 05:15:14 INFO - build_url:https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:15:14 INFO - Running post-action listener: _resource_record_post_action 05:15:14 INFO - Running post-action listener: set_extra_try_arguments 05:15:14 INFO - [mozharness: 2016-12-30 13:15:14.783851Z] Finished download-and-extract step (success) 05:15:14 INFO - [mozharness: 2016-12-30 13:15:14.784077Z] Running populate-webroot step. 05:15:14 INFO - Running pre-action listener: _resource_record_pre_action 05:15:14 INFO - Running main action method: populate_webroot 05:15:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:15:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:15:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:15:14 INFO - '--tppagecycles', 05:15:14 INFO - '7'], 05:15:14 INFO - 'tests': ['tsvgm']}}, 05:15:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:15:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:15:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:15:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:15:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:15:14 INFO - 'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:15:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:15:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:15:14 INFO - 'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['damp', 'tps']}, 05:15:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:15:14 INFO - 'tests': ['damp', 'tps']}, 05:15:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['dromaeo_dom']}, 05:15:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:15:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['basic_compositor_video']}, 05:15:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:15:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['a11yr', 05:15:14 INFO - 'ts_paint', 05:15:14 INFO - 'tpaint', 05:15:14 INFO - 'sessionrestore', 05:15:14 INFO - 'sessionrestore_no_auto_restore', 05:15:14 INFO - 'tabpaint']}, 05:15:14 INFO - 'other-e10s': {'tests': ['a11yr', 05:15:14 INFO - 'ts_paint', 05:15:14 INFO - 'tpaint', 05:15:14 INFO - 'sessionrestore', 05:15:14 INFO - 'sessionrestore_no_auto_restore', 05:15:14 INFO - 'tabpaint']}, 05:15:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:15:14 INFO - 'ts_paint', 05:15:14 INFO - 'tpaint', 05:15:14 INFO - 'sessionrestore', 05:15:14 INFO - 'sessionrestore_no_auto_restore', 05:15:14 INFO - 'tabpaint']}, 05:15:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:15:14 INFO - 'ts_paint', 05:15:14 INFO - 'tpaint', 05:15:14 INFO - 'sessionrestore', 05:15:14 INFO - 'sessionrestore_no_auto_restore', 05:15:14 INFO - 'tabpaint']}, 05:15:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['a11yr', 05:15:14 INFO - 'ts_paint', 05:15:14 INFO - 'tpaint', 05:15:14 INFO - 'sessionrestore', 05:15:14 INFO - 'sessionrestore_no_auto_restore', 05:15:14 INFO - 'tabpaint']}, 05:15:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['a11yr', 05:15:14 INFO - 'ts_paint', 05:15:14 INFO - 'tpaint', 05:15:14 INFO - 'sessionrestore', 05:15:14 INFO - 'sessionrestore_no_auto_restore', 05:15:14 INFO - 'tabpaint']}, 05:15:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['tsvgx', 05:15:14 INFO - 'tsvgr_opacity', 05:15:14 INFO - 'tart', 05:15:14 INFO - 'tscrollx', 05:15:14 INFO - 'cart', 05:15:14 INFO - 'tsvg_static']}, 05:15:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:15:14 INFO - 'tsvgr_opacity', 05:15:14 INFO - 'tart', 05:15:14 INFO - 'tscrollx', 05:15:14 INFO - 'cart', 05:15:14 INFO - 'tsvg_static']}, 05:15:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:15:14 INFO - 'talos_options': ['--disable-e10s'], 05:15:14 INFO - 'tests': ['tp5o']}, 05:15:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:15:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:15:14 INFO - 'talos_options': ['--disable-e10s', 05:15:14 INFO - '--xperf_path', 05:15:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:14 INFO - 'tests': ['tp5n']}, 05:15:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:15:14 INFO - 'talos_options': ['--xperf_path', 05:15:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:15:14 INFO - 'tests': ['tp5n']}}, 05:15:14 INFO - 'talos.zip': {'path': '', 05:15:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:15:14 INFO - Running post-action listener: _resource_record_post_action 05:15:14 INFO - [mozharness: 2016-12-30 13:15:14.800200Z] Finished populate-webroot step (success) 05:15:14 INFO - [mozharness: 2016-12-30 13:15:14.800288Z] Running create-virtualenv step. 05:15:14 INFO - Running pre-action listener: _resource_record_pre_action 05:15:14 INFO - Running main action method: create_virtualenv 05:15:14 INFO - Creating virtualenv /builds/slave/test/build/venv 05:15: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 05:15:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:15:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:15:14 INFO - Using real prefix '/usr' 05:15:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:15:15 INFO - Installing distribute.............................................................................................................................................................................................done. 05:15:17 INFO - Installing pip.................done. 05:15:17 INFO - Return code: 0 05:15:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:15:17 INFO - Reading from file tmpfile_stdout 05:15:17 INFO - Output received: 05:15: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) 05:15:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:15: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')]} 05:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15: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')]} 05:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15: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 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15: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 05:15: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 05:15:17 INFO - Using env: {'DISPLAY': ':0', 05:15:17 INFO - 'HOME': '/home/cltbld', 05:15:17 INFO - 'LANG': 'en_US.UTF-8', 05:15:17 INFO - 'LANGUAGE': 'en_US:en', 05:15:17 INFO - 'LOGNAME': 'cltbld', 05:15:17 INFO - 'MAIL': '/var/mail/cltbld', 05:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:17 INFO - 'MOZ_NO_REMOTE': '1', 05:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:17 INFO - 'NO_EM_RESTART': '1', 05:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:17 INFO - 'PWD': '/builds/slave/test', 05:15:17 INFO - 'SHELL': '/bin/bash', 05:15:17 INFO - 'SHLVL': '1', 05:15:17 INFO - 'TERM': 'linux', 05:15:17 INFO - 'TMOUT': '86400', 05:15:17 INFO - 'USER': 'cltbld', 05:15:17 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:17 INFO - '_': '/tools/buildbot/bin/python'} 05:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:15:17 INFO - Cleaning up... 05:15:17 INFO - Return code: 0 05:15:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:15: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')]} 05:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15: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')]} 05:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15: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', '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 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:15: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 psutil>=3.1.1 05:15:17 INFO - Using env: {'DISPLAY': ':0', 05:15:17 INFO - 'HOME': '/home/cltbld', 05:15:17 INFO - 'LANG': 'en_US.UTF-8', 05:15:17 INFO - 'LANGUAGE': 'en_US:en', 05:15:17 INFO - 'LOGNAME': 'cltbld', 05:15:17 INFO - 'MAIL': '/var/mail/cltbld', 05:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:17 INFO - 'MOZ_NO_REMOTE': '1', 05:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:17 INFO - 'NO_EM_RESTART': '1', 05:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:17 INFO - 'PWD': '/builds/slave/test', 05:15:17 INFO - 'SHELL': '/bin/bash', 05:15:17 INFO - 'SHLVL': '1', 05:15:17 INFO - 'TERM': 'linux', 05:15:17 INFO - 'TMOUT': '86400', 05:15:17 INFO - 'USER': 'cltbld', 05:15:17 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:17 INFO - '_': '/tools/buildbot/bin/python'} 05:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:18 INFO - Downloading/unpacking psutil>=3.1.1 05:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:15:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:20 INFO - Installing collected packages: psutil 05:15:20 INFO - Running setup.py install for psutil 05:15:20 INFO - building 'psutil._psutil_linux' extension 05:15:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:15:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:15:20 INFO - building 'psutil._psutil_posix' extension 05:15:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:15:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:15:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:20 INFO - Successfully installed psutil 05:15:20 INFO - Cleaning up... 05:15:20 INFO - Return code: 0 05:15:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:20 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 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:15:20 INFO - Using env: {'DISPLAY': ':0', 05:15:20 INFO - 'HOME': '/home/cltbld', 05:15:20 INFO - 'LANG': 'en_US.UTF-8', 05:15:20 INFO - 'LANGUAGE': 'en_US:en', 05:15:20 INFO - 'LOGNAME': 'cltbld', 05:15:20 INFO - 'MAIL': '/var/mail/cltbld', 05:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:20 INFO - 'MOZ_NO_REMOTE': '1', 05:15:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:20 INFO - 'NO_EM_RESTART': '1', 05:15:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:20 INFO - 'PWD': '/builds/slave/test', 05:15:20 INFO - 'SHELL': '/bin/bash', 05:15:20 INFO - 'SHLVL': '1', 05:15:20 INFO - 'TERM': 'linux', 05:15:20 INFO - 'TMOUT': '86400', 05:15:20 INFO - 'USER': 'cltbld', 05:15:20 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:20 INFO - '_': '/tools/buildbot/bin/python'} 05:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:15:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:15:22 INFO - Installing collected packages: mozsystemmonitor 05:15:22 INFO - Running setup.py install for mozsystemmonitor 05:15:23 INFO - Successfully installed mozsystemmonitor 05:15:23 INFO - Cleaning up... 05:15:23 INFO - Return code: 0 05:15:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:15:23 INFO - Using env: {'DISPLAY': ':0', 05:15:23 INFO - 'HOME': '/home/cltbld', 05:15:23 INFO - 'LANG': 'en_US.UTF-8', 05:15:23 INFO - 'LANGUAGE': 'en_US:en', 05:15:23 INFO - 'LOGNAME': 'cltbld', 05:15:23 INFO - 'MAIL': '/var/mail/cltbld', 05:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:23 INFO - 'MOZ_NO_REMOTE': '1', 05:15:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:23 INFO - 'NO_EM_RESTART': '1', 05:15:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:23 INFO - 'PWD': '/builds/slave/test', 05:15:23 INFO - 'SHELL': '/bin/bash', 05:15:23 INFO - 'SHLVL': '1', 05:15:23 INFO - 'TERM': 'linux', 05:15:23 INFO - 'TMOUT': '86400', 05:15:23 INFO - 'USER': 'cltbld', 05:15:23 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:23 INFO - '_': '/tools/buildbot/bin/python'} 05:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:23 INFO - Downloading/unpacking jsonschema==2.5.1 05:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:15:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:15:25 INFO - warning: no files found matching '*.txt' 05:15:25 INFO - no previously-included directories found matching 'build' 05:15:25 INFO - no previously-included directories found matching 'dist' 05:15:25 INFO - no previously-included directories found matching '.git*' 05:15:25 INFO - Installing collected packages: jsonschema, functools32 05:15:25 INFO - Running setup.py install for functools32 05:15:25 INFO - warning: no files found matching '*.txt' 05:15:25 INFO - no previously-included directories found matching 'build' 05:15:25 INFO - no previously-included directories found matching 'dist' 05:15:25 INFO - no previously-included directories found matching '.git*' 05:15:25 INFO - Successfully installed jsonschema functools32 05:15:25 INFO - Cleaning up... 05:15:25 INFO - Return code: 0 05:15:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:15:25 INFO - Using env: {'DISPLAY': ':0', 05:15:25 INFO - 'HOME': '/home/cltbld', 05:15:25 INFO - 'LANG': 'en_US.UTF-8', 05:15:25 INFO - 'LANGUAGE': 'en_US:en', 05:15:25 INFO - 'LOGNAME': 'cltbld', 05:15:25 INFO - 'MAIL': '/var/mail/cltbld', 05:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:25 INFO - 'MOZ_NO_REMOTE': '1', 05:15:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:25 INFO - 'NO_EM_RESTART': '1', 05:15:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:25 INFO - 'PWD': '/builds/slave/test', 05:15:25 INFO - 'SHELL': '/bin/bash', 05:15:25 INFO - 'SHLVL': '1', 05:15:25 INFO - 'TERM': 'linux', 05:15:25 INFO - 'TMOUT': '86400', 05:15:25 INFO - 'USER': 'cltbld', 05:15:25 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:25 INFO - '_': '/tools/buildbot/bin/python'} 05:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:15:26 INFO - Cleaning up... 05:15:26 INFO - Return code: 0 05:15:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:15:26 INFO - Using env: {'DISPLAY': ':0', 05:15:26 INFO - 'HOME': '/home/cltbld', 05:15:26 INFO - 'LANG': 'en_US.UTF-8', 05:15:26 INFO - 'LANGUAGE': 'en_US:en', 05:15:26 INFO - 'LOGNAME': 'cltbld', 05:15:26 INFO - 'MAIL': '/var/mail/cltbld', 05:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:26 INFO - 'MOZ_NO_REMOTE': '1', 05:15:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:26 INFO - 'NO_EM_RESTART': '1', 05:15:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:26 INFO - 'PWD': '/builds/slave/test', 05:15:26 INFO - 'SHELL': '/bin/bash', 05:15:26 INFO - 'SHLVL': '1', 05:15:26 INFO - 'TERM': 'linux', 05:15:26 INFO - 'TMOUT': '86400', 05:15:26 INFO - 'USER': 'cltbld', 05:15:26 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:26 INFO - '_': '/tools/buildbot/bin/python'} 05:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:28 INFO - Downloading blobuploader-1.2.4.tar.gz 05:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:15:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:15:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:28 INFO - Downloading docopt-0.6.1.tar.gz 05:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:15:29 INFO - Installing collected packages: blobuploader, requests, docopt 05:15:29 INFO - Running setup.py install for blobuploader 05:15:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:15:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:15:29 INFO - Running setup.py install for requests 05:15:29 INFO - Running setup.py install for docopt 05:15:29 INFO - Successfully installed blobuploader requests docopt 05:15:29 INFO - Cleaning up... 05:15:29 INFO - Return code: 0 05:15:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:15:29 INFO - Using env: {'DISPLAY': ':0', 05:15:29 INFO - 'HOME': '/home/cltbld', 05:15:29 INFO - 'LANG': 'en_US.UTF-8', 05:15:29 INFO - 'LANGUAGE': 'en_US:en', 05:15:29 INFO - 'LOGNAME': 'cltbld', 05:15:29 INFO - 'MAIL': '/var/mail/cltbld', 05:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:29 INFO - 'MOZ_NO_REMOTE': '1', 05:15:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:29 INFO - 'NO_EM_RESTART': '1', 05:15:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:29 INFO - 'PWD': '/builds/slave/test', 05:15:29 INFO - 'SHELL': '/bin/bash', 05:15:29 INFO - 'SHLVL': '1', 05:15:29 INFO - 'TERM': 'linux', 05:15:29 INFO - 'TMOUT': '86400', 05:15:29 INFO - 'USER': 'cltbld', 05:15:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:29 INFO - '_': '/tools/buildbot/bin/python'} 05:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:15:29 INFO - Running setup.py (path:/tmp/pip-ctfZSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:15:29 INFO - Running setup.py (path:/tmp/pip-N0nIK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:15:30 INFO - Running setup.py (path:/tmp/pip-OWpmhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:15:30 INFO - Running setup.py (path:/tmp/pip-weVYtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:15:30 INFO - Running setup.py (path:/tmp/pip-TprMc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:15:30 INFO - Running setup.py (path:/tmp/pip-NcVyFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:15:30 INFO - Running setup.py (path:/tmp/pip-BDDKzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:15:30 INFO - Running setup.py (path:/tmp/pip-6Kpf7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:15:30 INFO - Running setup.py (path:/tmp/pip-5CvBFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:15:30 INFO - Running setup.py (path:/tmp/pip-GvLCdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:15:30 INFO - Running setup.py (path:/tmp/pip-NUncWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:15:30 INFO - Running setup.py (path:/tmp/pip-YQNxCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:15:30 INFO - Running setup.py (path:/tmp/pip-cGepy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:15:30 INFO - Running setup.py (path:/tmp/pip-ThxU9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:30 INFO - Running setup.py (path:/tmp/pip-8R0X8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:15:30 INFO - Running setup.py (path:/tmp/pip-xLUdIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:15:31 INFO - Running setup.py (path:/tmp/pip-uG4Sts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:15:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:15:31 INFO - Running setup.py install for manifestparser 05:15:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Running setup.py install for mozcrash 05:15:31 INFO - Running setup.py install for mozdebug 05:15:31 INFO - Running setup.py install for mozdevice 05:15:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Running setup.py install for mozfile 05:15:31 INFO - Running setup.py install for mozhttpd 05:15:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Running setup.py install for mozinfo 05:15:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Running setup.py install for mozInstall 05:15:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:15:31 INFO - Running setup.py install for mozleak 05:15:32 INFO - Running setup.py install for mozlog 05:15:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:15:32 INFO - Running setup.py install for moznetwork 05:15:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:15:32 INFO - Running setup.py install for mozprocess 05:15:32 INFO - Running setup.py install for mozprofile 05:15:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:15:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:15:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:15:32 INFO - Running setup.py install for mozrunner 05:15:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:15:32 INFO - Running setup.py install for mozscreenshot 05:15:32 INFO - Running setup.py install for moztest 05:15:32 INFO - Running setup.py install for mozversion 05:15:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:15:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:15:32 INFO - Cleaning up... 05:15:32 INFO - Return code: 0 05:15:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:15:32 INFO - Using env: {'DISPLAY': ':0', 05:15:32 INFO - 'HOME': '/home/cltbld', 05:15:32 INFO - 'LANG': 'en_US.UTF-8', 05:15:32 INFO - 'LANGUAGE': 'en_US:en', 05:15:32 INFO - 'LOGNAME': 'cltbld', 05:15:32 INFO - 'MAIL': '/var/mail/cltbld', 05:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:32 INFO - 'MOZ_NO_REMOTE': '1', 05:15:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:32 INFO - 'NO_EM_RESTART': '1', 05:15:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:32 INFO - 'PWD': '/builds/slave/test', 05:15:32 INFO - 'SHELL': '/bin/bash', 05:15:32 INFO - 'SHLVL': '1', 05:15:32 INFO - 'TERM': 'linux', 05:15:32 INFO - 'TMOUT': '86400', 05:15:32 INFO - 'USER': 'cltbld', 05:15:32 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:32 INFO - '_': '/tools/buildbot/bin/python'} 05:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:15:33 INFO - Running setup.py (path:/tmp/pip-GSJZwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:15:33 INFO - Running setup.py (path:/tmp/pip-R2S2JQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:15:33 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)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:15:33 INFO - Running setup.py (path:/tmp/pip-FKrRyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:15:33 INFO - Running setup.py (path:/tmp/pip-cvnZF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:15:33 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)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:15:33 INFO - Running setup.py (path:/tmp/pip-dwDntU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:15:33 INFO - Running setup.py (path:/tmp/pip-3SvHEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:15:33 INFO - Running setup.py (path:/tmp/pip-M9tcH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:15:33 INFO - Running setup.py (path:/tmp/pip-2RogGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:15:33 INFO - Running setup.py (path:/tmp/pip-C78P5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:15:33 INFO - Running setup.py (path:/tmp/pip-GaePAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:15:33 INFO - Running setup.py (path:/tmp/pip-DYEcSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:15:33 INFO - Running setup.py (path:/tmp/pip-070zrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:15:34 INFO - Running setup.py (path:/tmp/pip-LB7Azu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:15:34 INFO - Running setup.py (path:/tmp/pip-HB7I7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:15:34 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)) 05:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:34 INFO - Running setup.py (path:/tmp/pip-QNLxK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:15:34 INFO - Running setup.py (path:/tmp/pip-FX_9wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:15:34 INFO - Running setup.py (path:/tmp/pip-ORmlXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:15:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:36 INFO - Downloading blessings-1.6.tar.gz 05:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:15:36 INFO - Installing collected packages: blessings 05:15:36 INFO - Running setup.py install for blessings 05:15:36 INFO - Successfully installed blessings 05:15:36 INFO - Cleaning up... 05:15:36 INFO - Return code: 0 05:15:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:15:36 INFO - Reading from file tmpfile_stdout 05:15:36 INFO - Current package versions: 05:15:36 INFO - argparse == 1.2.1 05:15:36 INFO - blessings == 1.6 05:15:36 INFO - blobuploader == 1.2.4 05:15:36 INFO - docopt == 0.6.1 05:15:36 INFO - functools32 == 3.2.3-2 05:15:36 INFO - jsonschema == 2.5.1 05:15:36 INFO - manifestparser == 1.1 05:15:36 INFO - mozInstall == 1.12 05:15:36 INFO - mozcrash == 1.0 05:15:36 INFO - mozdebug == 0.1 05:15:36 INFO - mozdevice == 0.49 05:15:36 INFO - mozfile == 1.2 05:15:36 INFO - mozhttpd == 0.7 05:15:36 INFO - mozinfo == 0.9 05:15:36 INFO - mozleak == 0.1 05:15:36 INFO - mozlog == 3.4 05:15:36 INFO - moznetwork == 0.27 05:15:36 INFO - mozprocess == 0.23 05:15:36 INFO - mozprofile == 0.28 05:15:36 INFO - mozrunner == 6.13 05:15:36 INFO - mozscreenshot == 0.1 05:15:36 INFO - mozsystemmonitor == 0.3 05:15:36 INFO - moztest == 0.8 05:15:36 INFO - mozversion == 1.4 05:15:36 INFO - psutil == 3.1.1 05:15:36 INFO - requests == 1.2.3 05:15:36 INFO - wsgiref == 0.1.2 05:15:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:15:36 INFO - Using env: {'DISPLAY': ':0', 05:15:36 INFO - 'HOME': '/home/cltbld', 05:15:36 INFO - 'LANG': 'en_US.UTF-8', 05:15:36 INFO - 'LANGUAGE': 'en_US:en', 05:15:36 INFO - 'LOGNAME': 'cltbld', 05:15:36 INFO - 'MAIL': '/var/mail/cltbld', 05:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:36 INFO - 'MOZ_NO_REMOTE': '1', 05:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:36 INFO - 'NO_EM_RESTART': '1', 05:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:36 INFO - 'PWD': '/builds/slave/test', 05:15:36 INFO - 'SHELL': '/bin/bash', 05:15:36 INFO - 'SHLVL': '1', 05:15:36 INFO - 'TERM': 'linux', 05:15:36 INFO - 'TMOUT': '86400', 05:15:36 INFO - 'USER': 'cltbld', 05:15:36 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:36 INFO - '_': '/tools/buildbot/bin/python'} 05:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:15:37 INFO - Cleaning up... 05:15:37 INFO - Return code: 0 05:15:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a5c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, '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': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:15:37 INFO - Using env: {'DISPLAY': ':0', 05:15:37 INFO - 'HOME': '/home/cltbld', 05:15:37 INFO - 'LANG': 'en_US.UTF-8', 05:15:37 INFO - 'LANGUAGE': 'en_US:en', 05:15:37 INFO - 'LOGNAME': 'cltbld', 05:15:37 INFO - 'MAIL': '/var/mail/cltbld', 05:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:37 INFO - 'MOZ_NO_REMOTE': '1', 05:15:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:37 INFO - 'NO_EM_RESTART': '1', 05:15:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:37 INFO - 'PWD': '/builds/slave/test', 05:15:37 INFO - 'SHELL': '/bin/bash', 05:15:37 INFO - 'SHLVL': '1', 05:15:37 INFO - 'TERM': 'linux', 05:15:37 INFO - 'TMOUT': '86400', 05:15:37 INFO - 'USER': 'cltbld', 05:15:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:37 INFO - '_': '/tools/buildbot/bin/python'} 05:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:15:37 INFO - Cleaning up... 05:15:37 INFO - Return code: 0 05:15:37 INFO - Running post-action listener: _resource_record_post_action 05:15:37 INFO - Running post-action listener: _start_resource_monitoring 05:15:37 INFO - Starting resource monitoring. 05:15:37 INFO - [mozharness: 2016-12-30 13:15:37.393743Z] Finished create-virtualenv step (success) 05:15:37 INFO - [mozharness: 2016-12-30 13:15:37.394206Z] Running install step. 05:15:37 INFO - Running pre-action listener: _resource_record_pre_action 05:15:37 INFO - Running main action method: install 05:15:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:15:37 INFO - Reading from file tmpfile_stdout 05:15:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:15:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:15:37 INFO - Reading from file tmpfile_stdout 05:15:37 INFO - Output received: 05:15:37 INFO - Usage: mozinstall [options] installer 05:15:37 INFO - Options: 05:15:37 INFO - -h, --help show this help message and exit 05:15:37 INFO - -d DEST, --destination=DEST 05:15:37 INFO - Directory to install application into. [default: 05:15:37 INFO - "/builds/slave/test"] 05:15:37 INFO - --app=APP Application being installed. [default: firefox] 05:15:37 INFO - mkdir: /builds/slave/test/build/application 05:15:37 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'] 05:15:37 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 05:15:55 INFO - Reading from file tmpfile_stdout 05:15:55 INFO - Output received: 05:15:55 INFO - /builds/slave/test/build/application/firefox/firefox 05:15:55 INFO - Running post-action listener: _resource_record_post_action 05:15:55 INFO - [mozharness: 2016-12-30 13:15:55.013897Z] Finished install step (success) 05:15:55 INFO - [mozharness: 2016-12-30 13:15:55.014140Z] Running run-tests step. 05:15:55 INFO - Running pre-action listener: _resource_record_pre_action 05:15:55 INFO - Running main action method: run_tests 05:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:15:55 INFO - Python 2.7.3 05:15:55 INFO - Return code: 0 05:15:55 INFO - grabbing minidump binary from tooltool 05:15: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')]} 05:15: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 0x16a33f0>, '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 0x16a3cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a41a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:15: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 05:15: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 05:15: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 05:15:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:15:55 INFO - Return code: 0 05:15:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:15:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:15:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:15:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:15:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:15:55 INFO - Using env: {'DISPLAY': ':0', 05:15:55 INFO - 'HOME': '/home/cltbld', 05:15:55 INFO - 'LANG': 'en_US.UTF-8', 05:15:55 INFO - 'LANGUAGE': 'en_US:en', 05:15:55 INFO - 'LOGNAME': 'cltbld', 05:15:55 INFO - 'MAIL': '/var/mail/cltbld', 05:15:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:15:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:55 INFO - 'MOZ_NO_REMOTE': '1', 05:15:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:15:55 INFO - 'NO_EM_RESTART': '1', 05:15:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:55 INFO - 'PWD': '/builds/slave/test', 05:15:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:15:55 INFO - 'SHELL': '/bin/bash', 05:15:55 INFO - 'SHLVL': '1', 05:15:55 INFO - 'TERM': 'linux', 05:15:55 INFO - 'TMOUT': '86400', 05:15:55 INFO - 'USER': 'cltbld', 05:15:55 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272', 05:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:55 INFO - '_': '/tools/buildbot/bin/python'} 05:15:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:15:55 INFO - mozversion application_buildid: 20161230030205 05:15:55 INFO - mozversion application_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 05:15:55 INFO - mozversion application_display_name: Nightly 05:15:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:15:55 INFO - mozversion application_name: Firefox 05:15:55 INFO - mozversion application_remotingname: firefox 05:15:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:15:55 INFO - mozversion application_vendor: Mozilla 05:15:55 INFO - mozversion application_version: 53.0a1 05:15:55 INFO - mozversion platform_buildid: 20161230030205 05:15:55 INFO - mozversion platform_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 05:15:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:15:55 INFO - mozversion platform_version: 53.0a1 05:15:55 INFO - using testdate: 1483103755 05:15:55 INFO - actual date: 1483103755 05:15:55 INFO - starting webserver on 'localhost:56561' 05:15:55 INFO - SUITE-START | Running 2 tests 05:15:55 INFO - TEST-START | dromaeo_css 05:15:55 INFO - operating with platform_type : linux_ 05:15:55 INFO - Initialising browser for dromaeo_css test... 05:15:55 INFO - TEST-INFO | started process 1243 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppJKxqB/profile http://localhost:56561/getInfo.html) 05:15:55 INFO - PROCESS | 1243 | 1483103755787 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 05:15:58 INFO - PROCESS | 1243 | __metrics Screen width/height:1600/1200 05:15:58 INFO - PROCESS | 1243 | colorDepth:24 05:15:58 INFO - PROCESS | 1243 | Browser inner width/height: 1024/697 05:15:58 INFO - PROCESS | 1243 | __metrics 05:15:59 INFO - PROCESS | 1243 | 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" 05:15:59 INFO - PROCESS | 1243 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:16:00 INFO - PROCESS | 1243 | console.error: PushService: 05:16:00 INFO - PROCESS | 1243 | stateChangeProcessEnqueue: Error transitioning state 05:16:00 INFO - PROCESS | 1243 | UnknownError 05:16:00 INFO - TEST-INFO | 1243: exit 0 05:16:00 INFO - Browser initialized. 05:16:00 INFO - Running cycle 1/1 for dromaeo_css test... 05:16:00 INFO - TEST-INFO | started process 1325 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppJKxqB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:16:12 INFO - PROCESS | 1325 | RSS: Main: 147316736 05:16:12 INFO - PROCESS | 1325 | 05:19:14 INFO - PROCESS | 1325 | Cycle 1(1): loaded http://localhost:56561/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56561/tests/dromaeo/cssquery-ext.html) 05:19:16 INFO - PROCESS | 1325 | RSS: Main: 258809856 05:19:16 INFO - PROCESS | 1325 | 05:21:01 INFO - PROCESS | 1325 | ************************* 05:21:01 INFO - PROCESS | 1325 | A coding exception was thrown and uncaught in a Task. 05:21:01 INFO - PROCESS | 1325 | 05:21:01 INFO - PROCESS | 1325 | Full message: ReferenceError: fetch is not defined 05:21:01 INFO - PROCESS | 1325 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 05:21:01 INFO - PROCESS | 1325 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 05:21:01 INFO - PROCESS | 1325 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 05:21:01 INFO - PROCESS | 1325 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:21:01 INFO - PROCESS | 1325 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:21:01 INFO - PROCESS | 1325 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:21:01 INFO - PROCESS | 1325 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 05:21:01 INFO - PROCESS | 1325 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:21:01 INFO - PROCESS | 1325 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:21:01 INFO - PROCESS | 1325 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:21:01 INFO - PROCESS | 1325 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 05:21:01 INFO - PROCESS | 1325 | 05:21:01 INFO - PROCESS | 1325 | ************************* 05:22:18 INFO - PROCESS | 1325 | Cycle 1(1): loaded http://localhost:56561/tests/dromaeo/cssquery-ext.html (next: http://localhost:56561/tests/dromaeo/cssquery-jquery.html) 05:22:19 INFO - PROCESS | 1325 | RSS: Main: 231141376 05:22:19 INFO - PROCESS | 1325 | 05:25:21 INFO - PROCESS | 1325 | Cycle 1(1): loaded http://localhost:56561/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56561/tests/dromaeo/cssquery-mootools.html) 05:25:21 INFO - PROCESS | 1325 | RSS: Main: 223928320 05:25:21 INFO - PROCESS | 1325 | 05:28:23 INFO - PROCESS | 1325 | Cycle 1(1): loaded http://localhost:56561/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56561/tests/dromaeo/cssquery-prototype.html) 05:28:24 INFO - PROCESS | 1325 | RSS: Main: 234704896 05:28:24 INFO - PROCESS | 1325 | 05:31:27 INFO - PROCESS | 1325 | Cycle 1(1): loaded http://localhost:56561/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56561/tests/dromaeo/cssquery-yui.html) 05:31:27 INFO - PROCESS | 1325 | RSS: Main: 183042048 05:31:27 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | Cycle 1(1): loaded http://localhost:56561/tests/dromaeo/cssquery-yui.html (next: http://localhost:56561/tests/dromaeo/cssquery-dojo.html) 05:34:30 INFO - PROCESS | 1325 | RSS: Main: 277032960 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | __start_tp_report 05:34:30 INFO - PROCESS | 1325 | _x_x_mozilla_page_load 05:34:30 INFO - PROCESS | 1325 | _x_x_mozilla_page_load_details 05:34:30 INFO - PROCESS | 1325 | |i|pagename|runs| 05:34:30 INFO - PROCESS | 1325 | |0;dojo.html;3145.4023735490255;3177.6504584495715;3202.535632333311;3214.3410600826833;3218.6942240487147;5206.921896171561;5224.346956630421;5323.547498462631;5423.4034256231835;5456.672599644019;5293.311869456975;5309.787608495655;5445.809396671108;5496.890062198753;5509.834704958847;4783.473817880031;4818.397700287464;4838.080764654722;4923.82766603169;4950.232713929347;4890.755462226893;4924.138275801738;4970.080535100999;4973;5005.6996580205305;4916.680415772963;4928.778204980785;4970.925436118461;4973.651844370877;4994.450610432849;4766.547178018082;4785.066911952171;4787.640926930494;4873.829415970459;4874.415070191564;4826.686265392738;4833.250846118857;4862.07620552094;4866.58634016109;4872.756362181876;5319;5320.733963301819;5358.276632654581;5371.274877891474;5380.542653874425;5747.683877386742;5752.942470575283;5759.65442073477;5763.106718458645;5806.129080637896;5598.44015598441;5638.661680299154;5694.174344720034;5733;5740.425957404268;5365.436629153961;5367.1680889462195;5369.275147855029;5373.946260537423;5414;5835.299764028344;5875.9118613220835;5903.26209223847;5910.320313163955;5917.556183286185;5487.341519017744;5530.6128571000445;5658.405867383948;5678.5741069419955;5692.402297758758;5817.592768506164;5825.126231065289;5833.4749872511675;5843.94156058434;5861.443162899518;5569.443055694398;5611.859703507445;5613.775448982076;5626.212330273765;5784.71076446176;5631.774729010794;5642.605017648725;5688.971555142198;5701.572382071361;5743.770249189985;4309.698321117492;4315.029118448387;4327.956720432755;4348.543402942709;4351.912961740747;3884.8251828667776;3898.7075969302414;3899.2786334527636;3914.5106861642294;3917.5103112110987;5373.838784836461;5383.892322153535;5391.245225668409;5410.3507579090765;5472.781088756485;5540.002799496049;5600.719964001783;5735.655860648374;5740.885182296331;5744.827655170351;5412.269343638637;5455.018096742545;5473.917891231635;5481.890362192734;5508.173773933942;4815.446223684251;4831.73425461603;4833.782479788418;4843.927341089887;4872;5805.071188609802;5861.560382971379;6065.120557519135;6069.119977603223;6086.056661217518;5697.287839020122;5712.742926568231;5724.685142317213;5786.074228123648;5838.211841401527;6000.339962604197;6001.339852616122;6003.6998150093195;6007.038873780174;6013.248343957005;5540.473655002768;5651.321841378896;5710.628809127394;5716.999525083177;5719.084946408555;5700.800471983461;5832.24180856486;5852.122181672613;5888.676122813115;5898;5638.5771067170945;5652.3217213934595;5652.4064973178865;5653.349864765604;5671.631343962629;5540.63985840919;5544.0575102231915;5687.345955215038;5700.800471983461;5712.942870571241;5577.27495425592;5614.270144881302;5630.380658127521;5637.154426835843;5705.486506214461;5729.9713501432225;5732.369439361583;5736.4550367715;5746.482816546531;5749.683767392667;5537.28015358017;5538.501534861882;5583.916241256303;5591.944080559142;5595.496405323541 05:34:30 INFO - PROCESS | 1325 | |1;ext.html;567.8807450435409;591.0366103251699;595.6396380189985;598.5451057196535;603.5051257968465;31317.68682313176;39167.41248881265;41006.38490422646;41031.384529232084;45525.54474455254;42553.36169957453;42771;42784.78607606966;42825.57174428263;42836.35745463821;8539.530325832076;8779.824403511926;8963.148500892437;8965;8969.461832290084;8550.187732165434;10459.84310235347;10734.946325268364;10736.087432568242;11196.104311655088;10743;10813.621523246688;10814.513346899408;10816.78366432671;10820.242583019191;10780.676579702622;10815.459227038617;10843.240973131884;10853.83719244208;10860.728481787939;10613.787724245472;10639.787204255948;10692.914881598092;10719.62481313158;10720.624778132806;39430.40854387187;39677.60322396768;39711.20577588432;39742.20515589672;39780.801095994626;3997.8200980955926;4057.2494088593703;4116.320807066831;4171.853985110537;4220.810063547147;30136;30559.847200764078;30601;30788.2302942426;30996.690033099607;4045.8381664733374;4166.604172603597;4183.288840897055;4184.330507118877;4209.957900420987;235232.94301170993;237228;238058;239602;239751.80124099442;55783.721081395146;55928;56173.15740263899;56294;56338.718306408206;57810.750279955006;58051.4194858046;58073.41926580764;58093;58154.41845581574;15999.68000640004;16364.83635163657;16375.263113159936;16381.836181638031;16386.180690965404;10590.205734569938;10947.56209751601;12236.082293827998;12625.494980200872;12703.809442858364;14854.405823767145;15583.844161558465;15595.766063509056;15610.219489025503;15623.609409764847;5701.914471282934;5770.480656740913;5804.303483581997;5809.128630705343;5875.6474611523445;877.3770622857843;899.7840518275567;905.9909400906039;909.7725568607848;917.8531434970373;27223.45553088927;29143.562846557317;29388.853055734584;30409.543856842167;30782.692173077983;1008.0826447932493;1018.149844879519;1019.5157300282307;1019.8164330420595;1052.163529993657;13887.58337249884;14630.561083167522;14838.93349192198;14962.70074598502;14999.080329490627;425743;427001;427239;427559;427877;49556;49894.25158622624;49925.50074499281;50037.74981125144;50189.24716129261;1286.3246795432285;1333.4932725564224;1337.7993301004733;1337.9063465557317;1354.9661258468618;25515.872420637777;26558.468830623668;26694;27675.584866227426;27833.443331132858;21057;21223.363299101053;21320.14719411206;21373.14507419748;21394.251201207884;366148;368279;369366;369839;370101;22514.324570262917;23193.536129277658;23972.76027239775;24903.62644560369;25361.57618721449;37467.6253237475;39774;39795.60204397919;39980.400293996194;40728;33512;33694.66305337013;33769.66230337665;34033.48949765805;34471.655283446846;32589.674103258665;33549.49675754817;33572.328553428306;33821.83089084539;34147.65852341445;34012.48981265332;34142;34362.484562732076;34543.13642158966;35354;29668.406631867674;32659.673403266614;32746.508802367505;32906.341873162884;32914;24942.62586061247;25493.617595735708;25599.872000639876;25720.228393148234;25930.870345648153 05:34:30 INFO - PROCESS | 1325 | |2;jquery.html;385.2949103141248;391.1024199462234;396.97419667721596;397.75538044102876;421.7195564949309;14450.566483005501;15380;17272.740908886375;18736.812631873676;18770.530736731587;15085;16177.676446471092;18061.36785212518;18133.4559963201;18363.816361836376;5340.759665815048;5629.577781666371;5662.292213473316;5674.09214525676;5676.120201368773;5439.755211015514;5550.583706222029;5636.549076073904;5644.520215781664;5646.350669672999;5513.696746678931;5551.389347171828;5554.72226388679;5562.137868630369;5616.269884914956;5371.8946535008545;5444.945550544483;5551.028570000254;5552.028395030853;5568.220449137124;4864.708117512961;4878.365812444395;4881.804727810883;4888.951110488885;4891.51084891508;21021.789782102136;21380;21393;21502.677459838116;21590.352289431186;2543.8092143089343;2593.247958092151;2628.829126106816;2659.0826164973055;2708.3499960009653;9467.944727997012;9945.403275803475;10005.099541041342;10179.236557258235;10194.694159175237;1915.7222202780658;1929.199382256362;1961.1567026178736;1976.8418526517844;1985.3349128042123;73067;73959.26040739578;75042.62478687626;75223.99999999945;76958;10373.325733827278;10488.790224195473;10540.31487953296;10695.83956240657;10900.509477073392;14736.115833050051;14756.631084222765;14784.704305913821;14794.926025369805;14802.925985370004;6917.9308206917285;7330.120385553768;7353.154387245535;7805.258500442449;8121.147279535682;7571.886421703679;7575.6969721210535;7644.732434364771;7791.922080779233;7801.921980780233;4299.505556860939;4352.303631418958;4369.43197384338;4409.735415875058;4440.955590444119;1919.5489060070872;2009.939701808948;2014.113789932425;2023.1401654296865;2024.0689282929723;829.3779665251062;862.8144948835904;867.7917299847991;870.9869351959726;887.8623813308948;16711;16803;16945.745813812802;17185.570360741083;18006.369777057742;941.2846236859899;956.168133723651;956.3353469338742;959.7792507723262;961.3751061809879;9584.329096963145;9595;9687.128158465772;9728.221742260603;9808.31341806081;392168;406231.9376806253;436195;438208;438509;77097;80518.98702532366;82678.58660706779;83060;83456.58271708603;1055.5672174408455;1057.2335057083706;1078.5308390850005;1078.5739632845139;1087.260662749338;15186.10173728284;15447.382104715685;15690.843091568939;15878.60303492422;16060.518184454486;38632;39221.16397908198;39739.40390894197;40603.187936240516;41616.58383416127;39308.80345598254;40812.79593602013;48549.757251213516;54195.458045420615;54512.18231726448;8167.224113709188;8298.377621678519;8396.748097557083;8501.872471912802;8673.176048275403;10400.635977740743;12383.62849114528;12474.688132796753;12785.424655890321;13097.541586044443;10276.743081423025;10836.891631083588;10839.403832789272;10948.452577370941;11013;10512.737181570215;10628.309159904582;10668.679939601825;10673.786524269626;10830.67507974762;10812.891871081189;10889.455527223448;10950.288231265262;11014.44927753625;11053.613123540637;8221.588920554068;8223.9588802058;8369.49783013021;8422.157784221528;8440.915590844013;8699.391042626956;10357.378557286587;10457.372557646566;10545.630902918361;10958.232923695265 05:34:30 INFO - PROCESS | 1325 | |3;mootools.html;1342.74487847309;1379.6412932637515;1386.3622733542563;1394.2958805803069;1396.6508372906774;7912.881306780396;8411.579421028948;8418;8419.284360829322;8429.030661473924;7423.4061275098;7634.656440460179;7635.809104772384;7849.60751962401;8275.54484503352;2141.7858214178573;2307.6884620576175;2362.303120579433;2377.2274010946426;2391.294568102414;2303.1363238785457;2355.9764402356013;2356.0340260493203;2367.4318163640687;2370.3363058343693;2041.1019151573335;2309.7805708457668;2362.5511152880986;2372.359462945004;2375.334906226251;2039.8674086184424;2245.65192395179;2298.793108620232;2304.7119110111234;2332.3935776698013;2246.7416247131628;2263;2264.1396269417514;2267.977320226793;2268.9092436302526;8122.3908206884125;8192.918070819274;8306.377021723394;8409.537475438849;8463.576821158978;5117.897642047175;5297.364316282033;5324.068288049614;5329.120695085307;5332.466753324637;3464.168599536093;3499.125218695326;3581.2837432513343;3592.46113083037;3604.963950360489;1417.758980973237;1423.2670174859954;1430.3491911180492;1483.9777403338958;1502.676924461246;10713.946430267877;10851.565937362497;10876.347419154876;11224.326540407683;11403.258788178742;4475.731456112644;4536.77316134192;4629.606483448877;4636.327732478771;4651;9040.231580315747;9048.502332371783;9052.909470905339;9061.728148155566;9062.773430664287;5202.7918883245;5227.085260079471;5227.60792940531;5229.058769421541;5279.366476022902;4333.9133217335475;4788.04239152171;4816.54242846929;4910.90178196434;4930.852074437773;3395.6264810870784;3599.640035996432;3680.8895733128047;3691.446283057563;3711.7216208783907;1171.3850228629867;1215.598852378713;1223.174357308813;1224.6020043485903;1226.9325187114794;880.9735707928772;896.8341753609768;900.1853322742928;900.4777229207044;915.6402741928231;5302.443243459458;5397.73011349431;5452.836414907559;5509.752061157257;5600.467955544217;929.2194556572376;932.911373419524;950.0879156010308;956.7177682583655;978.320067553058;3516.49010893419;3598.8560457581925;3637.1452708613456;3682.4476328550395;3841.4237864320576;9833.557489912828;10202.33684810485;10210.183185345155;10232.693019209435;10237.385756854612;10132.54403551842;10142.137918276881;10149.137323327599;10158.492075396201;10171.69484915439;7270.16393114788;7570;7578.4316176287;7772.261635144652;7789.1042530108625;5440.428754980829;5630.831075067755;5688.971555142198;5709.172170035405;5800.332961709458;9120.407173533698;9144.3598948073;9197.586108624993;9251.121143491358;9252.722418327461;9349.57926893305;9418.434893906388;9423.151916327564;9440.669576564787;9515.381500202464;7488.326050655467;7537.510061845963;7560.470767046254;7628.42786791004;7631.847363052597;7874.566898820391;8068.798280043045;8070.757877263692;8075.798105047421;8091.029076510857;7124.145102587515;7242.818929526804;7244.637768111679;7284.3808276294985;7397.112346518237;6893.0694356261265;6988.161420629557;7036.5074444790425;7070.505064645426;7112.03987461686;6437.742490300523;6442.645654489048;6565.113709649137;6576.506761992965;6589.209294884645;4921.360223170965;5013.498650135103;5048.873778155576;5091.694498330112;5101.183810590288;4709.387779588632;4798.4001999750035;4901.607871370211;4985.451600323889;5000.099982003274 05:34:30 INFO - PROCESS | 1325 | |4;prototype.html;373.5890520427528;381.6107570278314;383.95584507781604;385.86687592780487;389.1244699426293;5182.818601348954;5191.974040129803;5220.6084543659235;5247.763850626721;5252.159654455288;5098.796048158069;5148.613853960949;5154.664946778456;5160.432352441228;5174.301469301643;3630.52803135592;3884.514435695538;4036.3138266494766;4113.753174809513;4141.7100802943805;4077.5922407759285;4080.4491393661774;4107.650849677766;4108.322126849066;4115.36211887158;4086.2236175126777;4102.651274641644;4109.630133288004;4109.958900410988;4128.298189307825;3948.2430318508277;4009.859654912064;4135.172965406916;4140.937885931713;4148.43996060531;3653.451982202664;3711.2206436648335;3711.997760604622;3724.366857634209;3744.3634582121103;5057.064443078006;5064.316317297192;5077.771500282496;5085;5110.207917772751;4604.194266003436;4801.687890287155;4801.831935882262;4817.229243321087;4819.879503012417;5370.2750128732905;5381.165919282517;5398.676079435247;5431.782728690848;5436.157395603688;4085.4057673377324;4089.734167279117;4094.1811637672586;4100.261952848486;4101.651359634435;5336.199570064483;5424.240606315079;5552.307715369275;5581.376752926119;5642.548596112301;4129.607687269765;4157.106222162189;4161.521425036099;4178.310578754472;4183.288840897055;4222.4933008038615;4232.978835105866;4251.681123915719;4255.595718406808;4264.123785534355;4563.406757121553;4728.172569800271;4728.290756386569;4752.500987396343;4757.26262429324;5466.742649190708;5472.370677372073;5472.962505809314;5500.477454641803;5509.944900550943;1427.421894132878;1428.5428662827796;1449.3767679897744;1478.7652310320786;1481.4814814814729;992.369845148336;1047.7904419116055;1052.147760314148;1055.472263868066;1062.4475272858072;5075.289459475676;5076.18780995039;5081.822136225288;5087.186050231945;5098.388193416814;5359.785608575691;5363.704996225245;5381.838544843661;5385.892282154335;5386.380566234933;4849.75751212445;5011.469208049912;5049.722265275372;5058.266551350107;5097.745112744348;2843.189690938114;2847.4732174547776;2852.6576810782426;2858.19970408286;2878.596996420503;5584.324702589207;5652.576056795757;5658.547316214693;5679.649007085839;5705;5372.221027951004;5421.620486565903;5491.423400542965;5495.542737283676;5513.338399392019;5614.820887613565;5615.382307946123;5618.578606604521;5618.831435056954;5621.437856214346;5317.494837990384;5325.627206095536;5340.706261155518;5358.267283685271;5373.811916582901;5542.63972841764;5547;5549.778008879761;5558.416366281483;5571.275734154534;5551.444855514416;5610.857919565056;5698.601097923106;5758.539316854558;5786.566007549366;3951.427043078795;3953.920921581495;4002.5597184310286;4003.7597744135446;4005.118873847749;5385.784568617368;5643.407442218508;5660.9150862737915;5661.603687741818;5681.801136960186;5448.782048718007;5469.9179512306555;5485.424030476902;5489.670619762827;5495.917561236504;5462.333988133658;5469.507744303032;5521.806736764194;5529.806456773994;5550.139728342114;5239.921401179062;5451.046066938349;5455.727213639223;5482.5613950884635;5495.6153069284765;5388.886669596095;5421.807202415463;5473.890522189454;5487.463510217146;5487.807926722546;5319.696674314818;5369.4093649697725;5380.273663055437;5398.433164517669;5403.378611459732 05:34:30 INFO - PROCESS | 1325 | |5;yui.html;740.7592532426962;746.537146968879;778.2489897249153;826.3678286111124;840.2563731097979;15950.202489875503;16772.07753573553;16852.07313597753;16926.40757573485;16938;14937.775933360981;15123.546293611182;15129.167895765728;15135.697286054272;15142.394304227817;690.8756423843706;712.6045045000022;795.4193438789658;816.6121092481084;818.0633175014605;822.7326119011345;823.069930977997;824.5341382119113;825.0965193113561;825.162460102996;872.31959071924;876.837785009772;879.7096958003875;879.885614870066;881.8280435315116;726.2773540327381;745.4222977192665;853.8591132463198;878.4685265414428;880.3045593980779;637.9362063793584;638.4573112854083;639.2169592249504;639.25526761323;639.7760783725705;14853.628659283497;15676.451324203597;15681.372745090182;15694.76457853133;15714.528564143095;14044.508442204473;14243.85756142446;14447.711045779131;14627.89433525661;14652.633684157874;13139.671508212276;13233.956920325038;13329.333533323392;13343.666408339772;13640.386182621805;3731.9067023324365;3805.714571407128;4437.334399840018;4537.659675524349;4573.771311434415;74206;76390;76735.23264767337;76979;78442.60778696126;11372.601958931398;11389.259698119597;11461.942690286494;11485.827712584318;11518.827217591743;19863.205471780966;19903.502412439517;19956.3015294464;20016.59966800685;20126.39620811378;7923.484973476706;8001.359891208689;8031.839363212703;8035.879461808077;8232.423730338818;4360.367746676714;4423.469183697976;4479.350494178391;4538.410006699108;4621.2837010263465;3333.0667413124365;3413.0102270341818;3516.226430185355;3517.753757236969;3534.1164708822794;620.9223847019123;622.8290813271112;627.645380360095;633.6894921488438;639.5714871036324;716.401804493254;726.8982342471952;733.5818583407513;752.428154602495;759.848829024029;9935.950320248352;10018.198544116452;10066.949665251626;10093.848592271122;10122.392656440637;638.6359774928246;725.1515726599786;736.4292673178329;752.8306131120454;765.5865832450526;4529.886752831206;4782.426108866958;4846;4852.8544143675745;4890.339804126468;70432.2956770436;70785;71374.2862571378;73693.26306736971;73929;60286.69856650777;60744;60816;61478.38521614816;61542;55941.44058559362;57793.50961658636;58322.4167758317;58410;58877.70561147167;9521.333506654468;9773.218142548722;9938.602455901682;9964.651237206663;9983.101520863158;38542.80728596451;39643.60356396399;40502.79748601238;40771;42002.279635258215;45788.771056144506;50374.49625503698;50628.74685626695;50894.491055088976;50947.235791462415;12368.690782730504;12575;12711;12724.300163491096;13035.608931732064;13900.16599004063;14068.8896665501;14146.363413646617;14544.127352358892;15392;13151.473941042252;13247.536336228186;13553.72892542124;13855.515056972958;14236.857631423552;12988.805167922677;13084.738305233652;13359.1316564423;14240.501582444978;14319.498817541753;13104;13395.330233488481;13600.795988060385;13723.1079979801;13967.441302347792;11576.652700419001;11850.348230847041;12180.878191217975;12249.571265005681;12275.263484190977;11068.723281918017;11262.38056906878;11286.153538484483;11297.378644174649;11364.54541818351 05:34:30 INFO - PROCESS | 1325 | __end_tp_report 05:34:30 INFO - PROCESS | 1325 | __start_cc_report 05:34:30 INFO - PROCESS | 1325 | _x_x_mozilla_cycle_collect,1124 05:34:30 INFO - PROCESS | 1325 | __end_cc_report 05:34:30 INFO - PROCESS | 1325 | __startTimestamp1483104870734__endTimestamp 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | ------- Summary: start ------- 05:34:30 INFO - PROCESS | 1325 | Number of tests: 6 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | [#0] dojo.html Cycles:165 Average:5342.14 Median:5544.06 stddev:609.77 (11.0%) stddev-sans-first:586.78 05:34:30 INFO - PROCESS | 1325 | Values: 3145.4 3177.7 3202.5 3214.3 3218.7 5206.9 5224.3 5323.5 5423.4 5456.7 5293.3 5309.8 5445.8 5496.9 5509.8 4783.5 4818.4 4838.1 4923.8 4950.2 4890.8 4924.1 4970.1 4973.0 5005.7 4916.7 4928.8 4970.9 4973.7 4994.5 4766.5 4785.1 4787.6 4873.8 4874.4 4826.7 4833.3 4862.1 4866.6 4872.8 5319.0 5320.7 5358.3 5371.3 5380.5 5747.7 5752.9 5759.7 5763.1 5806.1 5598.4 5638.7 5694.2 5733.0 5740.4 5365.4 5367.2 5369.3 5373.9 5414.0 5835.3 5875.9 5903.3 5910.3 5917.6 5487.3 5530.6 5658.4 5678.6 5692.4 5817.6 5825.1 5833.5 5843.9 5861.4 5569.4 5611.9 5613.8 5626.2 5784.7 5631.8 5642.6 5689.0 5701.6 5743.8 4309.7 4315.0 4328.0 4348.5 4351.9 3884.8 3898.7 3899.3 3914.5 3917.5 5373.8 5383.9 5391.2 5410.4 5472.8 5540.0 5600.7 5735.7 5740.9 5744.8 5412.3 5455.0 5473.9 5481.9 5508.2 4815.4 4831.7 4833.8 4843.9 4872.0 5805.1 5861.6 6065.1 6069.1 6086.1 5697.3 5712.7 5724.7 5786.1 5838.2 6000.3 6001.3 6003.7 6007.0 6013.2 5540.5 5651.3 5710.6 5717.0 5719.1 5700.8 5832.2 5852.1 5888.7 5898.0 5638.6 5652.3 5652.4 5653.3 5671.6 5540.6 5544.1 5687.3 5700.8 5712.9 5577.3 5614.3 5630.4 5637.2 5705.5 5730.0 5732.4 5736.5 5746.5 5749.7 5537.3 5538.5 5583.9 5591.9 5595.5 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | [#1] ext.html Cycles:180 Average:49412.26 Median:33213.00 stddev:93482.17 (281.5%) stddev-sans-first:93672.48 05:34:30 INFO - PROCESS | 1325 | Values: 567.9 591.0 595.6 598.5 603.5 31317.7 39167.4 41006.4 41031.4 45525.5 42553.4 42771.0 42784.8 42825.6 42836.4 8539.5 8779.8 8963.1 8965.0 8969.5 8550.2 10459.8 10734.9 10736.1 11196.1 10743.0 10813.6 10814.5 10816.8 10820.2 10780.7 10815.5 10843.2 10853.8 10860.7 10613.8 10639.8 10692.9 10719.6 10720.6 39430.4 39677.6 39711.2 39742.2 39780.8 3997.8 4057.2 4116.3 4171.9 4220.8 30136.0 30559.8 30601.0 30788.2 30996.7 4045.8 4166.6 4183.3 4184.3 4210.0 235232.9 237228.0 238058.0 239602.0 239751.8 55783.7 55928.0 56173.2 56294.0 56338.7 57810.8 58051.4 58073.4 58093.0 58154.4 15999.7 16364.8 16375.3 16381.8 16386.2 10590.2 10947.6 12236.1 12625.5 12703.8 14854.4 15583.8 15595.8 15610.2 15623.6 5701.9 5770.5 5804.3 5809.1 5875.6 877.4 899.8 906.0 909.8 917.9 27223.5 29143.6 29388.9 30409.5 30782.7 1008.1 1018.1 1019.5 1019.8 1052.2 13887.6 14630.6 14838.9 14962.7 14999.1 425743.0 427001.0 427239.0 427559.0 427877.0 49556.0 49894.3 49925.5 50037.7 50189.2 1286.3 1333.5 1337.8 1337.9 1355.0 25515.9 26558.5 26694.0 27675.6 27833.4 21057.0 21223.4 21320.1 21373.1 21394.3 366148.0 368279.0 369366.0 369839.0 370101.0 22514.3 23193.5 23972.8 24903.6 25361.6 37467.6 39774.0 39795.6 39980.4 40728.0 33512.0 33694.7 33769.7 34033.5 34471.7 32589.7 33549.5 33572.3 33821.8 34147.7 34012.5 34142.0 34362.5 34543.1 35354.0 29668.4 32659.7 32746.5 32906.3 32914.0 24942.6 25493.6 25599.9 25720.2 25930.9 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | [#2] jquery.html Cycles:180 Average:25757.83 Median:40171.30 stddev:69729.92 (173.6%) stddev-sans-first:69899.51 05:34:30 INFO - PROCESS | 1325 | Values: 385.3 391.1 397.0 397.8 421.7 14450.6 15380.0 17272.7 18736.8 18770.5 15085.0 16177.7 18061.4 18133.5 18363.8 5340.8 5629.6 5662.3 5674.1 5676.1 5439.8 5550.6 5636.5 5644.5 5646.4 5513.7 5551.4 5554.7 5562.1 5616.3 5371.9 5444.9 5551.0 5552.0 5568.2 4864.7 4878.4 4881.8 4889.0 4891.5 21021.8 21380.0 21393.0 21502.7 21590.4 2543.8 2593.2 2628.8 2659.1 2708.3 9467.9 9945.4 10005.1 10179.2 10194.7 1915.7 1929.2 1961.2 1976.8 1985.3 73067.0 73959.3 75042.6 75224.0 76958.0 10373.3 10488.8 10540.3 10695.8 10900.5 14736.1 14756.6 14784.7 14794.9 14802.9 6917.9 7330.1 7353.2 7805.3 8121.1 7571.9 7575.7 7644.7 7791.9 7801.9 4299.5 4352.3 4369.4 4409.7 4441.0 1919.5 2009.9 2014.1 2023.1 2024.1 829.4 862.8 867.8 871.0 887.9 16711.0 16803.0 16945.7 17185.6 18006.4 941.3 956.2 956.3 959.8 961.4 9584.3 9595.0 9687.1 9728.2 9808.3 392168.0 406231.9 436195.0 438208.0 438509.0 77097.0 80519.0 82678.6 83060.0 83456.6 1055.6 1057.2 1078.5 1078.6 1087.3 15186.1 15447.4 15690.8 15878.6 16060.5 38632.0 39221.2 39739.4 40603.2 41616.6 39308.8 40812.8 48549.8 54195.5 54512.2 8167.2 8298.4 8396.7 8501.9 8673.2 10400.6 12383.6 12474.7 12785.4 13097.5 10276.7 10836.9 10839.4 10948.5 11013.0 10512.7 10628.3 10668.7 10673.8 10830.7 10812.9 10889.5 10950.3 11014.4 11053.6 8221.6 8224.0 8369.5 8422.2 8440.9 8699.4 10357.4 10457.4 10545.6 10958.2 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | [#3] mootools.html Cycles:180 Average:5456.12 Median:5214.94 stddev:2971.50 (57.0%) stddev-sans-first:2963.76 05:34:30 INFO - PROCESS | 1325 | Values: 1342.7 1379.6 1386.4 1394.3 1396.7 7912.9 8411.6 8418.0 8419.3 8429.0 7423.4 7634.7 7635.8 7849.6 8275.5 2141.8 2307.7 2362.3 2377.2 2391.3 2303.1 2356.0 2356.0 2367.4 2370.3 2041.1 2309.8 2362.6 2372.4 2375.3 2039.9 2245.7 2298.8 2304.7 2332.4 2246.7 2263.0 2264.1 2268.0 2268.9 8122.4 8192.9 8306.4 8409.5 8463.6 5117.9 5297.4 5324.1 5329.1 5332.5 3464.2 3499.1 3581.3 3592.5 3605.0 1417.8 1423.3 1430.3 1484.0 1502.7 10713.9 10851.6 10876.3 11224.3 11403.3 4475.7 4536.8 4629.6 4636.3 4651.0 9040.2 9048.5 9052.9 9061.7 9062.8 5202.8 5227.1 5227.6 5229.1 5279.4 4333.9 4788.0 4816.5 4910.9 4930.9 3395.6 3599.6 3680.9 3691.4 3711.7 1171.4 1215.6 1223.2 1224.6 1226.9 881.0 896.8 900.2 900.5 915.6 5302.4 5397.7 5452.8 5509.8 5600.5 929.2 932.9 950.1 956.7 978.3 3516.5 3598.9 3637.1 3682.4 3841.4 9833.6 10202.3 10210.2 10232.7 10237.4 10132.5 10142.1 10149.1 10158.5 10171.7 7270.2 7570.0 7578.4 7772.3 7789.1 5440.4 5630.8 5689.0 5709.2 5800.3 9120.4 9144.4 9197.6 9251.1 9252.7 9349.6 9418.4 9423.2 9440.7 9515.4 7488.3 7537.5 7560.5 7628.4 7631.8 7874.6 8068.8 8070.8 8075.8 8091.0 7124.1 7242.8 7244.6 7284.4 7397.1 6893.1 6988.2 7036.5 7070.5 7112.0 6437.7 6442.6 6565.1 6576.5 6589.2 4921.4 5013.5 5048.9 5091.7 5101.2 4709.4 4798.4 4901.6 4985.5 5000.1 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | [#4] prototype.html Cycles:180 Average:4586.47 Median:5151.64 stddev:1302.83 (25.3%) stddev-sans-first:1267.54 05:34:30 INFO - PROCESS | 1325 | Values: 373.6 381.6 384.0 385.9 389.1 5182.8 5192.0 5220.6 5247.8 5252.2 5098.8 5148.6 5154.7 5160.4 5174.3 3630.5 3884.5 4036.3 4113.8 4141.7 4077.6 4080.4 4107.7 4108.3 4115.4 4086.2 4102.7 4109.6 4110.0 4128.3 3948.2 4009.9 4135.2 4140.9 4148.4 3653.5 3711.2 3712.0 3724.4 3744.4 5057.1 5064.3 5077.8 5085.0 5110.2 4604.2 4801.7 4801.8 4817.2 4819.9 5370.3 5381.2 5398.7 5431.8 5436.2 4085.4 4089.7 4094.2 4100.3 4101.7 5336.2 5424.2 5552.3 5581.4 5642.5 4129.6 4157.1 4161.5 4178.3 4183.3 4222.5 4233.0 4251.7 4255.6 4264.1 4563.4 4728.2 4728.3 4752.5 4757.3 5466.7 5472.4 5473.0 5500.5 5509.9 1427.4 1428.5 1449.4 1478.8 1481.5 992.4 1047.8 1052.1 1055.5 1062.4 5075.3 5076.2 5081.8 5087.2 5098.4 5359.8 5363.7 5381.8 5385.9 5386.4 4849.8 5011.5 5049.7 5058.3 5097.7 2843.2 2847.5 2852.7 2858.2 2878.6 5584.3 5652.6 5658.5 5679.6 5705.0 5372.2 5421.6 5491.4 5495.5 5513.3 5614.8 5615.4 5618.6 5618.8 5621.4 5317.5 5325.6 5340.7 5358.3 5373.8 5542.6 5547.0 5549.8 5558.4 5571.3 5551.4 5610.9 5698.6 5758.5 5786.6 3951.4 3953.9 4002.6 4003.8 4005.1 5385.8 5643.4 5660.9 5661.6 5681.8 5448.8 5469.9 5485.4 5489.7 5495.9 5462.3 5469.5 5521.8 5529.8 5550.1 5239.9 5451.0 5455.7 5482.6 5495.6 5388.9 5421.8 5473.9 5487.5 5487.8 5319.7 5369.4 5380.3 5398.4 5403.4 05:34:30 INFO - PROCESS | 1325 | 05:34:30 INFO - PROCESS | 1325 | [#5] yui.html Cycles:180 Average:16853.80 Median:4533.77 stddev:20602.66 (454.4%) stddev-sans-first:20624.92 05:34:30 INFO - PROCESS | 1325 | Values: 740.8 746.5 778.2 826.4 840.3 15950.2 16772.1 16852.1 16926.4 16938.0 14937.8 15123.5 15129.2 15135.7 15142.4 690.9 712.6 795.4 816.6 818.1 822.7 823.1 824.5 825.1 825.2 872.3 876.8 879.7 879.9 881.8 726.3 745.4 853.9 878.5 880.3 637.9 638.5 639.2 639.3 639.8 14853.6 15676.5 15681.4 15694.8 15714.5 14044.5 14243.9 14447.7 14627.9 14652.6 13139.7 13234.0 13329.3 13343.7 13640.4 3731.9 3805.7 4437.3 4537.7 4573.8 74206.0 76390.0 76735.2 76979.0 78442.6 11372.6 11389.3 11461.9 11485.8 11518.8 19863.2 19903.5 19956.3 20016.6 20126.4 7923.5 8001.4 8031.8 8035.9 8232.4 4360.4 4423.5 4479.4 4538.4 4621.3 3333.1 3413.0 3516.2 3517.8 3534.1 620.9 622.8 627.6 633.7 639.6 716.4 726.9 733.6 752.4 759.8 9936.0 10018.2 10066.9 10093.8 10122.4 638.6 725.2 736.4 752.8 765.6 4529.9 4782.4 4846.0 4852.9 4890.3 70432.3 70785.0 71374.3 73693.3 73929.0 60286.7 60744.0 60816.0 61478.4 61542.0 55941.4 57793.5 58322.4 58410.0 58877.7 9521.3 9773.2 9938.6 9964.7 9983.1 38542.8 39643.6 40502.8 40771.0 42002.3 45788.8 50374.5 50628.7 50894.5 50947.2 12368.7 12575.0 12711.0 12724.3 13035.6 13900.2 14068.9 14146.4 14544.1 15392.0 13151.5 13247.5 13553.7 13855.5 14236.9 12988.8 13084.7 13359.1 14240.5 14319.5 13104.0 13395.3 13600.8 13723.1 13967.4 11576.7 11850.3 12180.9 12249.6 12275.3 11068.7 11262.4 11286.2 11297.4 11364.5 05:34:30 INFO - PROCESS | 1325 | -------- Summary: end -------- 05:34:30 INFO - PROCESS | 1325 | 05:34:31 INFO - TEST-INFO | 1325: exit 0 05:34:31 INFO - TEST-OK | dromaeo_css | took 1116259ms 05:34:31 INFO - TEST-START | kraken 05:34:31 INFO - operating with platform_type : linux_ 05:34:31 INFO - Initialising browser for kraken test... 05:34:31 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptG20yt/profile http://localhost:56561/getInfo.html) 05:34:31 INFO - PROCESS | 1637 | 1483104871995 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 05:34:35 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 05:34:35 INFO - PROCESS | 1637 | colorDepth:24 05:34:35 INFO - PROCESS | 1637 | Browser inner width/height: 1024/697 05:34:35 INFO - PROCESS | 1637 | __metrics 05:34:35 INFO - PROCESS | 1637 | 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" 05:34:35 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:34:36 INFO - PROCESS | 1637 | console.error: PushService: 05:34:36 INFO - PROCESS | 1637 | stateChangeProcessEnqueue: Error transitioning state 05:34:36 INFO - PROCESS | 1637 | UnknownError 05:34:36 INFO - TEST-INFO | 1637: exit 0 05:34:36 INFO - Browser initialized. 05:34:36 INFO - Running cycle 1/1 for kraken test... 05:34:36 INFO - TEST-INFO | started process 1724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptG20yt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:34:48 INFO - PROCESS | 1724 | RSS: Main: 146890752 05:34:48 INFO - PROCESS | 1724 | 05:34:51 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56561/tests/kraken/driver.html?testName=audio-beat-detection) 05:34:52 INFO - PROCESS | 1724 | RSS: Main: 201670656 05:34:52 INFO - PROCESS | 1724 | 05:34:54 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56561/tests/kraken/driver.html?testName=audio-dft) 05:34:55 INFO - PROCESS | 1724 | RSS: Main: 181039104 05:34:55 INFO - PROCESS | 1724 | 05:34:58 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56561/tests/kraken/driver.html?testName=audio-fft) 05:34:58 INFO - PROCESS | 1724 | RSS: Main: 262713344 05:34:58 INFO - PROCESS | 1724 | 05:35:01 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56561/tests/kraken/driver.html?testName=audio-oscillator) 05:35:01 INFO - PROCESS | 1724 | RSS: Main: 173461504 05:35:01 INFO - PROCESS | 1724 | 05:35:03 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56561/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:35:04 INFO - PROCESS | 1724 | RSS: Main: 177819648 05:35:04 INFO - PROCESS | 1724 | 05:35:07 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56561/tests/kraken/driver.html?testName=imaging-darkroom) 05:35:07 INFO - PROCESS | 1724 | RSS: Main: 217272320 05:35:07 INFO - PROCESS | 1724 | 05:35:11 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56561/tests/kraken/driver.html?testName=imaging-desaturate) 05:35:11 INFO - PROCESS | 1724 | RSS: Main: 217747456 05:35:11 INFO - PROCESS | 1724 | 05:35:14 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56561/tests/kraken/driver.html?testName=json-parse-financial) 05:35:15 INFO - PROCESS | 1724 | RSS: Main: 215531520 05:35:15 INFO - PROCESS | 1724 | 05:35:16 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56561/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:35:17 INFO - PROCESS | 1724 | RSS: Main: 177463296 05:35:17 INFO - PROCESS | 1724 | 05:35:19 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:35:19 INFO - PROCESS | 1724 | RSS: Main: 185008128 05:35:19 INFO - PROCESS | 1724 | 05:35:21 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:35:22 INFO - PROCESS | 1724 | RSS: Main: 193789952 05:35:22 INFO - PROCESS | 1724 | 05:35:25 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:35:25 INFO - PROCESS | 1724 | RSS: Main: 202260480 05:35:25 INFO - PROCESS | 1724 | 05:35:28 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:35:29 INFO - PROCESS | 1724 | RSS: Main: 199573504 05:35:29 INFO - PROCESS | 1724 | 05:35:30 INFO - PROCESS | 1724 | Cycle 1(1): loaded http://localhost:56561/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56561/tests/kraken/driver.html?testName=ai-astar) 05:35:31 INFO - PROCESS | 1724 | RSS: Main: 251506688 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | __start_tp_report 05:35:31 INFO - PROCESS | 1724 | _x_x_mozilla_page_load 05:35:31 INFO - PROCESS | 1724 | _x_x_mozilla_page_load_details 05:35:31 INFO - PROCESS | 1724 | |i|pagename|runs| 05:35:31 INFO - PROCESS | 1724 | |0;ai-astar;109;112;110;111;111;112;111;110;112;111 05:35:31 INFO - PROCESS | 1724 | |1;audio-beat-detection;151;119;120;167;122;123;126;119;140;120 05:35:31 INFO - PROCESS | 1724 | |2;audio-dft;161;163;162;162;162;159;162;162;161;160 05:35:31 INFO - PROCESS | 1724 | |3;audio-fft;75;76;76;114;76;77;76;76;100;76 05:35:31 INFO - PROCESS | 1724 | |4;audio-oscillator;84;83;107;84;83;82;99;83;83;107 05:35:31 INFO - PROCESS | 1724 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;116;117;117 05:35:31 INFO - PROCESS | 1724 | |6;imaging-darkroom;147;146;147;148;148;148;148;146;146;147 05:35:31 INFO - PROCESS | 1724 | |7;imaging-desaturate;95;96;95;95;96;95;95;96;96;95 05:35:31 INFO - PROCESS | 1724 | |8;json-parse-financial;51;50;51;51;53;51;52;51;54;52 05:35:31 INFO - PROCESS | 1724 | |9;json-stringify-tinderbox;68;68;69;68;68;67;71;68;68;68 05:35:31 INFO - PROCESS | 1724 | |10;stanford-crypto-aes;80;81;85;82;81;81;81;81;81;81 05:35:31 INFO - PROCESS | 1724 | |11;stanford-crypto-ccm;127;125;133;127;126;123;125;132;175;134 05:35:31 INFO - PROCESS | 1724 | |12;stanford-crypto-pbkdf2;166;171;166;166;168;167;166;166;169;164 05:35:31 INFO - PROCESS | 1724 | |13;stanford-crypto-sha256-iterative;63;65;62;63;62;61;64;62;61;61 05:35:31 INFO - PROCESS | 1724 | __end_tp_report 05:35:31 INFO - PROCESS | 1724 | __start_cc_report 05:35:31 INFO - PROCESS | 1724 | _x_x_mozilla_cycle_collect,1585 05:35:31 INFO - PROCESS | 1724 | __end_cc_report 05:35:31 INFO - PROCESS | 1724 | __startTimestamp1483104931045__endTimestamp 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | ------- Summary: start ------- 05:35:31 INFO - PROCESS | 1724 | Number of tests: 14 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:0.99 (0.9%) stddev-sans-first:0.78 05:35:31 INFO - PROCESS | 1724 | Values: 109.0 112.0 110.0 111.0 111.0 112.0 111.0 110.0 112.0 111.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:124.50 stddev:16.59 (13.3%) stddev-sans-first:15.88 05:35:31 INFO - PROCESS | 1724 | Values: 151.0 119.0 120.0 167.0 122.0 123.0 126.0 119.0 140.0 120.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.24 05:35:31 INFO - PROCESS | 1724 | Values: 161.0 163.0 162.0 162.0 162.0 159.0 162.0 162.0 161.0 160.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.00 stddev:13.49 (17.7%) stddev-sans-first:14.05 05:35:31 INFO - PROCESS | 1724 | Values: 75.0 76.0 76.0 114.0 76.0 77.0 76.0 76.0 100.0 76.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:83.00 stddev:10.48 (12.6%) stddev-sans-first:10.93 05:35:31 INFO - PROCESS | 1724 | Values: 84.0 83.0 107.0 84.0 83.0 82.0 99.0 83.0 83.0 107.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 05:35:31 INFO - PROCESS | 1724 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 116.0 117.0 117.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.50 stddev:0.88 (0.6%) stddev-sans-first:0.93 05:35:31 INFO - PROCESS | 1724 | Values: 147.0 146.0 147.0 148.0 148.0 148.0 148.0 146.0 146.0 147.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:35:31 INFO - PROCESS | 1724 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.50 stddev:1.17 (2.3%) stddev-sans-first:1.22 05:35:31 INFO - PROCESS | 1724 | Values: 51.0 50.0 51.0 51.0 53.0 51.0 52.0 51.0 54.0 52.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 05:35:31 INFO - PROCESS | 1724 | Values: 68.0 68.0 69.0 68.0 68.0 67.0 71.0 68.0 68.0 68.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:1.35 (1.7%) stddev-sans-first:1.33 05:35:31 INFO - PROCESS | 1724 | Values: 80.0 81.0 85.0 82.0 81.0 81.0 81.0 81.0 81.0 81.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#11] stanford-crypto-ccm Cycles:10 Average:132.70 Median:129.50 stddev:15.33 (11.8%) stddev-sans-first:16.12 05:35:31 INFO - PROCESS | 1724 | Values: 127.0 125.0 133.0 127.0 126.0 123.0 125.0 132.0 175.0 134.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:166.50 stddev:1.97 (1.2%) stddev-sans-first:2.06 05:35:31 INFO - PROCESS | 1724 | Values: 166.0 171.0 166.0 166.0 168.0 167.0 166.0 166.0 169.0 164.0 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - PROCESS | 1724 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.50 stddev:1.35 (2.2%) stddev-sans-first:1.41 05:35:31 INFO - PROCESS | 1724 | Values: 63.0 65.0 62.0 63.0 62.0 61.0 64.0 62.0 61.0 61.0 05:35:31 INFO - PROCESS | 1724 | -------- Summary: end -------- 05:35:31 INFO - PROCESS | 1724 | 05:35:31 INFO - TEST-INFO | 1724: exit 0 05:35:32 INFO - TEST-OK | kraken | took 60492ms 05:35:32 INFO - SUITE-END | took 1176s 05:35:32 INFO - Completed test suite (00:19:36) 05:35:32 INFO - javascript benchmark 05:35:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.4023735490255, 3177.6504584495715, 3202.535632333311, 3214.3410600826833, 3218.6942240487147, 5206.921896171561, 5224.346956630421, 5323.547498462631, 5423.4034256231835, 5456.672599644019, 5293.311869456975, 5309.787608495655, 5445.809396671108, 5496.890062198753, 5509.834704958847, 4783.473817880031, 4818.397700287464, 4838.080764654722, 4923.82766603169, 4950.232713929347, 4890.755462226893, 4924.138275801738, 4970.080535100999, 4973.0, 5005.6996580205305, 4916.680415772963, 4928.778204980785, 4970.925436118461, 4973.651844370877, 4994.450610432849, 4766.547178018082, 4785.066911952171, 4787.640926930494, 4873.829415970459, 4874.415070191564, 4826.686265392738, 4833.250846118857, 4862.07620552094, 4866.58634016109, 4872.756362181876, 5319.0, 5320.733963301819, 5358.276632654581, 5371.274877891474, 5380.542653874425, 5747.683877386742, 5752.942470575283, 5759.65442073477, 5763.106718458645, 5806.129080637896, 5598.44015598441, 5638.661680299154, 5694.174344720034, 5733.0, 5740.425957404268, 5365.436629153961, 5367.1680889462195, 5369.275147855029, 5373.946260537423, 5414.0, 5835.299764028344, 5875.9118613220835, 5903.26209223847, 5910.320313163955, 5917.556183286185, 5487.341519017744, 5530.6128571000445, 5658.405867383948, 5678.5741069419955, 5692.402297758758, 5817.592768506164, 5825.126231065289, 5833.4749872511675, 5843.94156058434, 5861.443162899518, 5569.443055694398, 5611.859703507445, 5613.775448982076, 5626.212330273765, 5784.71076446176, 5631.774729010794, 5642.605017648725, 5688.971555142198, 5701.572382071361, 5743.770249189985, 4309.698321117492, 4315.029118448387, 4327.956720432755, 4348.543402942709, 4351.912961740747, 3884.8251828667776, 3898.7075969302414, 3899.2786334527636, 3914.5106861642294, 3917.5103112110987, 5373.838784836461, 5383.892322153535, 5391.245225668409, 5410.3507579090765, 5472.781088756485, 5540.002799496049, 5600.719964001783, 5735.655860648374, 5740.885182296331, 5744.827655170351, 5412.269343638637, 5455.018096742545, 5473.917891231635, 5481.890362192734, 5508.173773933942, 4815.446223684251, 4831.73425461603, 4833.782479788418, 4843.927341089887, 4872.0, 5805.071188609802, 5861.560382971379, 6065.120557519135, 6069.119977603223, 6086.056661217518, 5697.287839020122, 5712.742926568231, 5724.685142317213, 5786.074228123648, 5838.211841401527, 6000.339962604197, 6001.339852616122, 6003.6998150093195, 6007.038873780174, 6013.248343957005, 5540.473655002768, 5651.321841378896, 5710.628809127394, 5716.999525083177, 5719.084946408555, 5700.800471983461, 5832.24180856486, 5852.122181672613, 5888.676122813115, 5898.0, 5638.5771067170945, 5652.3217213934595, 5652.4064973178865, 5653.349864765604, 5671.631343962629, 5540.63985840919, 5544.0575102231915, 5687.345955215038, 5700.800471983461, 5712.942870571241, 5577.27495425592, 5614.270144881302, 5630.380658127521, 5637.154426835843, 5705.486506214461, 5729.9713501432225, 5732.369439361583, 5736.4550367715, 5746.482816546531, 5749.683767392667, 5537.28015358017, 5538.501534861882, 5583.916241256303, 5591.944080559142, 5595.496405323541], "value": 5301.371042427751, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.8807450435409, 591.0366103251699, 595.6396380189985, 598.5451057196535, 603.5051257968465, 31317.68682313176, 39167.41248881265, 41006.38490422646, 41031.384529232084, 45525.54474455254, 42553.36169957453, 42771.0, 42784.78607606966, 42825.57174428263, 42836.35745463821, 8539.530325832076, 8779.824403511926, 8963.148500892437, 8965.0, 8969.461832290084, 8550.187732165434, 10459.84310235347, 10734.946325268364, 10736.087432568242, 11196.104311655088, 10743.0, 10813.621523246688, 10814.513346899408, 10816.78366432671, 10820.242583019191, 10780.676579702622, 10815.459227038617, 10843.240973131884, 10853.83719244208, 10860.728481787939, 10613.787724245472, 10639.787204255948, 10692.914881598092, 10719.62481313158, 10720.624778132806, 39430.40854387187, 39677.60322396768, 39711.20577588432, 39742.20515589672, 39780.801095994626, 3997.8200980955926, 4057.2494088593703, 4116.320807066831, 4171.853985110537, 4220.810063547147, 30136.0, 30559.847200764078, 30601.0, 30788.2302942426, 30996.690033099607, 4045.8381664733374, 4166.604172603597, 4183.288840897055, 4184.330507118877, 4209.957900420987, 235232.94301170993, 237228.0, 238058.0, 239602.0, 239751.80124099442, 55783.721081395146, 55928.0, 56173.15740263899, 56294.0, 56338.718306408206, 57810.750279955006, 58051.4194858046, 58073.41926580764, 58093.0, 58154.41845581574, 15999.68000640004, 16364.83635163657, 16375.263113159936, 16381.836181638031, 16386.180690965404, 10590.205734569938, 10947.56209751601, 12236.082293827998, 12625.494980200872, 12703.809442858364, 14854.405823767145, 15583.844161558465, 15595.766063509056, 15610.219489025503, 15623.609409764847, 5701.914471282934, 5770.480656740913, 5804.303483581997, 5809.128630705343, 5875.6474611523445, 877.3770622857843, 899.7840518275567, 905.9909400906039, 909.7725568607848, 917.8531434970373, 27223.45553088927, 29143.562846557317, 29388.853055734584, 30409.543856842167, 30782.692173077983, 1008.0826447932493, 1018.149844879519, 1019.5157300282307, 1019.8164330420595, 1052.163529993657, 13887.58337249884, 14630.561083167522, 14838.93349192198, 14962.70074598502, 14999.080329490627, 425743.0, 427001.0, 427239.0, 427559.0, 427877.0, 49556.0, 49894.25158622624, 49925.50074499281, 50037.74981125144, 50189.24716129261, 1286.3246795432285, 1333.4932725564224, 1337.7993301004733, 1337.9063465557317, 1354.9661258468618, 25515.872420637777, 26558.468830623668, 26694.0, 27675.584866227426, 27833.443331132858, 21057.0, 21223.363299101053, 21320.14719411206, 21373.14507419748, 21394.251201207884, 366148.0, 368279.0, 369366.0, 369839.0, 370101.0, 22514.324570262917, 23193.536129277658, 23972.76027239775, 24903.62644560369, 25361.57618721449, 37467.6253237475, 39774.0, 39795.60204397919, 39980.400293996194, 40728.0, 33512.0, 33694.66305337013, 33769.66230337665, 34033.48949765805, 34471.655283446846, 32589.674103258665, 33549.49675754817, 33572.328553428306, 33821.83089084539, 34147.65852341445, 34012.48981265332, 34142.0, 34362.484562732076, 34543.13642158966, 35354.0, 29668.406631867674, 32659.673403266614, 32746.508802367505, 32906.341873162884, 32914.0, 24942.62586061247, 25493.617595735708, 25599.872000639876, 25720.228393148234, 25930.870345648153], "value": 18202.576645502988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.2949103141248, 391.1024199462234, 396.97419667721596, 397.75538044102876, 421.7195564949309, 14450.566483005501, 15380.0, 17272.740908886375, 18736.812631873676, 18770.530736731587, 15085.0, 16177.676446471092, 18061.36785212518, 18133.4559963201, 18363.816361836376, 5340.759665815048, 5629.577781666371, 5662.292213473316, 5674.09214525676, 5676.120201368773, 5439.755211015514, 5550.583706222029, 5636.549076073904, 5644.520215781664, 5646.350669672999, 5513.696746678931, 5551.389347171828, 5554.72226388679, 5562.137868630369, 5616.269884914956, 5371.8946535008545, 5444.945550544483, 5551.028570000254, 5552.028395030853, 5568.220449137124, 4864.708117512961, 4878.365812444395, 4881.804727810883, 4888.951110488885, 4891.51084891508, 21021.789782102136, 21380.0, 21393.0, 21502.677459838116, 21590.352289431186, 2543.8092143089343, 2593.247958092151, 2628.829126106816, 2659.0826164973055, 2708.3499960009653, 9467.944727997012, 9945.403275803475, 10005.099541041342, 10179.236557258235, 10194.694159175237, 1915.7222202780658, 1929.199382256362, 1961.1567026178736, 1976.8418526517844, 1985.3349128042123, 73067.0, 73959.26040739578, 75042.62478687626, 75223.99999999945, 76958.0, 10373.325733827278, 10488.790224195473, 10540.31487953296, 10695.83956240657, 10900.509477073392, 14736.115833050051, 14756.631084222765, 14784.704305913821, 14794.926025369805, 14802.925985370004, 6917.9308206917285, 7330.120385553768, 7353.154387245535, 7805.258500442449, 8121.147279535682, 7571.886421703679, 7575.6969721210535, 7644.732434364771, 7791.922080779233, 7801.921980780233, 4299.505556860939, 4352.303631418958, 4369.43197384338, 4409.735415875058, 4440.955590444119, 1919.5489060070872, 2009.939701808948, 2014.113789932425, 2023.1401654296865, 2024.0689282929723, 829.3779665251062, 862.8144948835904, 867.7917299847991, 870.9869351959726, 887.8623813308948, 16711.0, 16803.0, 16945.745813812802, 17185.570360741083, 18006.369777057742, 941.2846236859899, 956.168133723651, 956.3353469338742, 959.7792507723262, 961.3751061809879, 9584.329096963145, 9595.0, 9687.128158465772, 9728.221742260603, 9808.31341806081, 392168.0, 406231.9376806253, 436195.0, 438208.0, 438509.0, 77097.0, 80518.98702532366, 82678.58660706779, 83060.0, 83456.58271708603, 1055.5672174408455, 1057.2335057083706, 1078.5308390850005, 1078.5739632845139, 1087.260662749338, 15186.10173728284, 15447.382104715685, 15690.843091568939, 15878.60303492422, 16060.518184454486, 38632.0, 39221.16397908198, 39739.40390894197, 40603.187936240516, 41616.58383416127, 39308.80345598254, 40812.79593602013, 48549.757251213516, 54195.458045420615, 54512.18231726448, 8167.224113709188, 8298.377621678519, 8396.748097557083, 8501.872471912802, 8673.176048275403, 10400.635977740743, 12383.62849114528, 12474.688132796753, 12785.424655890321, 13097.541586044443, 10276.743081423025, 10836.891631083588, 10839.403832789272, 10948.452577370941, 11013.0, 10512.737181570215, 10628.309159904582, 10668.679939601825, 10673.786524269626, 10830.67507974762, 10812.891871081189, 10889.455527223448, 10950.288231265262, 11014.44927753625, 11053.613123540637, 8221.588920554068, 8223.9588802058, 8369.49783013021, 8422.157784221528, 8440.915590844013, 8699.391042626956, 10357.378557286587, 10457.372557646566, 10545.630902918361, 10958.232923695265], "value": 8665.435621269964, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.74487847309, 1379.6412932637515, 1386.3622733542563, 1394.2958805803069, 1396.6508372906774, 7912.881306780396, 8411.579421028948, 8418.0, 8419.284360829322, 8429.030661473924, 7423.4061275098, 7634.656440460179, 7635.809104772384, 7849.60751962401, 8275.54484503352, 2141.7858214178573, 2307.6884620576175, 2362.303120579433, 2377.2274010946426, 2391.294568102414, 2303.1363238785457, 2355.9764402356013, 2356.0340260493203, 2367.4318163640687, 2370.3363058343693, 2041.1019151573335, 2309.7805708457668, 2362.5511152880986, 2372.359462945004, 2375.334906226251, 2039.8674086184424, 2245.65192395179, 2298.793108620232, 2304.7119110111234, 2332.3935776698013, 2246.7416247131628, 2263.0, 2264.1396269417514, 2267.977320226793, 2268.9092436302526, 8122.3908206884125, 8192.918070819274, 8306.377021723394, 8409.537475438849, 8463.576821158978, 5117.897642047175, 5297.364316282033, 5324.068288049614, 5329.120695085307, 5332.466753324637, 3464.168599536093, 3499.125218695326, 3581.2837432513343, 3592.46113083037, 3604.963950360489, 1417.758980973237, 1423.2670174859954, 1430.3491911180492, 1483.9777403338958, 1502.676924461246, 10713.946430267877, 10851.565937362497, 10876.347419154876, 11224.326540407683, 11403.258788178742, 4475.731456112644, 4536.77316134192, 4629.606483448877, 4636.327732478771, 4651.0, 9040.231580315747, 9048.502332371783, 9052.909470905339, 9061.728148155566, 9062.773430664287, 5202.7918883245, 5227.085260079471, 5227.60792940531, 5229.058769421541, 5279.366476022902, 4333.9133217335475, 4788.04239152171, 4816.54242846929, 4910.90178196434, 4930.852074437773, 3395.6264810870784, 3599.640035996432, 3680.8895733128047, 3691.446283057563, 3711.7216208783907, 1171.3850228629867, 1215.598852378713, 1223.174357308813, 1224.6020043485903, 1226.9325187114794, 880.9735707928772, 896.8341753609768, 900.1853322742928, 900.4777229207044, 915.6402741928231, 5302.443243459458, 5397.73011349431, 5452.836414907559, 5509.752061157257, 5600.467955544217, 929.2194556572376, 932.911373419524, 950.0879156010308, 956.7177682583655, 978.320067553058, 3516.49010893419, 3598.8560457581925, 3637.1452708613456, 3682.4476328550395, 3841.4237864320576, 9833.557489912828, 10202.33684810485, 10210.183185345155, 10232.693019209435, 10237.385756854612, 10132.54403551842, 10142.137918276881, 10149.137323327599, 10158.492075396201, 10171.69484915439, 7270.16393114788, 7570.0, 7578.4316176287, 7772.261635144652, 7789.1042530108625, 5440.428754980829, 5630.831075067755, 5688.971555142198, 5709.172170035405, 5800.332961709458, 9120.407173533698, 9144.3598948073, 9197.586108624993, 9251.121143491358, 9252.722418327461, 9349.57926893305, 9418.434893906388, 9423.151916327564, 9440.669576564787, 9515.381500202464, 7488.326050655467, 7537.510061845963, 7560.470767046254, 7628.42786791004, 7631.847363052597, 7874.566898820391, 8068.798280043045, 8070.757877263692, 8075.798105047421, 8091.029076510857, 7124.145102587515, 7242.818929526804, 7244.637768111679, 7284.3808276294985, 7397.112346518237, 6893.0694356261265, 6988.161420629557, 7036.5074444790425, 7070.505064645426, 7112.03987461686, 6437.742490300523, 6442.645654489048, 6565.113709649137, 6576.506761992965, 6589.209294884645, 4921.360223170965, 5013.498650135103, 5048.873778155576, 5091.694498330112, 5101.183810590288, 4709.387779588632, 4798.4001999750035, 4901.607871370211, 4985.451600323889, 5000.099982003274], "value": 4442.554264582161, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.5890520427528, 381.6107570278314, 383.95584507781604, 385.86687592780487, 389.1244699426293, 5182.818601348954, 5191.974040129803, 5220.6084543659235, 5247.763850626721, 5252.159654455288, 5098.796048158069, 5148.613853960949, 5154.664946778456, 5160.432352441228, 5174.301469301643, 3630.52803135592, 3884.514435695538, 4036.3138266494766, 4113.753174809513, 4141.7100802943805, 4077.5922407759285, 4080.4491393661774, 4107.650849677766, 4108.322126849066, 4115.36211887158, 4086.2236175126777, 4102.651274641644, 4109.630133288004, 4109.958900410988, 4128.298189307825, 3948.2430318508277, 4009.859654912064, 4135.172965406916, 4140.937885931713, 4148.43996060531, 3653.451982202664, 3711.2206436648335, 3711.997760604622, 3724.366857634209, 3744.3634582121103, 5057.064443078006, 5064.316317297192, 5077.771500282496, 5085.0, 5110.207917772751, 4604.194266003436, 4801.687890287155, 4801.831935882262, 4817.229243321087, 4819.879503012417, 5370.2750128732905, 5381.165919282517, 5398.676079435247, 5431.782728690848, 5436.157395603688, 4085.4057673377324, 4089.734167279117, 4094.1811637672586, 4100.261952848486, 4101.651359634435, 5336.199570064483, 5424.240606315079, 5552.307715369275, 5581.376752926119, 5642.548596112301, 4129.607687269765, 4157.106222162189, 4161.521425036099, 4178.310578754472, 4183.288840897055, 4222.4933008038615, 4232.978835105866, 4251.681123915719, 4255.595718406808, 4264.123785534355, 4563.406757121553, 4728.172569800271, 4728.290756386569, 4752.500987396343, 4757.26262429324, 5466.742649190708, 5472.370677372073, 5472.962505809314, 5500.477454641803, 5509.944900550943, 1427.421894132878, 1428.5428662827796, 1449.3767679897744, 1478.7652310320786, 1481.4814814814729, 992.369845148336, 1047.7904419116055, 1052.147760314148, 1055.472263868066, 1062.4475272858072, 5075.289459475676, 5076.18780995039, 5081.822136225288, 5087.186050231945, 5098.388193416814, 5359.785608575691, 5363.704996225245, 5381.838544843661, 5385.892282154335, 5386.380566234933, 4849.75751212445, 5011.469208049912, 5049.722265275372, 5058.266551350107, 5097.745112744348, 2843.189690938114, 2847.4732174547776, 2852.6576810782426, 2858.19970408286, 2878.596996420503, 5584.324702589207, 5652.576056795757, 5658.547316214693, 5679.649007085839, 5705.0, 5372.221027951004, 5421.620486565903, 5491.423400542965, 5495.542737283676, 5513.338399392019, 5614.820887613565, 5615.382307946123, 5618.578606604521, 5618.831435056954, 5621.437856214346, 5317.494837990384, 5325.627206095536, 5340.706261155518, 5358.267283685271, 5373.811916582901, 5542.63972841764, 5547.0, 5549.778008879761, 5558.416366281483, 5571.275734154534, 5551.444855514416, 5610.857919565056, 5698.601097923106, 5758.539316854558, 5786.566007549366, 3951.427043078795, 3953.920921581495, 4002.5597184310286, 4003.7597744135446, 4005.118873847749, 5385.784568617368, 5643.407442218508, 5660.9150862737915, 5661.603687741818, 5681.801136960186, 5448.782048718007, 5469.9179512306555, 5485.424030476902, 5489.670619762827, 5495.917561236504, 5462.333988133658, 5469.507744303032, 5521.806736764194, 5529.806456773994, 5550.139728342114, 5239.921401179062, 5451.046066938349, 5455.727213639223, 5482.5613950884635, 5495.6153069284765, 5388.886669596095, 5421.807202415463, 5473.890522189454, 5487.463510217146, 5487.807926722546, 5319.696674314818, 5369.4093649697725, 5380.273663055437, 5398.433164517669, 5403.378611459732], "value": 4193.8966614325645, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.7592532426962, 746.537146968879, 778.2489897249153, 826.3678286111124, 840.2563731097979, 15950.202489875503, 16772.07753573553, 16852.07313597753, 16926.40757573485, 16938.0, 14937.775933360981, 15123.546293611182, 15129.167895765728, 15135.697286054272, 15142.394304227817, 690.8756423843706, 712.6045045000022, 795.4193438789658, 816.6121092481084, 818.0633175014605, 822.7326119011345, 823.069930977997, 824.5341382119113, 825.0965193113561, 825.162460102996, 872.31959071924, 876.837785009772, 879.7096958003875, 879.885614870066, 881.8280435315116, 726.2773540327381, 745.4222977192665, 853.8591132463198, 878.4685265414428, 880.3045593980779, 637.9362063793584, 638.4573112854083, 639.2169592249504, 639.25526761323, 639.7760783725705, 14853.628659283497, 15676.451324203597, 15681.372745090182, 15694.76457853133, 15714.528564143095, 14044.508442204473, 14243.85756142446, 14447.711045779131, 14627.89433525661, 14652.633684157874, 13139.671508212276, 13233.956920325038, 13329.333533323392, 13343.666408339772, 13640.386182621805, 3731.9067023324365, 3805.714571407128, 4437.334399840018, 4537.659675524349, 4573.771311434415, 74206.0, 76390.0, 76735.23264767337, 76979.0, 78442.60778696126, 11372.601958931398, 11389.259698119597, 11461.942690286494, 11485.827712584318, 11518.827217591743, 19863.205471780966, 19903.502412439517, 19956.3015294464, 20016.59966800685, 20126.39620811378, 7923.484973476706, 8001.359891208689, 8031.839363212703, 8035.879461808077, 8232.423730338818, 4360.367746676714, 4423.469183697976, 4479.350494178391, 4538.410006699108, 4621.2837010263465, 3333.0667413124365, 3413.0102270341818, 3516.226430185355, 3517.753757236969, 3534.1164708822794, 620.9223847019123, 622.8290813271112, 627.645380360095, 633.6894921488438, 639.5714871036324, 716.401804493254, 726.8982342471952, 733.5818583407513, 752.428154602495, 759.848829024029, 9935.950320248352, 10018.198544116452, 10066.949665251626, 10093.848592271122, 10122.392656440637, 638.6359774928246, 725.1515726599786, 736.4292673178329, 752.8306131120454, 765.5865832450526, 4529.886752831206, 4782.426108866958, 4846.0, 4852.8544143675745, 4890.339804126468, 70432.2956770436, 70785.0, 71374.2862571378, 73693.26306736971, 73929.0, 60286.69856650777, 60744.0, 60816.0, 61478.38521614816, 61542.0, 55941.44058559362, 57793.50961658636, 58322.4167758317, 58410.0, 58877.70561147167, 9521.333506654468, 9773.218142548722, 9938.602455901682, 9964.651237206663, 9983.101520863158, 38542.80728596451, 39643.60356396399, 40502.79748601238, 40771.0, 42002.279635258215, 45788.771056144506, 50374.49625503698, 50628.74685626695, 50894.491055088976, 50947.235791462415, 12368.690782730504, 12575.0, 12711.0, 12724.300163491096, 13035.608931732064, 13900.16599004063, 14068.8896665501, 14146.363413646617, 14544.127352358892, 15392.0, 13151.473941042252, 13247.536336228186, 13553.72892542124, 13855.515056972958, 14236.857631423552, 12988.805167922677, 13084.738305233652, 13359.1316564423, 14240.501582444978, 14319.498817541753, 13104.0, 13395.330233488481, 13600.795988060385, 13723.1079979801, 13967.441302347792, 11576.652700419001, 11850.348230847041, 12180.878191217975, 12249.571265005681, 12275.263484190977, 11068.723281918017, 11262.38056906878, 11286.153538484483, 11297.378644174649, 11364.54541818351], "value": 7217.7316613451985, "unit": "score"}], "name": "dromaeo_css", "value": 6947.589701588059, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 110.0, 111.0, 111.0, 112.0, 111.0, 110.0, 112.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 120.0, 167.0, 122.0, 123.0, 126.0, 119.0, 140.0, 120.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 162.0, 162.0, 159.0, 162.0, 162.0, 161.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 114.0, 76.0, 77.0, 76.0, 76.0, 100.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 107.0, 84.0, 83.0, 82.0, 99.0, 83.0, 83.0, 107.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 148.0, 148.0, 148.0, 148.0, 146.0, 146.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 51.0, 53.0, 51.0, 52.0, 51.0, 54.0, 52.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 67.0, 71.0, 68.0, 68.0, 68.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 85.0, 82.0, 81.0, 81.0, 81.0, 81.0, 81.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 125.0, 133.0, 127.0, 126.0, 123.0, 125.0, 132.0, 175.0, 134.0], "value": 132.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 166.0, 166.0, 168.0, 167.0, 166.0, 166.0, 169.0, 164.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 62.0, 63.0, 62.0, 61.0, 64.0, 62.0, 61.0, 61.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1497.5000000000005, "alertThreshold": 2.0}]} 05:35:32 INFO - Return code: 0 05:35:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:35:32 INFO - # TBPL SUCCESS # 05:35:32 INFO - Running post-action listener: _resource_record_post_action 05:35:32 INFO - [mozharness: 2016-12-30 13:35:32.336812Z] Finished run-tests step (success) 05:35:32 INFO - Running post-run listener: _resource_record_post_run 05:35:32 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401981440; Read time: 16; Write time: 262904 05:35:32 INFO - TinderboxPrint: CPU usage
13.0% 05:35:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:35:32 INFO - TinderboxPrint: I/O write bytes / time
401,981,440 / 262,904 05:35:32 INFO - TinderboxPrint: CPU idle
8,286.0 (86.9%) 05:35:32 INFO - TinderboxPrint: CPU user
1,199.2 (12.6%) 05:35:32 INFO - TinderboxPrint: Swap in / out
0 / 0 05:35:32 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 104742912; Read time: 0; Write time: 61376 05:35:32 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 296345600; Read time: 0; Write time: 201500 05:35:32 INFO - Running post-run listener: _upload_blobber_files 05:35:32 INFO - Blob upload gear active. 05:35:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:35:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:35:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:35:33 INFO - (blobuploader) - INFO - Open directory for files ... 05:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:35:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:36 INFO - (blobuploader) - INFO - Done attempting. 05:35:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:35:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:35:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:37 INFO - (blobuploader) - INFO - Done attempting. 05:35:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:35:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:35:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:38 INFO - (blobuploader) - INFO - Done attempting. 05:35:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:35:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:35:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:39 INFO - (blobuploader) - INFO - Done attempting. 05:35:39 INFO - (blobuploader) - INFO - Iteration through files over. 05:35:39 INFO - Return code: 0 05:35:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:35:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:35:39 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33cc4851d5d109e4520283e682c99781343ebe8d89b78da21c8577bffdf30b8e9a7ef55e5621c8113ce2790f2406cd0cb1a82827b7cd6e83347571fc359b74f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107d63bcd25126c67a9f63b81e6ae64c156dcdf2b8c6602837939f20acd580b6a244d027b98759bb26543611491632e72f3f17fc01acaa5736d750115bdbdcc9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b0ac53b07cc0289f4f342565cdedc50b68a14354347b59d6d705e36ffb6d1ba1885a712bb9e94597ecb28261994dd9e1ab94fddcc964e1f3b83310b30b5ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b3b62774a710476f689d296f45a54a20c2e82a73f266b577cd684d9027d8ab93d459b8f7554538c80bbcf3e56d3a9bebc5cf2164b7f5149d40e6e7f182bed2"} 05:35:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:35:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:35:39 INFO - Contents: 05:35:39 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33cc4851d5d109e4520283e682c99781343ebe8d89b78da21c8577bffdf30b8e9a7ef55e5621c8113ce2790f2406cd0cb1a82827b7cd6e83347571fc359b74f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107d63bcd25126c67a9f63b81e6ae64c156dcdf2b8c6602837939f20acd580b6a244d027b98759bb26543611491632e72f3f17fc01acaa5736d750115bdbdcc9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b0ac53b07cc0289f4f342565cdedc50b68a14354347b59d6d705e36ffb6d1ba1885a712bb9e94597ecb28261994dd9e1ab94fddcc964e1f3b83310b30b5ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b3b62774a710476f689d296f45a54a20c2e82a73f266b577cd684d9027d8ab93d459b8f7554538c80bbcf3e56d3a9bebc5cf2164b7f5149d40e6e7f182bed2"} 05:35:39 INFO - Running post-run listener: copy_logs_to_upload_dir 05:35:39 INFO - Copying logs to upload dir... 05:35:39 INFO - mkdir: /builds/slave/test/build/upload/logs 05:35:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.972412 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-30 05:35:39.199841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 05:35:39.203544) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33cc4851d5d109e4520283e682c99781343ebe8d89b78da21c8577bffdf30b8e9a7ef55e5621c8113ce2790f2406cd0cb1a82827b7cd6e83347571fc359b74f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107d63bcd25126c67a9f63b81e6ae64c156dcdf2b8c6602837939f20acd580b6a244d027b98759bb26543611491632e72f3f17fc01acaa5736d750115bdbdcc9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b0ac53b07cc0289f4f342565cdedc50b68a14354347b59d6d705e36ffb6d1ba1885a712bb9e94597ecb28261994dd9e1ab94fddcc964e1f3b83310b30b5ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b3b62774a710476f689d296f45a54a20c2e82a73f266b577cd684d9027d8ab93d459b8f7554538c80bbcf3e56d3a9bebc5cf2164b7f5149d40e6e7f182bed2"} build_url:https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011670 build_url: 'https://queue.taskcluster.net/v1/task/DL82FKw_RpSbD-M5pGYOKA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33cc4851d5d109e4520283e682c99781343ebe8d89b78da21c8577bffdf30b8e9a7ef55e5621c8113ce2790f2406cd0cb1a82827b7cd6e83347571fc359b74f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107d63bcd25126c67a9f63b81e6ae64c156dcdf2b8c6602837939f20acd580b6a244d027b98759bb26543611491632e72f3f17fc01acaa5736d750115bdbdcc9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80b0ac53b07cc0289f4f342565cdedc50b68a14354347b59d6d705e36ffb6d1ba1885a712bb9e94597ecb28261994dd9e1ab94fddcc964e1f3b83310b30b5ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86b3b62774a710476f689d296f45a54a20c2e82a73f266b577cd684d9027d8ab93d459b8f7554538c80bbcf3e56d3a9bebc5cf2164b7f5149d40e6e7f182bed2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 05:35:39.228805) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 05:35:39.229149) ========= 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=20e666a3d4cfbb71505a330e000001b4-1483103698.192165-1489080272 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 05:35:39.313893) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 05:35:39.314269) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 05:35:39.314577) ========= ========= Total master_lag: 0.17 =========